2017-10-24 09:14:35,114 [salt.cli.daemons ][WARNING ][2547] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-24 09:15:03,041 [salt.state       ][ERROR   ][3833] No contents found in top file
2017-10-24 09:16:39,482 [salt.utils.parsers][WARNING ][2547] Minion received a SIGTERM. Exiting.
2017-10-24 09:18:53,045 [salt.cli.daemons ][WARNING ][3270] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-24 09:21:52,667 [salt.loaded.int.module.cmdmod][ERROR   ][10195] Command 'virsh net-destroy default 2>&1 1>/dev/null; virsh net-undefine default' failed with return code: 1
2017-10-24 09:21:52,668 [salt.loaded.int.module.cmdmod][ERROR   ][10195] stderr: error: failed to get network 'default'
error: Network not found: no network with matching name 'default'
2017-10-24 09:21:52,668 [salt.loaded.int.module.cmdmod][ERROR   ][10195] retcode: 1
2017-10-24 09:21:52,668 [salt.state       ][ERROR   ][10195] {'pid': 15043, 'retcode': 1, 'stderr': "error: failed to get network 'default'\nerror: Network not found: no network with matching name 'default'", 'stdout': ''}
2017-10-24 09:22:08,539 [py.warnings      ][WARNING ][15120] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2017-10-24 09:32:47,167 [salt.loaded.int.module.cmdmod][ERROR   ][15120] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-24 09:32:47,167 [salt.loaded.int.module.cmdmod][ERROR   ][15120] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-24 09:32:54,320 [salt.loaded.int.module.cmdmod][ERROR   ][15120] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-24 09:32:54,320 [salt.loaded.int.module.cmdmod][ERROR   ][15120] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-24 09:33:02,543 [salt.loaded.int.module.cmdmod][ERROR   ][15120] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-24 09:33:02,543 [salt.loaded.int.module.cmdmod][ERROR   ][15120] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-24 09:33:12,403 [salt.loaded.int.module.cmdmod][ERROR   ][15120] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-24 09:33:12,403 [salt.loaded.int.module.cmdmod][ERROR   ][15120] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-24 09:33:21,789 [salt.loaded.int.module.cmdmod][ERROR   ][15120] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-24 09:33:21,789 [salt.loaded.int.module.cmdmod][ERROR   ][15120] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-24 09:33:28,316 [salt.loaded.int.module.cmdmod][ERROR   ][15120] Command 'while true; do salt-call saltutil.running|grep fun: && continue; salt-call --local service.restart salt-minion; break; done' failed with return code: None
2017-10-24 09:33:31,558 [salt.state       ][ERROR   ][22952] No contents found in top file
2017-10-24 09:33:40,554 [salt.loaded.int.module.cmdmod][INFO    ][23603] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-10-24 09:33:40,561 [salt.loaded.int.module.cmdmod][INFO    ][23603] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-10-24 09:33:40,571 [salt.loaded.int.module.cmdmod][INFO    ][23603] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-10-24 09:33:40,604 [salt.utils.parsers][WARNING ][3270] Minion received a SIGTERM. Exiting.
2017-10-24 09:33:40,864 [salt.cli.daemons ][INFO    ][23720] Setting up the Salt Minion "kvm01.baremetal-mcp-ocata-ovs-ha.local"
2017-10-24 09:33:41,378 [salt.minion      ][INFO    ][23720] Creating minion process manager
2017-10-24 09:33:41,378 [salt.cli.daemons ][WARNING ][23720] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-24 09:33:41,379 [salt.cli.daemons ][INFO    ][23720] The Salt Minion is starting up
2017-10-24 09:33:41,379 [salt.minion      ][INFO    ][23720] Minion is starting as user 'root'
2017-10-24 09:33:41,379 [salt.utils.event ][INFO    ][23720] Starting pull socket on /var/run/salt/minion/minion_event_fd678c5357_pull.ipc
2017-10-24 09:33:42,327 [salt.loaded.int.module.cmdmod][INFO    ][23720] Executing command ['date', '+%z'] in directory '/root'
2017-10-24 09:33:42,338 [salt.utils.schedule][INFO    ][23720] Updating job settings for scheduled job: __mine_interval
2017-10-24 09:33:42,340 [salt.minion      ][INFO    ][23720] Added mine.update to scheduler
2017-10-24 09:33:42,593 [salt.minion      ][INFO    ][23720] Minion is ready to receive requests!
2017-10-24 09:33:43,594 [salt.utils.schedule][INFO    ][23720] Running scheduled job: __mine_interval
2017-10-24 09:33:49,535 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command saltutil.find_job with jid 20171024093349524057
2017-10-24 09:33:49,602 [salt.minion      ][INFO    ][23850] Starting a new job with PID 23850
2017-10-24 09:33:49,610 [salt.minion      ][INFO    ][23850] Returning information for job: 20171024093349524057
2017-10-24 09:34:10,720 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command state.sls with jid 20171024093410709746
2017-10-24 09:34:10,734 [salt.minion      ][INFO    ][23855] Starting a new job with PID 23855
2017-10-24 09:34:13,203 [salt.state       ][INFO    ][23855] Loading fresh modules for state activity
2017-10-24 09:34:13,234 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-10-24 09:34:13,252 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-10-24 09:34:13,290 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-24 09:34:13,383 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-10-24 09:34:13,423 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-10-24 09:34:13,448 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-24 09:34:13,545 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-10-24 09:34:13,591 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-24 09:34:13,633 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-10-24 09:34:13,651 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-10-24 09:34:13,663 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-24 09:34:13,678 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-10-24 09:34:13,685 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-10-24 09:34:13,711 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-10-24 09:34:13,725 [py.warnings      ][WARNING ][23855] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2017-10-24 09:34:13,761 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command 'salt-minion --version' in directory '/root'
2017-10-24 09:34:14,016 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-10-24 09:34:14,034 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-10-24 09:34:14,097 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-10-24 09:34:14,109 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-10-24 09:34:14,160 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-10-24 09:34:14,208 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-10-24 09:34:14,262 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-10-24 09:34:14,282 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-24 09:34:14,367 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-10-24 09:34:14,421 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-24 09:34:14,463 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-10-24 09:34:14,480 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-10-24 09:34:14,491 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-24 09:34:14,523 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-10-24 09:34:14,529 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-10-24 09:34:14,550 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-10-24 09:34:14,564 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command 'salt-minion --version' in directory '/root'
2017-10-24 09:34:14,836 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-10-24 09:34:14,855 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-10-24 09:34:14,934 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-10-24 09:34:14,946 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-10-24 09:34:14,990 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-10-24 09:34:15,030 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-10-24 09:34:15,115 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-10-24 09:34:15,171 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-24 09:34:15,527 [salt.state       ][INFO    ][23855] Running state [salt-minion] at time 09:34:15.526779
2017-10-24 09:34:15,527 [salt.state       ][INFO    ][23855] Executing state pkg.installed for salt-minion
2017-10-24 09:34:15,528 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-24 09:34:15,854 [salt.state       ][INFO    ][23855] Package salt-minion is already installed
2017-10-24 09:34:15,855 [salt.state       ][INFO    ][23855] Completed state [salt-minion] at time 09:34:15.854707 duration_in_ms=327.928
2017-10-24 09:34:15,855 [salt.state       ][INFO    ][23855] Running state [salt_minion_dependency_packages] at time 09:34:15.854939
2017-10-24 09:34:15,855 [salt.state       ][INFO    ][23855] Executing state pkg.installed for salt_minion_dependency_packages
2017-10-24 09:34:15,858 [salt.state       ][INFO    ][23855] All specified packages are already installed
2017-10-24 09:34:15,858 [salt.state       ][INFO    ][23855] Completed state [salt_minion_dependency_packages] at time 09:34:15.857986 duration_in_ms=3.047
2017-10-24 09:34:15,860 [salt.state       ][INFO    ][23855] Running state [/etc/salt/minion.d/minion.conf] at time 09:34:15.859688
2017-10-24 09:34:15,860 [salt.state       ][INFO    ][23855] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-10-24 09:34:15,903 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-10-24 09:34:15,953 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-24 09:34:16,009 [salt.fileclient  ][INFO    ][23855] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-24 09:34:16,163 [salt.state       ][INFO    ][23855] File /etc/salt/minion.d/minion.conf is in the correct state
2017-10-24 09:34:16,163 [salt.state       ][INFO    ][23855] Completed state [/etc/salt/minion.d/minion.conf] at time 09:34:16.162715 duration_in_ms=303.027
2017-10-24 09:34:16,163 [salt.state       ][INFO    ][23855] Running state [/etc/salt/minion.d/_virt.conf] at time 09:34:16.163059
2017-10-24 09:34:16,163 [salt.state       ][INFO    ][23855] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-10-24 09:34:16,303 [salt.state       ][INFO    ][23855] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-10-24 09:34:16,304 [salt.state       ][INFO    ][23855] Completed state [/etc/salt/minion.d/_virt.conf] at time 09:34:16.303601 duration_in_ms=140.54
2017-10-24 09:34:16,306 [salt.state       ][INFO    ][23855] Running state [salt-minion] at time 09:34:16.305538
2017-10-24 09:34:16,306 [salt.state       ][INFO    ][23855] Executing state service.running for salt-minion
2017-10-24 09:34:16,306 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-10-24 09:34:16,323 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-10-24 09:34:16,330 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-10-24 09:34:16,336 [salt.state       ][INFO    ][23855] The service salt-minion is already running
2017-10-24 09:34:16,337 [salt.state       ][INFO    ][23855] Completed state [salt-minion] at time 09:34:16.336643 duration_in_ms=31.104
2017-10-24 09:34:16,338 [salt.state       ][INFO    ][23855] Running state [/etc/salt/grains.d] at time 09:34:16.338071
2017-10-24 09:34:16,338 [salt.state       ][INFO    ][23855] Executing state file.directory for /etc/salt/grains.d
2017-10-24 09:34:16,339 [salt.state       ][INFO    ][23855] Directory /etc/salt/grains.d is in the correct state
2017-10-24 09:34:16,339 [salt.state       ][INFO    ][23855] Completed state [/etc/salt/grains.d] at time 09:34:16.339075 duration_in_ms=1.004
2017-10-24 09:34:16,340 [salt.state       ][INFO    ][23855] Running state [/etc/salt/grains] at time 09:34:16.339526
2017-10-24 09:34:16,340 [salt.state       ][INFO    ][23855] Executing state file.managed for /etc/salt/grains
2017-10-24 09:34:16,340 [salt.state       ][INFO    ][23855] File /etc/salt/grains exists with proper permissions. No changes made.
2017-10-24 09:34:16,340 [salt.state       ][INFO    ][23855] Completed state [/etc/salt/grains] at time 09:34:16.340257 duration_in_ms=0.73
2017-10-24 09:34:16,341 [salt.state       ][INFO    ][23855] Running state [/etc/salt/grains.d/placeholder] at time 09:34:16.340555
2017-10-24 09:34:16,402 [salt.state       ][INFO    ][23855] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-10-24 09:34:16,402 [salt.state       ][INFO    ][23855] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-10-24 09:34:16,402 [salt.state       ][INFO    ][23855] Completed state [/etc/salt/grains.d/placeholder] at time 09:34:16.402309 duration_in_ms=61.754
2017-10-24 09:34:16,403 [salt.state       ][INFO    ][23855] Running state [/etc/salt/grains.d/sphinx] at time 09:34:16.402630
2017-10-24 09:34:16,403 [salt.state       ][INFO    ][23855] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-10-24 09:34:16,428 [salt.state       ][INFO    ][23855] File changed:
--- 
+++ 
@@ -49,7 +49,6 @@
               - 10.167.4.141
               - 127.0.0.1
               - 192.168.11.251
-              - 192.168.122.1
         system:
           name: System
           param:

2017-10-24 09:34:16,428 [salt.state       ][INFO    ][23855] Completed state [/etc/salt/grains.d/sphinx] at time 09:34:16.428275 duration_in_ms=25.645
2017-10-24 09:34:16,429 [salt.state       ][INFO    ][23855] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:34:16.428878
2017-10-24 09:34:16,429 [salt.state       ][INFO    ][23855] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-10-24 09:34:16,429 [salt.state       ][INFO    ][23855] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-10-24 09:34:16,430 [salt.state       ][INFO    ][23855] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:34:16.429465 duration_in_ms=0.587
2017-10-24 09:34:16,430 [salt.state       ][INFO    ][23855] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:34:16.429653
2017-10-24 09:34:16,430 [salt.state       ][INFO    ][23855] Executing state cmd.mod_watch for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-10-24 09:34:16,430 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command 'python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"' in directory '/root'
2017-10-24 09:34:16,524 [salt.state       ][INFO    ][23855] {'pid': 23904, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-10-24 09:34:16,524 [salt.state       ][INFO    ][23855] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:34:16.524388 duration_in_ms=94.735
2017-10-24 09:34:16,525 [salt.state       ][INFO    ][23855] Running state [/etc/salt/grains.d/dns_records] at time 09:34:16.524819
2017-10-24 09:34:16,525 [salt.state       ][INFO    ][23855] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-10-24 09:34:16,660 [salt.state       ][INFO    ][23855] File /etc/salt/grains.d/dns_records is in the correct state
2017-10-24 09:34:16,660 [salt.state       ][INFO    ][23855] Completed state [/etc/salt/grains.d/dns_records] at time 09:34:16.660185 duration_in_ms=135.366
2017-10-24 09:34:16,661 [salt.state       ][INFO    ][23855] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:34:16.660985
2017-10-24 09:34:16,661 [salt.state       ][INFO    ][23855] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-10-24 09:34:16,662 [salt.state       ][INFO    ][23855] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-10-24 09:34:16,662 [salt.state       ][INFO    ][23855] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:34:16.661692 duration_in_ms=0.706
2017-10-24 09:34:16,662 [salt.state       ][INFO    ][23855] Running state [/etc/salt/grains.d/salt] at time 09:34:16.662020
2017-10-24 09:34:16,662 [salt.state       ][INFO    ][23855] Executing state file.managed for /etc/salt/grains.d/salt
2017-10-24 09:34:16,720 [salt.state       ][INFO    ][23855] File /etc/salt/grains.d/salt is in the correct state
2017-10-24 09:34:16,720 [salt.state       ][INFO    ][23855] Completed state [/etc/salt/grains.d/salt] at time 09:34:16.720128 duration_in_ms=58.108
2017-10-24 09:34:16,721 [salt.state       ][INFO    ][23855] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:34:16.720701
2017-10-24 09:34:16,721 [salt.state       ][INFO    ][23855] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-10-24 09:34:16,721 [salt.state       ][INFO    ][23855] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-10-24 09:34:16,721 [salt.state       ][INFO    ][23855] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:34:16.721318 duration_in_ms=0.617
2017-10-24 09:34:16,723 [salt.state       ][INFO    ][23855] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:34:16.722655
2017-10-24 09:34:16,723 [salt.state       ][INFO    ][23855] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-24 09:34:16,723 [salt.state       ][INFO    ][23855] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-24 09:34:16,723 [salt.state       ][INFO    ][23855] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:34:16.723280 duration_in_ms=0.625
2017-10-24 09:34:16,723 [salt.state       ][INFO    ][23855] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:34:16.723441
2017-10-24 09:34:16,724 [salt.state       ][INFO    ][23855] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-24 09:34:16,725 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-10-24 09:34:16,735 [salt.state       ][INFO    ][23855] {'pid': 23906, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-10-24 09:34:16,736 [salt.state       ][INFO    ][23855] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:34:16.735618 duration_in_ms=12.177
2017-10-24 09:34:16,736 [salt.state       ][INFO    ][23855] Running state [mine.update] at time 09:34:16.736271
2017-10-24 09:34:16,737 [salt.state       ][INFO    ][23855] Executing state module.wait for mine.update
2017-10-24 09:34:16,737 [salt.state       ][INFO    ][23855] No changes made for mine.update
2017-10-24 09:34:16,737 [salt.state       ][INFO    ][23855] Completed state [mine.update] at time 09:34:16.736900 duration_in_ms=0.629
2017-10-24 09:34:16,737 [salt.state       ][INFO    ][23855] Running state [mine.update] at time 09:34:16.737065
2017-10-24 09:34:16,737 [salt.state       ][INFO    ][23855] Executing state module.mod_watch for mine.update
2017-10-24 09:34:17,256 [salt.state       ][INFO    ][23855] {'ret': True}
2017-10-24 09:34:17,257 [salt.state       ][INFO    ][23855] Completed state [mine.update] at time 09:34:17.256766 duration_in_ms=519.699
2017-10-24 09:34:17,257 [salt.state       ][INFO    ][23855] Running state [ca-certificates] at time 09:34:17.257041
2017-10-24 09:34:17,257 [salt.state       ][INFO    ][23855] Executing state pkg.installed for ca-certificates
2017-10-24 09:34:17,261 [salt.state       ][INFO    ][23855] Package ca-certificates is already installed
2017-10-24 09:34:17,261 [salt.state       ][INFO    ][23855] Completed state [ca-certificates] at time 09:34:17.260809 duration_in_ms=3.767
2017-10-24 09:34:17,261 [salt.state       ][INFO    ][23855] Running state [update-ca-certificates] at time 09:34:17.261432
2017-10-24 09:34:17,262 [salt.state       ][INFO    ][23855] Executing state cmd.wait for update-ca-certificates
2017-10-24 09:34:17,262 [salt.state       ][INFO    ][23855] No changes made for update-ca-certificates
2017-10-24 09:34:17,262 [salt.state       ][INFO    ][23855] Completed state [update-ca-certificates] at time 09:34:17.262072 duration_in_ms=0.64
2017-10-24 09:34:17,262 [salt.state       ][INFO    ][23855] Running state [libvirt-dev] at time 09:34:17.262263
2017-10-24 09:34:17,262 [salt.state       ][INFO    ][23855] Executing state pkg.installed for libvirt-dev
2017-10-24 09:34:17,265 [salt.state       ][INFO    ][23855] Package libvirt-dev is already installed
2017-10-24 09:34:17,265 [salt.state       ][INFO    ][23855] Completed state [libvirt-dev] at time 09:34:17.265376 duration_in_ms=3.112
2017-10-24 09:34:17,266 [salt.state       ][INFO    ][23855] Running state [pkg-config] at time 09:34:17.265576
2017-10-24 09:34:17,266 [salt.state       ][INFO    ][23855] Executing state pkg.installed for pkg-config
2017-10-24 09:34:17,269 [salt.state       ][INFO    ][23855] Package pkg-config is already installed
2017-10-24 09:34:17,269 [salt.state       ][INFO    ][23855] Completed state [pkg-config] at time 09:34:17.268664 duration_in_ms=3.087
2017-10-24 09:34:17,269 [salt.state       ][INFO    ][23855] Running state [virtng.init] at time 09:34:17.268849
2017-10-24 09:34:17,269 [salt.state       ][INFO    ][23855] Executing state module.run for virtng.init
2017-10-24 09:34:17,326 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command ['git', '--version'] in directory '/root'
2017-10-24 09:34:17,370 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command 'virsh list --all --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-24 09:34:17,384 [salt.state       ][INFO    ][23855] unless execution succeeded
2017-10-24 09:34:17,385 [salt.state       ][INFO    ][23855] Completed state [virtng.init] at time 09:34:17.384647 duration_in_ms=115.797
2017-10-24 09:34:17,385 [salt.state       ][INFO    ][23855] Running state [virt.set_autostart] at time 09:34:17.384869
2017-10-24 09:34:17,385 [salt.state       ][INFO    ][23855] Executing state module.run for virt.set_autostart
2017-10-24 09:34:17,387 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command 'virsh list --autostart --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-24 09:34:17,401 [salt.state       ][INFO    ][23855] unless execution succeeded
2017-10-24 09:34:17,401 [salt.state       ][INFO    ][23855] Completed state [virt.set_autostart] at time 09:34:17.400868 duration_in_ms=15.999
2017-10-24 09:34:17,401 [salt.state       ][INFO    ][23855] Running state [virtng.init] at time 09:34:17.401076
2017-10-24 09:34:17,401 [salt.state       ][INFO    ][23855] Executing state module.run for virtng.init
2017-10-24 09:34:17,403 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command 'virsh list --all --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-24 09:34:17,417 [salt.state       ][INFO    ][23855] unless execution succeeded
2017-10-24 09:34:17,417 [salt.state       ][INFO    ][23855] Completed state [virtng.init] at time 09:34:17.416839 duration_in_ms=15.763
2017-10-24 09:34:17,417 [salt.state       ][INFO    ][23855] Running state [virt.set_autostart] at time 09:34:17.417054
2017-10-24 09:34:17,417 [salt.state       ][INFO    ][23855] Executing state module.run for virt.set_autostart
2017-10-24 09:34:17,419 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command 'virsh list --autostart --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-24 09:34:17,432 [salt.state       ][INFO    ][23855] unless execution succeeded
2017-10-24 09:34:17,433 [salt.state       ][INFO    ][23855] Completed state [virt.set_autostart] at time 09:34:17.432490 duration_in_ms=15.436
2017-10-24 09:34:17,433 [salt.state       ][INFO    ][23855] Running state [virtng.init] at time 09:34:17.432697
2017-10-24 09:34:17,433 [salt.state       ][INFO    ][23855] Executing state module.run for virtng.init
2017-10-24 09:34:17,435 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command 'virsh list --all --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-24 09:34:17,448 [salt.state       ][INFO    ][23855] unless execution succeeded
2017-10-24 09:34:17,448 [salt.state       ][INFO    ][23855] Completed state [virtng.init] at time 09:34:17.447710 duration_in_ms=15.013
2017-10-24 09:34:17,448 [salt.state       ][INFO    ][23855] Running state [virt.set_autostart] at time 09:34:17.447908
2017-10-24 09:34:17,448 [salt.state       ][INFO    ][23855] Executing state module.run for virt.set_autostart
2017-10-24 09:34:17,450 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command 'virsh list --autostart --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-24 09:34:17,465 [salt.state       ][INFO    ][23855] unless execution succeeded
2017-10-24 09:34:17,465 [salt.state       ][INFO    ][23855] Completed state [virt.set_autostart] at time 09:34:17.465029 duration_in_ms=17.121
2017-10-24 09:34:17,465 [salt.state       ][INFO    ][23855] Running state [virtng.init] at time 09:34:17.465228
2017-10-24 09:34:17,465 [salt.state       ][INFO    ][23855] Executing state module.run for virtng.init
2017-10-24 09:34:17,467 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command 'virsh list --all --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-24 09:34:17,480 [salt.state       ][INFO    ][23855] unless execution succeeded
2017-10-24 09:34:17,480 [salt.state       ][INFO    ][23855] Completed state [virtng.init] at time 09:34:17.480350 duration_in_ms=15.122
2017-10-24 09:34:17,481 [salt.state       ][INFO    ][23855] Running state [virt.set_autostart] at time 09:34:17.480546
2017-10-24 09:34:17,481 [salt.state       ][INFO    ][23855] Executing state module.run for virt.set_autostart
2017-10-24 09:34:17,483 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command 'virsh list --autostart --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-24 09:34:17,496 [salt.state       ][INFO    ][23855] unless execution succeeded
2017-10-24 09:34:17,496 [salt.state       ][INFO    ][23855] Completed state [virt.set_autostart] at time 09:34:17.495698 duration_in_ms=15.152
2017-10-24 09:34:17,496 [salt.state       ][INFO    ][23855] Running state [virtng.init] at time 09:34:17.495895
2017-10-24 09:34:17,496 [salt.state       ][INFO    ][23855] Executing state module.run for virtng.init
2017-10-24 09:34:17,498 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command 'virsh list --all --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-24 09:34:17,511 [salt.state       ][INFO    ][23855] unless execution succeeded
2017-10-24 09:34:17,511 [salt.state       ][INFO    ][23855] Completed state [virtng.init] at time 09:34:17.510934 duration_in_ms=15.038
2017-10-24 09:34:17,511 [salt.state       ][INFO    ][23855] Running state [virt.set_autostart] at time 09:34:17.511133
2017-10-24 09:34:17,511 [salt.state       ][INFO    ][23855] Executing state module.run for virt.set_autostart
2017-10-24 09:34:17,513 [salt.loaded.int.module.cmdmod][INFO    ][23855] Executing command 'virsh list --autostart --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-24 09:34:17,526 [salt.state       ][INFO    ][23855] unless execution succeeded
2017-10-24 09:34:17,544 [salt.state       ][INFO    ][23855] Completed state [virt.set_autostart] at time 09:34:17.543893 duration_in_ms=32.759
2017-10-24 09:34:17,546 [salt.minion      ][INFO    ][23855] Returning information for job: 20171024093410709746
2017-10-24 09:34:18,538 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command pillar.get with jid 20171024093418531160
2017-10-24 09:34:18,551 [salt.minion      ][INFO    ][23959] Starting a new job with PID 23959
2017-10-24 09:34:18,554 [salt.minion      ][INFO    ][23959] Returning information for job: 20171024093418531160
2017-10-24 09:35:06,910 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171024093506904473
2017-10-24 09:35:06,926 [salt.minion      ][INFO    ][23968] Starting a new job with PID 23968
2017-10-24 09:35:10,165 [salt.state       ][INFO    ][23968] Loading fresh modules for state activity
2017-10-24 09:35:10,235 [salt.state       ][ERROR   ][23968] No contents found in top file
2017-10-24 09:35:10,236 [salt.utils.extmods][INFO    ][23968] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-10-24 09:35:10,239 [salt.utils.extmods][INFO    ][23968] Syncing beacons for environment 'base'
2017-10-24 09:35:10,239 [salt.utils.extmods][INFO    ][23968] Loading cache from salt://_beacons, for base)
2017-10-24 09:35:10,239 [salt.fileclient  ][INFO    ][23968] Caching directory '_beacons/' for environment 'base'
2017-10-24 09:35:10,270 [salt.utils.extmods][INFO    ][23968] Syncing modules for environment 'base'
2017-10-24 09:35:10,270 [salt.utils.extmods][INFO    ][23968] Loading cache from salt://_modules, for base)
2017-10-24 09:35:10,270 [salt.fileclient  ][INFO    ][23968] Caching directory '_modules/' for environment 'base'
2017-10-24 09:35:10,333 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-10-24 09:35:10,340 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-10-24 09:35:10,348 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-10-24 09:35:10,359 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-10-24 09:35:10,474 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-10-24 09:35:10,484 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-10-24 09:35:10,507 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-10-24 09:35:10,524 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-10-24 09:35:10,532 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-10-24 09:35:10,540 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-10-24 09:35:10,693 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-10-24 09:35:10,725 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-10-24 09:35:10,742 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-10-24 09:35:10,761 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-10-24 09:35:10,796 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-10-24 09:35:10,817 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-10-24 09:35:10,826 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-10-24 09:35:10,861 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-10-24 09:35:10,887 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-10-24 09:35:10,919 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-10-24 09:35:10,956 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-10-24 09:35:10,968 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-10-24 09:35:11,024 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-10-24 09:35:11,037 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-10-24 09:35:11,047 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-10-24 09:35:11,064 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-10-24 09:35:11,084 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-10-24 09:35:11,098 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-10-24 09:35:11,120 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-10-24 09:35:11,130 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-10-24 09:35:11,140 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-10-24 09:35:11,158 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-10-24 09:35:11,161 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-10-24 09:35:11,161 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-10-24 09:35:11,161 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-10-24 09:35:11,161 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-10-24 09:35:11,162 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-10-24 09:35:11,162 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-10-24 09:35:11,162 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-10-24 09:35:11,163 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-10-24 09:35:11,163 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-10-24 09:35:11,163 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-10-24 09:35:11,163 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-10-24 09:35:11,164 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-10-24 09:35:11,164 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-10-24 09:35:11,164 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-10-24 09:35:11,164 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-10-24 09:35:11,165 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-10-24 09:35:11,165 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-10-24 09:35:11,165 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-10-24 09:35:11,165 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-10-24 09:35:11,166 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-10-24 09:35:11,166 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-10-24 09:35:11,166 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-10-24 09:35:11,167 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-10-24 09:35:11,167 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-10-24 09:35:11,167 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-10-24 09:35:11,167 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-10-24 09:35:11,168 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-10-24 09:35:11,168 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-10-24 09:35:11,168 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-10-24 09:35:11,168 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-10-24 09:35:11,169 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-10-24 09:35:11,169 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/testing/django_client_proxy.py' to '/var/cache/salt/minion/extmods/modules/testing/django_client_proxy.py'
2017-10-24 09:35:11,169 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-10-24 09:35:11,169 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-10-24 09:35:11,173 [salt.utils.extmods][INFO    ][23968] Syncing states for environment 'base'
2017-10-24 09:35:11,173 [salt.utils.extmods][INFO    ][23968] Loading cache from salt://_states, for base)
2017-10-24 09:35:11,174 [salt.fileclient  ][INFO    ][23968] Caching directory '_states/' for environment 'base'
2017-10-24 09:35:11,213 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-10-24 09:35:11,240 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-10-24 09:35:11,248 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-10-24 09:35:11,277 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-10-24 09:35:11,293 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-10-24 09:35:11,311 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-10-24 09:35:11,324 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-10-24 09:35:11,368 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-10-24 09:35:11,380 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-10-24 09:35:11,425 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-10-24 09:35:11,445 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-10-24 09:35:11,480 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-10-24 09:35:11,548 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-10-24 09:35:11,564 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-10-24 09:35:11,574 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-10-24 09:35:11,617 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-10-24 09:35:11,627 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-10-24 09:35:11,648 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-10-24 09:35:11,659 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-10-24 09:35:11,671 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-10-24 09:35:11,705 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-10-24 09:35:11,724 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-10-24 09:35:11,743 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-10-24 09:35:11,761 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-10-24 09:35:11,768 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-10-24 09:35:11,790 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-10-24 09:35:11,801 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-10-24 09:35:11,811 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-10-24 09:35:11,822 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-10-24 09:35:11,824 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-10-24 09:35:11,824 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-10-24 09:35:11,825 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-10-24 09:35:11,825 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-10-24 09:35:11,825 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-10-24 09:35:11,826 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-10-24 09:35:11,826 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-10-24 09:35:11,826 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-10-24 09:35:11,826 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-10-24 09:35:11,827 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-10-24 09:35:11,827 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-10-24 09:35:11,827 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-10-24 09:35:11,827 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-10-24 09:35:11,828 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-10-24 09:35:11,828 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-10-24 09:35:11,828 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-10-24 09:35:11,828 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-10-24 09:35:11,829 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-10-24 09:35:11,829 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-10-24 09:35:11,829 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-10-24 09:35:11,829 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-10-24 09:35:11,830 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-10-24 09:35:11,830 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-10-24 09:35:11,830 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-10-24 09:35:11,830 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-10-24 09:35:11,831 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-10-24 09:35:11,831 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-10-24 09:35:11,831 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-10-24 09:35:11,831 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-10-24 09:35:11,832 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-10-24 09:35:11,833 [salt.utils.extmods][INFO    ][23968] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-10-24 09:35:11,836 [salt.utils.extmods][INFO    ][23968] Syncing sdb for environment 'base'
2017-10-24 09:35:11,836 [salt.utils.extmods][INFO    ][23968] Loading cache from salt://_sdb, for base)
2017-10-24 09:35:11,836 [salt.fileclient  ][INFO    ][23968] Caching directory '_sdb/' for environment 'base'
2017-10-24 09:35:11,879 [salt.utils.extmods][INFO    ][23968] Syncing grains for environment 'base'
2017-10-24 09:35:11,879 [salt.utils.extmods][INFO    ][23968] Loading cache from salt://_grains, for base)
2017-10-24 09:35:11,879 [salt.fileclient  ][INFO    ][23968] Caching directory '_grains/' for environment 'base'
2017-10-24 09:35:11,948 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-10-24 09:35:11,958 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-10-24 09:35:11,966 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-10-24 09:35:12,021 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-10-24 09:35:12,056 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-10-24 09:35:12,065 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-10-24 09:35:12,076 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-10-24 09:35:12,091 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-10-24 09:35:12,105 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-10-24 09:35:12,120 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-10-24 09:35:12,130 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-10-24 09:35:12,139 [salt.fileclient  ][INFO    ][23968] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-10-24 09:35:12,144 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-10-24 09:35:12,144 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-10-24 09:35:12,145 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-10-24 09:35:12,145 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-10-24 09:35:12,145 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-10-24 09:35:12,146 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-10-24 09:35:12,146 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-10-24 09:35:12,146 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-10-24 09:35:12,147 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-10-24 09:35:12,147 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-10-24 09:35:12,147 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-10-24 09:35:12,147 [salt.utils.extmods][INFO    ][23968] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-10-24 09:35:12,149 [salt.utils.extmods][INFO    ][23968] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-10-24 09:35:12,153 [salt.utils.extmods][INFO    ][23968] Syncing renderers for environment 'base'
2017-10-24 09:35:12,153 [salt.utils.extmods][INFO    ][23968] Loading cache from salt://_renderers, for base)
2017-10-24 09:35:12,153 [salt.fileclient  ][INFO    ][23968] Caching directory '_renderers/' for environment 'base'
2017-10-24 09:35:12,174 [salt.utils.extmods][INFO    ][23968] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-10-24 09:35:12,177 [salt.utils.extmods][INFO    ][23968] Syncing returners for environment 'base'
2017-10-24 09:35:12,177 [salt.utils.extmods][INFO    ][23968] Loading cache from salt://_returners, for base)
2017-10-24 09:35:12,177 [salt.fileclient  ][INFO    ][23968] Caching directory '_returners/' for environment 'base'
2017-10-24 09:35:12,224 [salt.utils.extmods][INFO    ][23968] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-10-24 09:35:12,226 [salt.utils.extmods][INFO    ][23968] Syncing output for environment 'base'
2017-10-24 09:35:12,226 [salt.utils.extmods][INFO    ][23968] Loading cache from salt://_output, for base)
2017-10-24 09:35:12,226 [salt.fileclient  ][INFO    ][23968] Caching directory '_output/' for environment 'base'
2017-10-24 09:35:12,264 [salt.utils.extmods][INFO    ][23968] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-10-24 09:35:12,266 [salt.utils.extmods][INFO    ][23968] Syncing utils for environment 'base'
2017-10-24 09:35:12,266 [salt.utils.extmods][INFO    ][23968] Loading cache from salt://_utils, for base)
2017-10-24 09:35:12,267 [salt.fileclient  ][INFO    ][23968] Caching directory '_utils/' for environment 'base'
2017-10-24 09:35:12,288 [salt.utils.extmods][INFO    ][23968] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-10-24 09:35:12,290 [salt.utils.extmods][INFO    ][23968] Syncing log_handlers for environment 'base'
2017-10-24 09:35:12,290 [salt.utils.extmods][INFO    ][23968] Loading cache from salt://_log_handlers, for base)
2017-10-24 09:35:12,291 [salt.fileclient  ][INFO    ][23968] Caching directory '_log_handlers/' for environment 'base'
2017-10-24 09:35:12,323 [salt.utils.extmods][INFO    ][23968] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-10-24 09:35:12,325 [salt.utils.extmods][INFO    ][23968] Syncing proxy for environment 'base'
2017-10-24 09:35:12,325 [salt.utils.extmods][INFO    ][23968] Loading cache from salt://_proxy, for base)
2017-10-24 09:35:12,325 [salt.fileclient  ][INFO    ][23968] Caching directory '_proxy/' for environment 'base'
2017-10-24 09:35:12,349 [salt.utils.extmods][INFO    ][23968] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-10-24 09:35:12,351 [salt.utils.extmods][INFO    ][23968] Syncing engines for environment 'base'
2017-10-24 09:35:12,351 [salt.utils.extmods][INFO    ][23968] Loading cache from salt://_engines, for base)
2017-10-24 09:35:12,351 [salt.fileclient  ][INFO    ][23968] Caching directory '_engines/' for environment 'base'
2017-10-24 09:35:12,376 [salt.minion      ][INFO    ][23968] Returning information for job: 20171024093506904473
2017-10-24 09:40:06,884 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command test.ping with jid 20171024094006875038
2017-10-24 09:40:06,903 [salt.minion      ][INFO    ][24125] Starting a new job with PID 24125
2017-10-24 09:40:06,931 [salt.minion      ][INFO    ][24125] Returning information for job: 20171024094006875038
2017-10-24 09:43:51,522 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command state.sls with jid 20171024094351510751
2017-10-24 09:43:51,537 [salt.minion      ][INFO    ][24184] Starting a new job with PID 24184
2017-10-24 09:43:51,973 [salt.state       ][INFO    ][24184] Loading fresh modules for state activity
2017-10-24 09:43:52,067 [salt.fileclient  ][INFO    ][24184] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-10-24 09:43:52,084 [salt.fileclient  ][INFO    ][24184] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-10-24 09:43:52,109 [salt.fileclient  ][INFO    ][24184] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-24 09:43:52,397 [salt.state       ][INFO    ][24184] Running state [lsof] at time 09:43:52.397405
2017-10-24 09:43:52,398 [salt.state       ][INFO    ][24184] Executing state pkg.installed for lsof
2017-10-24 09:43:52,398 [salt.loaded.int.module.cmdmod][INFO    ][24184] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-24 09:43:52,660 [salt.state       ][INFO    ][24184] Package lsof is already installed
2017-10-24 09:43:52,660 [salt.state       ][INFO    ][24184] Completed state [lsof] at time 09:43:52.660316 duration_in_ms=262.911
2017-10-24 09:43:52,661 [salt.state       ][INFO    ][24184] Running state [keepalived] at time 09:43:52.660504
2017-10-24 09:43:52,661 [salt.state       ][INFO    ][24184] Executing state pkg.installed for keepalived
2017-10-24 09:43:52,675 [salt.loaded.int.module.cmdmod][INFO    ][24184] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-10-24 09:43:54,558 [salt.loaded.int.module.cmdmod][INFO    ][24184] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'keepalived'] in directory '/root'
2017-10-24 09:44:01,565 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command saltutil.find_job with jid 20171024094401553514
2017-10-24 09:44:01,579 [salt.minion      ][INFO    ][25610] Starting a new job with PID 25610
2017-10-24 09:44:01,589 [salt.minion      ][INFO    ][25610] Returning information for job: 20171024094401553514
2017-10-24 09:44:02,461 [salt.loaded.int.module.cmdmod][INFO    ][24184] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-24 09:44:02,485 [salt.state       ][INFO    ][24184] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4'
'keepalived' changed from 'absent' to '1:1.2.19-1ubuntu0.2'
'ipvsadm' changed from 'absent' to '1:1.28-3'
'libsensors4' changed from 'absent' to '1:3.4.0-2'

2017-10-24 09:44:02,493 [salt.state       ][INFO    ][24184] Loading fresh modules for state activity
2017-10-24 09:44:02,504 [salt.state       ][INFO    ][24184] Completed state [keepalived] at time 09:44:02.504070 duration_in_ms=9843.566
2017-10-24 09:44:02,506 [salt.state       ][INFO    ][24184] Running state [/etc/keepalived/keepalived.conf] at time 09:44:02.506135
2017-10-24 09:44:02,506 [salt.state       ][INFO    ][24184] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-10-24 09:44:02,531 [salt.fileclient  ][INFO    ][24184] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-10-24 09:44:02,559 [salt.fileclient  ][INFO    ][24184] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-24 09:44:02,565 [salt.state       ][INFO    ][24184] File changed:
New file
2017-10-24 09:44:02,565 [salt.state       ][INFO    ][24184] Completed state [/etc/keepalived/keepalived.conf] at time 09:44:02.565267 duration_in_ms=59.132
2017-10-24 09:44:02,623 [salt.state       ][INFO    ][24184] Running state [keepalived] at time 09:44:02.623144
2017-10-24 09:44:02,623 [salt.state       ][INFO    ][24184] Executing state service.running for keepalived
2017-10-24 09:44:02,625 [salt.loaded.int.module.cmdmod][INFO    ][24184] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-10-24 09:44:02,634 [salt.loaded.int.module.cmdmod][INFO    ][24184] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-24 09:44:02,641 [salt.loaded.int.module.cmdmod][INFO    ][24184] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-24 09:44:02,648 [salt.loaded.int.module.cmdmod][INFO    ][24184] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-24 09:44:02,655 [salt.loaded.int.module.cmdmod][INFO    ][24184] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-10-24 09:44:02,726 [salt.loaded.int.module.cmdmod][INFO    ][24184] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-24 09:44:02,733 [salt.loaded.int.module.cmdmod][INFO    ][24184] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-24 09:44:02,738 [salt.loaded.int.module.cmdmod][INFO    ][24184] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-24 09:44:02,745 [salt.state       ][INFO    ][24184] {'keepalived': True}
2017-10-24 09:44:02,745 [salt.state       ][INFO    ][24184] Completed state [keepalived] at time 09:44:02.744970 duration_in_ms=121.827
2017-10-24 09:44:02,746 [salt.minion      ][INFO    ][24184] Returning information for job: 20171024094351510751
2017-10-24 09:44:17,509 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command pillar.get with jid 20171024094417502991
2017-10-24 09:44:17,526 [salt.minion      ][INFO    ][25747] Starting a new job with PID 25747
2017-10-24 09:44:17,532 [salt.minion      ][INFO    ][25747] Returning information for job: 20171024094417502991
2017-10-24 09:49:31,649 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command state.sls with jid 20171024094931639403
2017-10-24 09:49:31,664 [salt.minion      ][INFO    ][25839] Starting a new job with PID 25839
2017-10-24 09:49:34,105 [salt.state       ][INFO    ][25839] Loading fresh modules for state activity
2017-10-24 09:49:34,125 [salt.fileclient  ][INFO    ][25839] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-24 09:49:34,145 [salt.fileclient  ][INFO    ][25839] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-24 09:49:34,404 [salt.state       ][INFO    ][25839] Running state [attr] at time 09:49:34.404249
2017-10-24 09:49:34,405 [salt.state       ][INFO    ][25839] Executing state pkg.installed for attr
2017-10-24 09:49:34,405 [salt.loaded.int.module.cmdmod][INFO    ][25839] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-24 09:49:34,653 [salt.loaded.int.module.cmdmod][INFO    ][25839] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-10-24 09:49:36,497 [salt.loaded.int.module.cmdmod][INFO    ][25839] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'attr'] in directory '/root'
2017-10-24 09:49:39,044 [salt.loaded.int.module.cmdmod][INFO    ][25839] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-24 09:49:39,069 [salt.state       ][INFO    ][25839] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-10-24 09:49:39,077 [salt.state       ][INFO    ][25839] Loading fresh modules for state activity
2017-10-24 09:49:39,089 [salt.state       ][INFO    ][25839] Completed state [attr] at time 09:49:39.089025 duration_in_ms=4684.775
2017-10-24 09:49:39,094 [salt.state       ][INFO    ][25839] Running state [glusterfs-server] at time 09:49:39.093591
2017-10-24 09:49:39,094 [salt.state       ][INFO    ][25839] Executing state pkg.installed for glusterfs-server
2017-10-24 09:49:39,275 [salt.loaded.int.module.cmdmod][INFO    ][25839] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'glusterfs-server'] in directory '/root'
2017-10-24 09:49:41,740 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command saltutil.find_job with jid 20171024094941729826
2017-10-24 09:49:41,755 [salt.minion      ][INFO    ][26811] Starting a new job with PID 26811
2017-10-24 09:49:41,765 [salt.minion      ][INFO    ][26811] Returning information for job: 20171024094941729826
2017-10-24 09:49:51,942 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command saltutil.find_job with jid 20171024094951931051
2017-10-24 09:49:51,987 [salt.minion      ][INFO    ][29485] Starting a new job with PID 29485
2017-10-24 09:49:52,003 [salt.minion      ][INFO    ][29485] Returning information for job: 20171024094951931051
2017-10-24 09:50:00,136 [salt.loaded.int.module.cmdmod][INFO    ][25839] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-24 09:50:00,160 [salt.state       ][INFO    ][25839] Made the following changes:
'keyutils' changed from 'absent' to '1.5.9-8ubuntu1'
'nfs-common' changed from 'absent' to '1:1.2.8-9ubuntu12.1'
'rpcbind' changed from 'absent' to '0.2.3-0.2'
'glusterfs-common' changed from 'absent' to '3.7.6-1ubuntu1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'libnfsidmap2' changed from 'absent' to '0.25-5'
'portmap' changed from 'absent' to '1'
'libattr1-dev' changed from 'absent' to '1:2.4.47-2'
'attr-dev' changed from 'absent' to '1'
'acl-dev' changed from 'absent' to '1'
'glusterfs-server' changed from 'absent' to '3.7.6-1ubuntu1'
'glusterfs-client' changed from 'absent' to '3.7.6-1ubuntu1'
'libpython2.7' changed from 'absent' to '2.7.12-1ubuntu0~16.04.1'
'nfs-client' changed from 'absent' to '1'
'liburcu4' changed from 'absent' to '0.9.1-3'
'libacl1-dev' changed from 'absent' to '2.2.52-3'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libtirpc1' changed from 'absent' to '0.2.5-1'

2017-10-24 09:50:00,168 [salt.state       ][INFO    ][25839] Loading fresh modules for state activity
2017-10-24 09:50:00,180 [salt.state       ][INFO    ][25839] Completed state [glusterfs-server] at time 09:50:00.179671 duration_in_ms=21086.08
2017-10-24 09:50:00,184 [salt.state       ][INFO    ][25839] Running state [bc] at time 09:50:00.183611
2017-10-24 09:50:00,184 [salt.state       ][INFO    ][25839] Executing state pkg.installed for bc
2017-10-24 09:50:00,427 [salt.loaded.int.module.cmdmod][INFO    ][25839] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'bc'] in directory '/root'
2017-10-24 09:50:02,148 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command saltutil.find_job with jid 20171024095002134620
2017-10-24 09:50:02,162 [salt.minion      ][INFO    ][30402] Starting a new job with PID 30402
2017-10-24 09:50:02,173 [salt.minion      ][INFO    ][30402] Returning information for job: 20171024095002134620
2017-10-24 09:50:03,335 [salt.loaded.int.module.cmdmod][INFO    ][25839] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-24 09:50:03,360 [salt.state       ][INFO    ][25839] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-10-24 09:50:03,368 [salt.state       ][INFO    ][25839] Loading fresh modules for state activity
2017-10-24 09:50:03,381 [salt.state       ][INFO    ][25839] Completed state [bc] at time 09:50:03.380914 duration_in_ms=3197.303
2017-10-24 09:50:03,454 [salt.state       ][INFO    ][25839] Running state [glusterfs-server] at time 09:50:03.454256
2017-10-24 09:50:03,455 [salt.state       ][INFO    ][25839] Executing state service.running for glusterfs-server
2017-10-24 09:50:03,456 [salt.loaded.int.module.cmdmod][INFO    ][25839] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-24 09:50:03,466 [salt.loaded.int.module.cmdmod][INFO    ][25839] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-24 09:50:03,473 [salt.loaded.int.module.cmdmod][INFO    ][25839] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-24 09:50:03,482 [salt.state       ][INFO    ][25839] The service glusterfs-server is already running
2017-10-24 09:50:03,482 [salt.state       ][INFO    ][25839] Completed state [glusterfs-server] at time 09:50:03.481776 duration_in_ms=27.52
2017-10-24 09:50:03,483 [salt.state       ][INFO    ][25839] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:50:03.483281
2017-10-24 09:50:03,484 [salt.state       ][INFO    ][25839] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-24 09:50:03,495 [salt.state       ][INFO    ][25839] File changed:
New file
2017-10-24 09:50:03,495 [salt.state       ][INFO    ][25839] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:50:03.495086 duration_in_ms=11.804
2017-10-24 09:50:03,495 [salt.state       ][INFO    ][25839] Running state [/srv/glusterfs/glance] at time 09:50:03.495339
2017-10-24 09:50:03,496 [salt.state       ][INFO    ][25839] Executing state file.directory for /srv/glusterfs/glance
2017-10-24 09:50:03,496 [salt.state       ][INFO    ][25839] {'/srv/glusterfs/glance': 'New Dir'}
2017-10-24 09:50:03,496 [salt.state       ][INFO    ][25839] Completed state [/srv/glusterfs/glance] at time 09:50:03.496390 duration_in_ms=1.051
2017-10-24 09:50:03,497 [salt.state       ][INFO    ][25839] Running state [/srv/glusterfs/keystone-keys] at time 09:50:03.496602
2017-10-24 09:50:03,497 [salt.state       ][INFO    ][25839] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-24 09:50:03,497 [salt.state       ][INFO    ][25839] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-10-24 09:50:03,497 [salt.state       ][INFO    ][25839] Completed state [/srv/glusterfs/keystone-keys] at time 09:50:03.497399 duration_in_ms=0.796
2017-10-24 09:50:03,498 [salt.state       ][INFO    ][25839] Running state [/srv/glusterfs/nova_instances] at time 09:50:03.497621
2017-10-24 09:50:03,498 [salt.state       ][INFO    ][25839] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-24 09:50:03,498 [salt.state       ][INFO    ][25839] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-10-24 09:50:03,498 [salt.state       ][INFO    ][25839] Completed state [/srv/glusterfs/nova_instances] at time 09:50:03.498437 duration_in_ms=0.816
2017-10-24 09:50:03,499 [salt.state       ][INFO    ][25839] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:50:03.498665
2017-10-24 09:50:03,499 [salt.state       ][INFO    ][25839] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-24 09:50:03,499 [salt.state       ][INFO    ][25839] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-10-24 09:50:03,500 [salt.state       ][INFO    ][25839] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:50:03.499478 duration_in_ms=0.813
2017-10-24 09:50:03,500 [salt.minion      ][INFO    ][25839] Returning information for job: 20171024094931639403
2017-10-24 09:50:05,615 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command test.ping with jid 20171024095005603464
2017-10-24 09:50:05,629 [salt.minion      ][INFO    ][30557] Starting a new job with PID 30557
2017-10-24 09:50:05,655 [salt.minion      ][INFO    ][30557] Returning information for job: 20171024095005603464
2017-10-24 09:50:52,653 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command state.sls with jid 20171024095052641632
2017-10-24 09:50:52,667 [salt.minion      ][INFO    ][30786] Starting a new job with PID 30786
2017-10-24 09:50:53,110 [salt.state       ][INFO    ][30786] Loading fresh modules for state activity
2017-10-24 09:50:53,131 [salt.fileclient  ][INFO    ][30786] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-10-24 09:50:53,162 [salt.fileclient  ][INFO    ][30786] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-24 09:50:53,225 [salt.fileclient  ][INFO    ][30786] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-24 09:50:53,244 [salt.fileclient  ][INFO    ][30786] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-24 09:50:53,513 [salt.state       ][INFO    ][30786] Running state [attr] at time 09:50:53.512484
2017-10-24 09:50:53,513 [salt.state       ][INFO    ][30786] Executing state pkg.installed for attr
2017-10-24 09:50:53,513 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-24 09:50:53,752 [salt.state       ][INFO    ][30786] Package attr is already installed
2017-10-24 09:50:53,753 [salt.state       ][INFO    ][30786] Completed state [attr] at time 09:50:53.752694 duration_in_ms=240.211
2017-10-24 09:50:53,753 [salt.state       ][INFO    ][30786] Running state [glusterfs-server] at time 09:50:53.752889
2017-10-24 09:50:53,753 [salt.state       ][INFO    ][30786] Executing state pkg.installed for glusterfs-server
2017-10-24 09:50:53,756 [salt.state       ][INFO    ][30786] Package glusterfs-server is already installed
2017-10-24 09:50:53,756 [salt.state       ][INFO    ][30786] Completed state [glusterfs-server] at time 09:50:53.755749 duration_in_ms=2.86
2017-10-24 09:50:53,756 [salt.state       ][INFO    ][30786] Running state [bc] at time 09:50:53.755886
2017-10-24 09:50:53,756 [salt.state       ][INFO    ][30786] Executing state pkg.installed for bc
2017-10-24 09:50:53,759 [salt.state       ][INFO    ][30786] Package bc is already installed
2017-10-24 09:50:53,759 [salt.state       ][INFO    ][30786] Completed state [bc] at time 09:50:53.758672 duration_in_ms=2.786
2017-10-24 09:50:53,759 [salt.state       ][INFO    ][30786] Running state [glusterfs-server] at time 09:50:53.759439
2017-10-24 09:50:53,760 [salt.state       ][INFO    ][30786] Executing state service.running for glusterfs-server
2017-10-24 09:50:53,760 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-24 09:50:53,769 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-10-24 09:50:53,811 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-24 09:50:53,825 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-24 09:50:53,832 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-24 09:50:53,840 [salt.state       ][INFO    ][30786] The service glusterfs-server is already running
2017-10-24 09:50:53,840 [salt.state       ][INFO    ][30786] Completed state [glusterfs-server] at time 09:50:53.840346 duration_in_ms=80.906
2017-10-24 09:50:53,842 [salt.state       ][INFO    ][30786] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:50:53.841875
2017-10-24 09:50:53,842 [salt.state       ][INFO    ][30786] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-24 09:50:53,855 [salt.state       ][INFO    ][30786] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-24 09:50:53,856 [salt.state       ][INFO    ][30786] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:50:53.855508 duration_in_ms=13.634
2017-10-24 09:50:53,856 [salt.state       ][INFO    ][30786] Running state [/srv/glusterfs/glance] at time 09:50:53.855677
2017-10-24 09:50:53,856 [salt.state       ][INFO    ][30786] Executing state file.directory for /srv/glusterfs/glance
2017-10-24 09:50:53,856 [salt.state       ][INFO    ][30786] Directory /srv/glusterfs/glance is in the correct state
2017-10-24 09:50:53,856 [salt.state       ][INFO    ][30786] Completed state [/srv/glusterfs/glance] at time 09:50:53.856367 duration_in_ms=0.689
2017-10-24 09:50:53,857 [salt.state       ][INFO    ][30786] Running state [/srv/glusterfs/keystone-keys] at time 09:50:53.856531
2017-10-24 09:50:53,857 [salt.state       ][INFO    ][30786] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-24 09:50:53,857 [salt.state       ][INFO    ][30786] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-24 09:50:53,857 [salt.state       ][INFO    ][30786] Completed state [/srv/glusterfs/keystone-keys] at time 09:50:53.857192 duration_in_ms=0.66
2017-10-24 09:50:53,857 [salt.state       ][INFO    ][30786] Running state [/srv/glusterfs/nova_instances] at time 09:50:53.857355
2017-10-24 09:50:53,858 [salt.state       ][INFO    ][30786] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-24 09:50:53,858 [salt.state       ][INFO    ][30786] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-24 09:50:53,858 [salt.state       ][INFO    ][30786] Completed state [/srv/glusterfs/nova_instances] at time 09:50:53.858028 duration_in_ms=0.673
2017-10-24 09:50:53,858 [salt.state       ][INFO    ][30786] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:50:53.858190
2017-10-24 09:50:53,858 [salt.state       ][INFO    ][30786] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-24 09:50:53,859 [salt.state       ][INFO    ][30786] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-24 09:50:53,859 [salt.state       ][INFO    ][30786] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:50:53.858846 duration_in_ms=0.656
2017-10-24 09:50:53,912 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command ['git', '--version'] in directory '/root'
2017-10-24 09:50:53,955 [salt.state       ][INFO    ][30786] Running state [sleep 5] at time 09:50:53.955067
2017-10-24 09:50:53,955 [salt.state       ][INFO    ][30786] Executing state cmd.wait for sleep 5
2017-10-24 09:50:53,956 [salt.state       ][INFO    ][30786] No changes made for sleep 5
2017-10-24 09:50:53,956 [salt.state       ][INFO    ][30786] Completed state [sleep 5] at time 09:50:53.955619 duration_in_ms=0.552
2017-10-24 09:50:53,956 [salt.state       ][INFO    ][30786] Running state [10.167.4.141] at time 09:50:53.955985
2017-10-24 09:50:53,956 [salt.state       ][INFO    ][30786] Executing state glusterfs.peered for 10.167.4.141
2017-10-24 09:50:53,956 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:53,965 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:54,015 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:54,022 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:54,070 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:54,077 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:54,124 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:54,132 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:54,224 [salt.state       ][INFO    ][30786] Peering with localhost is not needed
2017-10-24 09:50:54,225 [salt.state       ][INFO    ][30786] Completed state [10.167.4.141] at time 09:50:54.224697 duration_in_ms=268.711
2017-10-24 09:50:54,227 [salt.state       ][INFO    ][30786] Running state [10.167.4.142] at time 09:50:54.226775
2017-10-24 09:50:54,227 [salt.state       ][INFO    ][30786] Executing state glusterfs.peered for 10.167.4.142
2017-10-24 09:50:54,227 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:54,237 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:54,283 [salt.state       ][INFO    ][30786] Host 10.167.4.142 already peered
2017-10-24 09:50:54,283 [salt.state       ][INFO    ][30786] Completed state [10.167.4.142] at time 09:50:54.283201 duration_in_ms=56.426
2017-10-24 09:50:54,286 [salt.state       ][INFO    ][30786] Running state [10.167.4.143] at time 09:50:54.285583
2017-10-24 09:50:54,286 [salt.state       ][INFO    ][30786] Executing state glusterfs.peered for 10.167.4.143
2017-10-24 09:50:54,286 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:54,295 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:54,343 [salt.state       ][INFO    ][30786] Host 10.167.4.143 already peered
2017-10-24 09:50:54,343 [salt.state       ][INFO    ][30786] Completed state [10.167.4.143] at time 09:50:54.343391 duration_in_ms=57.808
2017-10-24 09:50:54,344 [salt.state       ][INFO    ][30786] Running state [glance] at time 09:50:54.344231
2017-10-24 09:50:54,345 [salt.state       ][INFO    ][30786] Executing state glusterfs.created for glance
2017-10-24 09:50:54,345 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:54,354 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:54,401 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:54,410 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:54,461 [salt.state       ][INFO    ][30786] Volume glance already exists and is started.
2017-10-24 09:50:54,461 [salt.state       ][INFO    ][30786] Completed state [glance] at time 09:50:54.461366 duration_in_ms=117.134
2017-10-24 09:50:54,464 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:50:54.464078
2017-10-24 09:50:54,464 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-10-24 09:50:54,465 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-24 09:50:54,511 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:54,511 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:50:54.511311 duration_in_ms=47.233
2017-10-24 09:50:54,512 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:50:54.511760
2017-10-24 09:50:54,512 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-10-24 09:50:54,512 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-24 09:50:54,560 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:54,560 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:50:54.559982 duration_in_ms=48.221
2017-10-24 09:50:54,560 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:50:54.560446
2017-10-24 09:50:54,561 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-10-24 09:50:54,561 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-10-24 09:50:54,612 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:54,612 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:50:54.612072 duration_in_ms=51.626
2017-10-24 09:50:54,613 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:50:54.612503
2017-10-24 09:50:54,613 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-10-24 09:50:54,613 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-24 09:50:54,662 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:54,662 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:50:54.662076 duration_in_ms=49.573
2017-10-24 09:50:54,663 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:50:54.662520
2017-10-24 09:50:54,663 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-10-24 09:50:54,663 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-24 09:50:54,709 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:54,710 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:50:54.709662 duration_in_ms=47.141
2017-10-24 09:50:54,711 [salt.state       ][INFO    ][30786] Running state [glance] at time 09:50:54.710597
2017-10-24 09:50:54,711 [salt.state       ][INFO    ][30786] Executing state glusterfs.started for glance
2017-10-24 09:50:54,711 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:54,720 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:54,769 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:54,777 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:54,828 [salt.state       ][INFO    ][30786] Volume glance is already started
2017-10-24 09:50:54,828 [salt.state       ][INFO    ][30786] Completed state [glance] at time 09:50:54.827833 duration_in_ms=117.235
2017-10-24 09:50:54,829 [salt.state       ][INFO    ][30786] Running state [keystone-keys] at time 09:50:54.828604
2017-10-24 09:50:54,829 [salt.state       ][INFO    ][30786] Executing state glusterfs.created for keystone-keys
2017-10-24 09:50:54,829 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:54,838 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:54,887 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:54,896 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:54,946 [salt.state       ][INFO    ][30786] Volume keystone-keys already exists and is started.
2017-10-24 09:50:54,946 [salt.state       ][INFO    ][30786] Completed state [keystone-keys] at time 09:50:54.945991 duration_in_ms=117.387
2017-10-24 09:50:54,949 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:50:54.948630
2017-10-24 09:50:54,949 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-10-24 09:50:54,949 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-24 09:50:54,996 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:54,996 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:50:54.996339 duration_in_ms=47.709
2017-10-24 09:50:54,997 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:50:54.996837
2017-10-24 09:50:54,997 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-24 09:50:54,998 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-24 09:50:55,050 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:55,050 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:50:55.049851 duration_in_ms=53.013
2017-10-24 09:50:55,050 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:50:55.050276
2017-10-24 09:50:55,051 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-24 09:50:55,051 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-24 09:50:55,099 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:55,099 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:50:55.098958 duration_in_ms=48.682
2017-10-24 09:50:55,099 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:50:55.099370
2017-10-24 09:50:55,100 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-10-24 09:50:55,100 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-24 09:50:55,146 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:55,146 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:50:55.146360 duration_in_ms=46.99
2017-10-24 09:50:55,147 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:50:55.146796
2017-10-24 09:50:55,147 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-10-24 09:50:55,147 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-24 09:50:55,193 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:55,194 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:50:55.193672 duration_in_ms=46.876
2017-10-24 09:50:55,195 [salt.state       ][INFO    ][30786] Running state [keystone-keys] at time 09:50:55.194577
2017-10-24 09:50:55,195 [salt.state       ][INFO    ][30786] Executing state glusterfs.started for keystone-keys
2017-10-24 09:50:55,195 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:55,203 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:55,254 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:55,263 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:55,334 [salt.state       ][INFO    ][30786] Volume keystone-keys is already started
2017-10-24 09:50:55,334 [salt.state       ][INFO    ][30786] Completed state [keystone-keys] at time 09:50:55.333746 duration_in_ms=139.167
2017-10-24 09:50:55,335 [salt.state       ][INFO    ][30786] Running state [nova_instances] at time 09:50:55.334548
2017-10-24 09:50:55,335 [salt.state       ][INFO    ][30786] Executing state glusterfs.created for nova_instances
2017-10-24 09:50:55,335 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:55,344 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:55,395 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:55,404 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:55,456 [salt.state       ][INFO    ][30786] Volume nova_instances already exists and is started.
2017-10-24 09:50:55,456 [salt.state       ][INFO    ][30786] Completed state [nova_instances] at time 09:50:55.456168 duration_in_ms=121.619
2017-10-24 09:50:55,459 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:50:55.459135
2017-10-24 09:50:55,459 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-10-24 09:50:55,460 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-24 09:50:55,511 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:55,511 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:50:55.511114 duration_in_ms=51.978
2017-10-24 09:50:55,512 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 09:50:55.511556
2017-10-24 09:50:55,512 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-10-24 09:50:55,512 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-10-24 09:50:55,559 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:55,559 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 09:50:55.559075 duration_in_ms=47.519
2017-10-24 09:50:55,560 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 09:50:55.559530
2017-10-24 09:50:55,560 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-10-24 09:50:55,560 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-10-24 09:50:55,610 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:55,610 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 09:50:55.609992 duration_in_ms=50.462
2017-10-24 09:50:55,610 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 09:50:55.610421
2017-10-24 09:50:55,611 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-10-24 09:50:55,611 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-10-24 09:50:55,659 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:55,659 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 09:50:55.658880 duration_in_ms=48.458
2017-10-24 09:50:55,659 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:50:55.659317
2017-10-24 09:50:55,660 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-10-24 09:50:55,660 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-24 09:50:55,706 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:55,707 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:50:55.706480 duration_in_ms=47.162
2017-10-24 09:50:55,707 [salt.state       ][INFO    ][30786] Running state [nova_instances] at time 09:50:55.707397
2017-10-24 09:50:55,708 [salt.state       ][INFO    ][30786] Executing state glusterfs.started for nova_instances
2017-10-24 09:50:55,708 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:55,718 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:55,768 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:55,775 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:55,837 [salt.state       ][INFO    ][30786] Volume nova_instances is already started
2017-10-24 09:50:55,837 [salt.state       ][INFO    ][30786] Completed state [nova_instances] at time 09:50:55.837023 duration_in_ms=129.625
2017-10-24 09:50:55,838 [salt.state       ][INFO    ][30786] Running state [keystone-credential-keys] at time 09:50:55.837881
2017-10-24 09:50:55,838 [salt.state       ][INFO    ][30786] Executing state glusterfs.created for keystone-credential-keys
2017-10-24 09:50:55,839 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:55,847 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:55,894 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:55,903 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:55,955 [salt.state       ][INFO    ][30786] Volume keystone-credential-keys already exists and is started.
2017-10-24 09:50:55,955 [salt.state       ][INFO    ][30786] Completed state [keystone-credential-keys] at time 09:50:55.955136 duration_in_ms=117.254
2017-10-24 09:50:55,958 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:50:55.958133
2017-10-24 09:50:55,958 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-10-24 09:50:55,959 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-24 09:50:56,011 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:56,011 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:50:56.011247 duration_in_ms=53.114
2017-10-24 09:50:56,012 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:50:56.011673
2017-10-24 09:50:56,012 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-24 09:50:56,012 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-24 09:50:56,060 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:56,060 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:50:56.059807 duration_in_ms=48.133
2017-10-24 09:50:56,060 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:50:56.060294
2017-10-24 09:50:56,061 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-10-24 09:50:56,061 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-24 09:50:56,110 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:56,111 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:50:56.110931 duration_in_ms=50.636
2017-10-24 09:50:56,112 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:50:56.111726
2017-10-24 09:50:56,112 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-24 09:50:56,113 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-24 09:50:56,166 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:56,167 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:50:56.166649 duration_in_ms=54.923
2017-10-24 09:50:56,167 [salt.state       ][INFO    ][30786] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:50:56.167108
2017-10-24 09:50:56,167 [salt.state       ][INFO    ][30786] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-10-24 09:50:56,168 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-24 09:50:56,217 [salt.state       ][INFO    ][30786] unless execution succeeded
2017-10-24 09:50:56,217 [salt.state       ][INFO    ][30786] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:50:56.216786 duration_in_ms=49.678
2017-10-24 09:50:56,218 [salt.state       ][INFO    ][30786] Running state [keystone-credential-keys] at time 09:50:56.217745
2017-10-24 09:50:56,218 [salt.state       ][INFO    ][30786] Executing state glusterfs.started for keystone-credential-keys
2017-10-24 09:50:56,219 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:56,227 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:56,274 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --version' in directory '/root'
2017-10-24 09:50:56,283 [salt.loaded.int.module.cmdmod][INFO    ][30786] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 09:50:56,333 [salt.state       ][INFO    ][30786] Volume keystone-credential-keys is already started
2017-10-24 09:50:56,333 [salt.state       ][INFO    ][30786] Completed state [keystone-credential-keys] at time 09:50:56.333336 duration_in_ms=115.591
2017-10-24 09:50:56,336 [salt.minion      ][INFO    ][30786] Returning information for job: 20171024095052641632
2017-10-24 09:50:57,564 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command test.ping with jid 20171024095057551703
2017-10-24 09:50:57,579 [salt.minion      ][INFO    ][31186] Starting a new job with PID 31186
2017-10-24 09:50:57,603 [salt.minion      ][INFO    ][31186] Returning information for job: 20171024095057551703
2017-10-24 09:50:58,265 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command cmd.run with jid 20171024095058255052
2017-10-24 09:50:58,278 [salt.minion      ][INFO    ][31193] Starting a new job with PID 31193
2017-10-24 09:50:58,282 [salt.loaded.int.module.cmdmod][INFO    ][31193] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-10-24 09:50:58,391 [salt.minion      ][INFO    ][31193] Returning information for job: 20171024095058255052
2017-10-24 10:33:43,595 [salt.utils.schedule][INFO    ][23720] Running scheduled job: __mine_interval
2017-10-24 10:58:23,580 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command test.ping with jid 20171024105823571553
2017-10-24 10:58:23,603 [salt.minion      ][INFO    ][32297] Starting a new job with PID 32297
2017-10-24 10:58:23,633 [salt.minion      ][INFO    ][32297] Returning information for job: 20171024105823571553
2017-10-24 10:58:51,843 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command state.sls with jid 20171024105851833236
2017-10-24 10:58:51,856 [salt.minion      ][INFO    ][32308] Starting a new job with PID 32308
2017-10-24 10:58:53,546 [salt.state       ][INFO    ][32308] Loading fresh modules for state activity
2017-10-24 10:58:53,571 [salt.fileclient  ][INFO    ][32308] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-10-24 10:58:53,589 [salt.fileclient  ][INFO    ][32308] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-10-24 10:58:53,613 [salt.fileclient  ][INFO    ][32308] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-24 10:58:53,885 [salt.state       ][INFO    ][32308] Running state [lsof] at time 10:58:53.885136
2017-10-24 10:58:53,885 [salt.state       ][INFO    ][32308] Executing state pkg.installed for lsof
2017-10-24 10:58:53,886 [salt.loaded.int.module.cmdmod][INFO    ][32308] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-24 10:58:54,164 [salt.state       ][INFO    ][32308] Package lsof is already installed
2017-10-24 10:58:54,164 [salt.state       ][INFO    ][32308] Completed state [lsof] at time 10:58:54.164221 duration_in_ms=279.084
2017-10-24 10:58:54,164 [salt.state       ][INFO    ][32308] Running state [keepalived] at time 10:58:54.164413
2017-10-24 10:58:54,165 [salt.state       ][INFO    ][32308] Executing state pkg.installed for keepalived
2017-10-24 10:58:54,167 [salt.state       ][INFO    ][32308] Package keepalived is already installed
2017-10-24 10:58:54,167 [salt.state       ][INFO    ][32308] Completed state [keepalived] at time 10:58:54.167426 duration_in_ms=3.013
2017-10-24 10:58:54,169 [salt.state       ][INFO    ][32308] Running state [/etc/keepalived/keepalived.conf] at time 10:58:54.168882
2017-10-24 10:58:54,169 [salt.state       ][INFO    ][32308] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-10-24 10:58:54,239 [salt.fileclient  ][INFO    ][32308] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-10-24 10:58:54,269 [salt.fileclient  ][INFO    ][32308] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-24 10:58:54,273 [salt.state       ][INFO    ][32308] File /etc/keepalived/keepalived.conf is in the correct state
2017-10-24 10:58:54,273 [salt.state       ][INFO    ][32308] Completed state [/etc/keepalived/keepalived.conf] at time 10:58:54.272999 duration_in_ms=104.117
2017-10-24 10:58:54,274 [salt.state       ][INFO    ][32308] Running state [keepalived] at time 10:58:54.273569
2017-10-24 10:58:54,274 [salt.state       ][INFO    ][32308] Executing state service.running for keepalived
2017-10-24 10:58:54,274 [salt.loaded.int.module.cmdmod][INFO    ][32308] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-10-24 10:58:54,291 [salt.loaded.int.module.cmdmod][INFO    ][32308] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-24 10:58:54,299 [salt.loaded.int.module.cmdmod][INFO    ][32308] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-24 10:58:54,306 [salt.state       ][INFO    ][32308] The service keepalived is already running
2017-10-24 10:58:54,307 [salt.state       ][INFO    ][32308] Completed state [keepalived] at time 10:58:54.306663 duration_in_ms=33.094
2017-10-24 10:58:54,308 [salt.minion      ][INFO    ][32308] Returning information for job: 20171024105851833236
2017-10-24 10:58:55,153 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command pillar.get with jid 20171024105855147664
2017-10-24 10:58:55,177 [salt.minion      ][INFO    ][32332] Starting a new job with PID 32332
2017-10-24 10:58:55,183 [salt.minion      ][INFO    ][32332] Returning information for job: 20171024105855147664
2017-10-24 10:59:29,429 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command state.sls with jid 20171024105929399874
2017-10-24 10:59:29,444 [salt.minion      ][INFO    ][32349] Starting a new job with PID 32349
2017-10-24 10:59:31,963 [salt.state       ][INFO    ][32349] Loading fresh modules for state activity
2017-10-24 10:59:31,986 [salt.fileclient  ][INFO    ][32349] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-24 10:59:32,008 [salt.fileclient  ][INFO    ][32349] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-24 10:59:32,266 [salt.state       ][INFO    ][32349] Running state [attr] at time 10:59:32.265637
2017-10-24 10:59:32,266 [salt.state       ][INFO    ][32349] Executing state pkg.installed for attr
2017-10-24 10:59:32,266 [salt.loaded.int.module.cmdmod][INFO    ][32349] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-24 10:59:32,492 [salt.state       ][INFO    ][32349] Package attr is already installed
2017-10-24 10:59:32,492 [salt.state       ][INFO    ][32349] Completed state [attr] at time 10:59:32.492454 duration_in_ms=226.818
2017-10-24 10:59:32,493 [salt.state       ][INFO    ][32349] Running state [glusterfs-server] at time 10:59:32.492643
2017-10-24 10:59:32,493 [salt.state       ][INFO    ][32349] Executing state pkg.installed for glusterfs-server
2017-10-24 10:59:32,495 [salt.state       ][INFO    ][32349] Package glusterfs-server is already installed
2017-10-24 10:59:32,496 [salt.state       ][INFO    ][32349] Completed state [glusterfs-server] at time 10:59:32.495534 duration_in_ms=2.891
2017-10-24 10:59:32,496 [salt.state       ][INFO    ][32349] Running state [bc] at time 10:59:32.495673
2017-10-24 10:59:32,496 [salt.state       ][INFO    ][32349] Executing state pkg.installed for bc
2017-10-24 10:59:32,498 [salt.state       ][INFO    ][32349] Package bc is already installed
2017-10-24 10:59:32,498 [salt.state       ][INFO    ][32349] Completed state [bc] at time 10:59:32.498445 duration_in_ms=2.772
2017-10-24 10:59:32,499 [salt.state       ][INFO    ][32349] Running state [glusterfs-server] at time 10:59:32.499084
2017-10-24 10:59:32,499 [salt.state       ][INFO    ][32349] Executing state service.running for glusterfs-server
2017-10-24 10:59:32,500 [salt.loaded.int.module.cmdmod][INFO    ][32349] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-24 10:59:32,517 [salt.loaded.int.module.cmdmod][INFO    ][32349] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-24 10:59:32,525 [salt.loaded.int.module.cmdmod][INFO    ][32349] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-24 10:59:32,535 [salt.state       ][INFO    ][32349] The service glusterfs-server is already running
2017-10-24 10:59:32,535 [salt.state       ][INFO    ][32349] Completed state [glusterfs-server] at time 10:59:32.534856 duration_in_ms=35.771
2017-10-24 10:59:32,536 [salt.state       ][INFO    ][32349] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:59:32.536158
2017-10-24 10:59:32,536 [salt.state       ][INFO    ][32349] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-24 10:59:32,548 [salt.state       ][INFO    ][32349] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-24 10:59:32,549 [salt.state       ][INFO    ][32349] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:59:32.548609 duration_in_ms=12.452
2017-10-24 10:59:32,549 [salt.state       ][INFO    ][32349] Running state [/srv/glusterfs/glance] at time 10:59:32.548755
2017-10-24 10:59:32,549 [salt.state       ][INFO    ][32349] Executing state file.directory for /srv/glusterfs/glance
2017-10-24 10:59:32,549 [salt.state       ][INFO    ][32349] Directory /srv/glusterfs/glance is in the correct state
2017-10-24 10:59:32,549 [salt.state       ][INFO    ][32349] Completed state [/srv/glusterfs/glance] at time 10:59:32.549397 duration_in_ms=0.642
2017-10-24 10:59:32,550 [salt.state       ][INFO    ][32349] Running state [/srv/glusterfs/keystone-keys] at time 10:59:32.549553
2017-10-24 10:59:32,550 [salt.state       ][INFO    ][32349] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-24 10:59:32,550 [salt.state       ][INFO    ][32349] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-24 10:59:32,550 [salt.state       ][INFO    ][32349] Completed state [/srv/glusterfs/keystone-keys] at time 10:59:32.550128 duration_in_ms=0.576
2017-10-24 10:59:32,550 [salt.state       ][INFO    ][32349] Running state [/srv/glusterfs/nova_instances] at time 10:59:32.550265
2017-10-24 10:59:32,550 [salt.state       ][INFO    ][32349] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-24 10:59:32,551 [salt.state       ][INFO    ][32349] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-24 10:59:32,551 [salt.state       ][INFO    ][32349] Completed state [/srv/glusterfs/nova_instances] at time 10:59:32.550835 duration_in_ms=0.57
2017-10-24 10:59:32,551 [salt.state       ][INFO    ][32349] Running state [/srv/glusterfs/keystone-credential-keys] at time 10:59:32.550971
2017-10-24 10:59:32,551 [salt.state       ][INFO    ][32349] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-24 10:59:32,551 [salt.state       ][INFO    ][32349] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-24 10:59:32,552 [salt.state       ][INFO    ][32349] Completed state [/srv/glusterfs/keystone-credential-keys] at time 10:59:32.551546 duration_in_ms=0.574
2017-10-24 10:59:32,552 [salt.minion      ][INFO    ][32349] Returning information for job: 20171024105929399874
2017-10-24 10:59:33,749 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command test.ping with jid 20171024105933740679
2017-10-24 10:59:33,762 [salt.minion      ][INFO    ][32372] Starting a new job with PID 32372
2017-10-24 10:59:33,787 [salt.minion      ][INFO    ][32372] Returning information for job: 20171024105933740679
2017-10-24 10:59:41,281 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command state.sls with jid 20171024105941273556
2017-10-24 10:59:41,296 [salt.minion      ][INFO    ][32377] Starting a new job with PID 32377
2017-10-24 10:59:41,771 [salt.state       ][INFO    ][32377] Loading fresh modules for state activity
2017-10-24 10:59:41,795 [salt.fileclient  ][INFO    ][32377] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-10-24 10:59:41,828 [salt.fileclient  ][INFO    ][32377] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-24 10:59:41,891 [salt.fileclient  ][INFO    ][32377] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-24 10:59:41,912 [salt.fileclient  ][INFO    ][32377] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-24 10:59:42,194 [salt.state       ][INFO    ][32377] Running state [attr] at time 10:59:42.193594
2017-10-24 10:59:42,194 [salt.state       ][INFO    ][32377] Executing state pkg.installed for attr
2017-10-24 10:59:42,194 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-24 10:59:42,420 [salt.state       ][INFO    ][32377] Package attr is already installed
2017-10-24 10:59:42,420 [salt.state       ][INFO    ][32377] Completed state [attr] at time 10:59:42.420401 duration_in_ms=226.808
2017-10-24 10:59:42,421 [salt.state       ][INFO    ][32377] Running state [glusterfs-server] at time 10:59:42.420625
2017-10-24 10:59:42,421 [salt.state       ][INFO    ][32377] Executing state pkg.installed for glusterfs-server
2017-10-24 10:59:42,423 [salt.state       ][INFO    ][32377] Package glusterfs-server is already installed
2017-10-24 10:59:42,424 [salt.state       ][INFO    ][32377] Completed state [glusterfs-server] at time 10:59:42.423523 duration_in_ms=2.898
2017-10-24 10:59:42,424 [salt.state       ][INFO    ][32377] Running state [bc] at time 10:59:42.423666
2017-10-24 10:59:42,424 [salt.state       ][INFO    ][32377] Executing state pkg.installed for bc
2017-10-24 10:59:42,426 [salt.state       ][INFO    ][32377] Package bc is already installed
2017-10-24 10:59:42,427 [salt.state       ][INFO    ][32377] Completed state [bc] at time 10:59:42.426475 duration_in_ms=2.81
2017-10-24 10:59:42,427 [salt.state       ][INFO    ][32377] Running state [glusterfs-server] at time 10:59:42.427251
2017-10-24 10:59:42,427 [salt.state       ][INFO    ][32377] Executing state service.running for glusterfs-server
2017-10-24 10:59:42,428 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-24 10:59:42,441 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-24 10:59:42,449 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-24 10:59:42,457 [salt.state       ][INFO    ][32377] The service glusterfs-server is already running
2017-10-24 10:59:42,457 [salt.state       ][INFO    ][32377] Completed state [glusterfs-server] at time 10:59:42.457186 duration_in_ms=29.935
2017-10-24 10:59:42,459 [salt.state       ][INFO    ][32377] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:59:42.458627
2017-10-24 10:59:42,459 [salt.state       ][INFO    ][32377] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-24 10:59:42,463 [salt.state       ][INFO    ][32377] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-24 10:59:42,463 [salt.state       ][INFO    ][32377] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:59:42.462722 duration_in_ms=4.095
2017-10-24 10:59:42,463 [salt.state       ][INFO    ][32377] Running state [/srv/glusterfs/glance] at time 10:59:42.462876
2017-10-24 10:59:42,463 [salt.state       ][INFO    ][32377] Executing state file.directory for /srv/glusterfs/glance
2017-10-24 10:59:42,463 [salt.state       ][INFO    ][32377] Directory /srv/glusterfs/glance is in the correct state
2017-10-24 10:59:42,464 [salt.state       ][INFO    ][32377] Completed state [/srv/glusterfs/glance] at time 10:59:42.463491 duration_in_ms=0.615
2017-10-24 10:59:42,464 [salt.state       ][INFO    ][32377] Running state [/srv/glusterfs/keystone-keys] at time 10:59:42.463634
2017-10-24 10:59:42,464 [salt.state       ][INFO    ][32377] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-24 10:59:42,464 [salt.state       ][INFO    ][32377] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-24 10:59:42,464 [salt.state       ][INFO    ][32377] Completed state [/srv/glusterfs/keystone-keys] at time 10:59:42.464224 duration_in_ms=0.591
2017-10-24 10:59:42,464 [salt.state       ][INFO    ][32377] Running state [/srv/glusterfs/nova_instances] at time 10:59:42.464361
2017-10-24 10:59:42,465 [salt.state       ][INFO    ][32377] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-24 10:59:42,465 [salt.state       ][INFO    ][32377] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-24 10:59:42,465 [salt.state       ][INFO    ][32377] Completed state [/srv/glusterfs/nova_instances] at time 10:59:42.464954 duration_in_ms=0.592
2017-10-24 10:59:42,465 [salt.state       ][INFO    ][32377] Running state [/srv/glusterfs/keystone-credential-keys] at time 10:59:42.465096
2017-10-24 10:59:42,465 [salt.state       ][INFO    ][32377] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-24 10:59:42,466 [salt.state       ][INFO    ][32377] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-24 10:59:42,466 [salt.state       ][INFO    ][32377] Completed state [/srv/glusterfs/keystone-credential-keys] at time 10:59:42.465762 duration_in_ms=0.666
2017-10-24 10:59:42,502 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command ['git', '--version'] in directory '/root'
2017-10-24 10:59:42,580 [salt.state       ][INFO    ][32377] Running state [sleep 5] at time 10:59:42.579867
2017-10-24 10:59:42,580 [salt.state       ][INFO    ][32377] Executing state cmd.wait for sleep 5
2017-10-24 10:59:42,580 [salt.state       ][INFO    ][32377] No changes made for sleep 5
2017-10-24 10:59:42,580 [salt.state       ][INFO    ][32377] Completed state [sleep 5] at time 10:59:42.580459 duration_in_ms=0.592
2017-10-24 10:59:42,581 [salt.state       ][INFO    ][32377] Running state [10.167.4.141] at time 10:59:42.580850
2017-10-24 10:59:42,581 [salt.state       ][INFO    ][32377] Executing state glusterfs.peered for 10.167.4.141
2017-10-24 10:59:42,581 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:42,590 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:42,638 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:42,646 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:42,695 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:42,704 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:42,752 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:42,761 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:42,811 [salt.state       ][INFO    ][32377] Peering with localhost is not needed
2017-10-24 10:59:42,811 [salt.state       ][INFO    ][32377] Completed state [10.167.4.141] at time 10:59:42.811269 duration_in_ms=230.419
2017-10-24 10:59:42,814 [salt.state       ][INFO    ][32377] Running state [10.167.4.142] at time 10:59:42.813619
2017-10-24 10:59:42,814 [salt.state       ][INFO    ][32377] Executing state glusterfs.peered for 10.167.4.142
2017-10-24 10:59:42,814 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:42,824 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:42,872 [salt.state       ][INFO    ][32377] Host 10.167.4.142 already peered
2017-10-24 10:59:42,872 [salt.state       ][INFO    ][32377] Completed state [10.167.4.142] at time 10:59:42.872403 duration_in_ms=58.784
2017-10-24 10:59:42,875 [salt.state       ][INFO    ][32377] Running state [10.167.4.143] at time 10:59:42.874547
2017-10-24 10:59:42,875 [salt.state       ][INFO    ][32377] Executing state glusterfs.peered for 10.167.4.143
2017-10-24 10:59:42,875 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:42,883 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:42,930 [salt.state       ][INFO    ][32377] Host 10.167.4.143 already peered
2017-10-24 10:59:42,931 [salt.state       ][INFO    ][32377] Completed state [10.167.4.143] at time 10:59:42.930496 duration_in_ms=55.949
2017-10-24 10:59:42,931 [salt.state       ][INFO    ][32377] Running state [glance] at time 10:59:42.931274
2017-10-24 10:59:42,931 [salt.state       ][INFO    ][32377] Executing state glusterfs.created for glance
2017-10-24 10:59:42,932 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:42,941 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:42,989 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:42,998 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:43,048 [salt.state       ][INFO    ][32377] Volume glance already exists and is started.
2017-10-24 10:59:43,048 [salt.state       ][INFO    ][32377] Completed state [glance] at time 10:59:43.048406 duration_in_ms=117.13
2017-10-24 10:59:43,051 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 10:59:43.051103
2017-10-24 10:59:43,051 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-10-24 10:59:43,052 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-24 10:59:43,099 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:43,099 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 10:59:43.098813 duration_in_ms=47.709
2017-10-24 10:59:43,099 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 10:59:43.099225
2017-10-24 10:59:43,099 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-10-24 10:59:43,100 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-24 10:59:43,145 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:43,146 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 10:59:43.145707 duration_in_ms=46.482
2017-10-24 10:59:43,146 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 10:59:43.146138
2017-10-24 10:59:43,146 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-10-24 10:59:43,147 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-10-24 10:59:43,195 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:43,195 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 10:59:43.195038 duration_in_ms=48.899
2017-10-24 10:59:43,195 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 10:59:43.195462
2017-10-24 10:59:43,196 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-10-24 10:59:43,196 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-24 10:59:43,255 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:43,255 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 10:59:43.255190 duration_in_ms=59.728
2017-10-24 10:59:43,256 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 10:59:43.255635
2017-10-24 10:59:43,256 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-10-24 10:59:43,256 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-24 10:59:43,302 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:43,302 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 10:59:43.302107 duration_in_ms=46.472
2017-10-24 10:59:43,303 [salt.state       ][INFO    ][32377] Running state [glance] at time 10:59:43.303013
2017-10-24 10:59:43,303 [salt.state       ][INFO    ][32377] Executing state glusterfs.started for glance
2017-10-24 10:59:43,304 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:43,312 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:43,357 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:43,365 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:43,415 [salt.state       ][INFO    ][32377] Volume glance is already started
2017-10-24 10:59:43,415 [salt.state       ][INFO    ][32377] Completed state [glance] at time 10:59:43.414872 duration_in_ms=111.859
2017-10-24 10:59:43,416 [salt.state       ][INFO    ][32377] Running state [keystone-keys] at time 10:59:43.415599
2017-10-24 10:59:43,416 [salt.state       ][INFO    ][32377] Executing state glusterfs.created for keystone-keys
2017-10-24 10:59:43,416 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:43,424 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:43,470 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:43,478 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:43,528 [salt.state       ][INFO    ][32377] Volume keystone-keys already exists and is started.
2017-10-24 10:59:43,528 [salt.state       ][INFO    ][32377] Completed state [keystone-keys] at time 10:59:43.527817 duration_in_ms=112.218
2017-10-24 10:59:43,531 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 10:59:43.530551
2017-10-24 10:59:43,531 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-10-24 10:59:43,531 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-24 10:59:43,580 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:43,580 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 10:59:43.580305 duration_in_ms=49.753
2017-10-24 10:59:43,581 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:59:43.580718
2017-10-24 10:59:43,581 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-24 10:59:43,581 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-24 10:59:43,630 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:43,631 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:59:43.630514 duration_in_ms=49.796
2017-10-24 10:59:43,631 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:59:43.630921
2017-10-24 10:59:43,631 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-24 10:59:43,632 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-24 10:59:43,679 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:43,679 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:59:43.679389 duration_in_ms=48.468
2017-10-24 10:59:43,680 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 10:59:43.679899
2017-10-24 10:59:43,680 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-10-24 10:59:43,681 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-24 10:59:43,728 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:43,728 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 10:59:43.727835 duration_in_ms=47.936
2017-10-24 10:59:43,728 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 10:59:43.728305
2017-10-24 10:59:43,729 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-10-24 10:59:43,729 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-24 10:59:43,775 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:43,776 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 10:59:43.775465 duration_in_ms=47.159
2017-10-24 10:59:43,776 [salt.state       ][INFO    ][32377] Running state [keystone-keys] at time 10:59:43.776381
2017-10-24 10:59:43,777 [salt.state       ][INFO    ][32377] Executing state glusterfs.started for keystone-keys
2017-10-24 10:59:43,777 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:43,784 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:43,830 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:43,837 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:43,887 [salt.state       ][INFO    ][32377] Volume keystone-keys is already started
2017-10-24 10:59:43,887 [salt.state       ][INFO    ][32377] Completed state [keystone-keys] at time 10:59:43.887015 duration_in_ms=110.633
2017-10-24 10:59:43,888 [salt.state       ][INFO    ][32377] Running state [nova_instances] at time 10:59:43.887767
2017-10-24 10:59:43,888 [salt.state       ][INFO    ][32377] Executing state glusterfs.created for nova_instances
2017-10-24 10:59:43,888 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:43,897 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:43,944 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:43,952 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:44,003 [salt.state       ][INFO    ][32377] Volume nova_instances already exists and is started.
2017-10-24 10:59:44,003 [salt.state       ][INFO    ][32377] Completed state [nova_instances] at time 10:59:44.002814 duration_in_ms=115.045
2017-10-24 10:59:44,006 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 10:59:44.005747
2017-10-24 10:59:44,006 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-10-24 10:59:44,006 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-24 10:59:44,054 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:44,054 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 10:59:44.054075 duration_in_ms=48.328
2017-10-24 10:59:44,055 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 10:59:44.054563
2017-10-24 10:59:44,055 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-10-24 10:59:44,055 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-10-24 10:59:44,103 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:44,103 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 10:59:44.102825 duration_in_ms=48.262
2017-10-24 10:59:44,103 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 10:59:44.103232
2017-10-24 10:59:44,103 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-10-24 10:59:44,104 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-10-24 10:59:44,152 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:44,152 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 10:59:44.152389 duration_in_ms=49.155
2017-10-24 10:59:44,153 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 10:59:44.152834
2017-10-24 10:59:44,153 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-10-24 10:59:44,153 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-10-24 10:59:44,201 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:44,201 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 10:59:44.200853 duration_in_ms=48.018
2017-10-24 10:59:44,201 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 10:59:44.201309
2017-10-24 10:59:44,202 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-10-24 10:59:44,202 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-24 10:59:44,249 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:44,249 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 10:59:44.249424 duration_in_ms=48.114
2017-10-24 10:59:44,250 [salt.state       ][INFO    ][32377] Running state [nova_instances] at time 10:59:44.250347
2017-10-24 10:59:44,251 [salt.state       ][INFO    ][32377] Executing state glusterfs.started for nova_instances
2017-10-24 10:59:44,251 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:44,258 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:44,304 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:44,312 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:44,361 [salt.state       ][INFO    ][32377] Volume nova_instances is already started
2017-10-24 10:59:44,361 [salt.state       ][INFO    ][32377] Completed state [nova_instances] at time 10:59:44.360929 duration_in_ms=110.581
2017-10-24 10:59:44,362 [salt.state       ][INFO    ][32377] Running state [keystone-credential-keys] at time 10:59:44.361819
2017-10-24 10:59:44,362 [salt.state       ][INFO    ][32377] Executing state glusterfs.created for keystone-credential-keys
2017-10-24 10:59:44,362 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:44,370 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:44,415 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:44,423 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:44,474 [salt.state       ][INFO    ][32377] Volume keystone-credential-keys already exists and is started.
2017-10-24 10:59:44,474 [salt.state       ][INFO    ][32377] Completed state [keystone-credential-keys] at time 10:59:44.474127 duration_in_ms=112.308
2017-10-24 10:59:44,477 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 10:59:44.477044
2017-10-24 10:59:44,477 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-10-24 10:59:44,478 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-24 10:59:44,524 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:44,524 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 10:59:44.524453 duration_in_ms=47.409
2017-10-24 10:59:44,525 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:59:44.524886
2017-10-24 10:59:44,525 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-24 10:59:44,526 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-24 10:59:44,574 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:44,574 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:59:44.574090 duration_in_ms=49.204
2017-10-24 10:59:44,575 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 10:59:44.574546
2017-10-24 10:59:44,575 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-10-24 10:59:44,575 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-24 10:59:44,622 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:44,622 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 10:59:44.621955 duration_in_ms=47.409
2017-10-24 10:59:44,622 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:59:44.622377
2017-10-24 10:59:44,623 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-24 10:59:44,623 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-24 10:59:44,671 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:44,671 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:59:44.671432 duration_in_ms=49.054
2017-10-24 10:59:44,672 [salt.state       ][INFO    ][32377] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 10:59:44.671850
2017-10-24 10:59:44,672 [salt.state       ][INFO    ][32377] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-10-24 10:59:44,672 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-24 10:59:44,721 [salt.state       ][INFO    ][32377] unless execution succeeded
2017-10-24 10:59:44,721 [salt.state       ][INFO    ][32377] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 10:59:44.720916 duration_in_ms=49.065
2017-10-24 10:59:44,722 [salt.state       ][INFO    ][32377] Running state [keystone-credential-keys] at time 10:59:44.721844
2017-10-24 10:59:44,722 [salt.state       ][INFO    ][32377] Executing state glusterfs.started for keystone-credential-keys
2017-10-24 10:59:44,722 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:44,731 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:44,778 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --version' in directory '/root'
2017-10-24 10:59:44,786 [salt.loaded.int.module.cmdmod][INFO    ][32377] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 10:59:44,837 [salt.state       ][INFO    ][32377] Volume keystone-credential-keys is already started
2017-10-24 10:59:44,837 [salt.state       ][INFO    ][32377] Completed state [keystone-credential-keys] at time 10:59:44.836818 duration_in_ms=114.974
2017-10-24 10:59:44,838 [salt.minion      ][INFO    ][32377] Returning information for job: 20171024105941273556
2017-10-24 10:59:46,035 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command test.ping with jid 20171024105946029547
2017-10-24 10:59:46,050 [salt.minion      ][INFO    ][32759] Starting a new job with PID 32759
2017-10-24 10:59:46,074 [salt.minion      ][INFO    ][32759] Returning information for job: 20171024105946029547
2017-10-24 10:59:46,741 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command cmd.run with jid 20171024105946731950
2017-10-24 10:59:46,755 [salt.minion      ][INFO    ][32764] Starting a new job with PID 32764
2017-10-24 10:59:46,759 [salt.loaded.int.module.cmdmod][INFO    ][32764] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-10-24 10:59:46,869 [salt.minion      ][INFO    ][32764] Returning information for job: 20171024105946731950
2017-10-24 11:10:50,646 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command test.ping with jid 20171024111050641597
2017-10-24 11:10:50,670 [salt.minion      ][INFO    ][427] Starting a new job with PID 427
2017-10-24 11:10:50,707 [salt.minion      ][INFO    ][427] Returning information for job: 20171024111050641597
2017-10-24 11:11:22,743 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command state.sls with jid 20171024111122735578
2017-10-24 11:11:22,759 [salt.minion      ][INFO    ][436] Starting a new job with PID 436
2017-10-24 11:11:23,179 [salt.state       ][INFO    ][436] Loading fresh modules for state activity
2017-10-24 11:11:23,204 [salt.fileclient  ][INFO    ][436] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-10-24 11:11:23,222 [salt.fileclient  ][INFO    ][436] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-10-24 11:11:23,245 [salt.fileclient  ][INFO    ][436] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-24 11:11:23,519 [salt.state       ][INFO    ][436] Running state [lsof] at time 11:11:23.518474
2017-10-24 11:11:23,519 [salt.state       ][INFO    ][436] Executing state pkg.installed for lsof
2017-10-24 11:11:23,519 [salt.loaded.int.module.cmdmod][INFO    ][436] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-24 11:11:23,757 [salt.state       ][INFO    ][436] Package lsof is already installed
2017-10-24 11:11:23,757 [salt.state       ][INFO    ][436] Completed state [lsof] at time 11:11:23.756974 duration_in_ms=238.499
2017-10-24 11:11:23,757 [salt.state       ][INFO    ][436] Running state [keepalived] at time 11:11:23.757164
2017-10-24 11:11:23,757 [salt.state       ][INFO    ][436] Executing state pkg.installed for keepalived
2017-10-24 11:11:23,760 [salt.state       ][INFO    ][436] Package keepalived is already installed
2017-10-24 11:11:23,760 [salt.state       ][INFO    ][436] Completed state [keepalived] at time 11:11:23.759984 duration_in_ms=2.82
2017-10-24 11:11:23,761 [salt.state       ][INFO    ][436] Running state [/etc/keepalived/keepalived.conf] at time 11:11:23.761311
2017-10-24 11:11:23,761 [salt.state       ][INFO    ][436] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-10-24 11:11:23,784 [salt.fileclient  ][INFO    ][436] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-10-24 11:11:23,811 [salt.fileclient  ][INFO    ][436] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-24 11:11:23,816 [salt.state       ][INFO    ][436] File /etc/keepalived/keepalived.conf is in the correct state
2017-10-24 11:11:23,816 [salt.state       ][INFO    ][436] Completed state [/etc/keepalived/keepalived.conf] at time 11:11:23.815737 duration_in_ms=54.425
2017-10-24 11:11:23,816 [salt.state       ][INFO    ][436] Running state [keepalived] at time 11:11:23.816279
2017-10-24 11:11:23,816 [salt.state       ][INFO    ][436] Executing state service.running for keepalived
2017-10-24 11:11:23,817 [salt.loaded.int.module.cmdmod][INFO    ][436] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-10-24 11:11:23,830 [salt.loaded.int.module.cmdmod][INFO    ][436] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-24 11:11:23,836 [salt.loaded.int.module.cmdmod][INFO    ][436] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-24 11:11:23,843 [salt.state       ][INFO    ][436] The service keepalived is already running
2017-10-24 11:11:23,843 [salt.state       ][INFO    ][436] Completed state [keepalived] at time 11:11:23.843282 duration_in_ms=27.003
2017-10-24 11:11:23,844 [salt.minion      ][INFO    ][436] Returning information for job: 20171024111122735578
2017-10-24 11:11:24,647 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command pillar.get with jid 20171024111124644382
2017-10-24 11:11:24,670 [salt.minion      ][INFO    ][460] Starting a new job with PID 460
2017-10-24 11:11:24,676 [salt.minion      ][INFO    ][460] Returning information for job: 20171024111124644382
2017-10-24 11:12:01,542 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command state.sls with jid 20171024111201534992
2017-10-24 11:12:01,555 [salt.minion      ][INFO    ][478] Starting a new job with PID 478
2017-10-24 11:12:01,973 [salt.state       ][INFO    ][478] Loading fresh modules for state activity
2017-10-24 11:12:01,995 [salt.fileclient  ][INFO    ][478] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-24 11:12:02,015 [salt.fileclient  ][INFO    ][478] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-24 11:12:02,289 [salt.state       ][INFO    ][478] Running state [attr] at time 11:12:02.288615
2017-10-24 11:12:02,289 [salt.state       ][INFO    ][478] Executing state pkg.installed for attr
2017-10-24 11:12:02,289 [salt.loaded.int.module.cmdmod][INFO    ][478] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-24 11:12:02,521 [salt.state       ][INFO    ][478] Package attr is already installed
2017-10-24 11:12:02,521 [salt.state       ][INFO    ][478] Completed state [attr] at time 11:12:02.520906 duration_in_ms=232.291
2017-10-24 11:12:02,521 [salt.state       ][INFO    ][478] Running state [glusterfs-server] at time 11:12:02.521174
2017-10-24 11:12:02,521 [salt.state       ][INFO    ][478] Executing state pkg.installed for glusterfs-server
2017-10-24 11:12:02,524 [salt.state       ][INFO    ][478] Package glusterfs-server is already installed
2017-10-24 11:12:02,524 [salt.state       ][INFO    ][478] Completed state [glusterfs-server] at time 11:12:02.524193 duration_in_ms=3.019
2017-10-24 11:12:02,524 [salt.state       ][INFO    ][478] Running state [bc] at time 11:12:02.524396
2017-10-24 11:12:02,525 [salt.state       ][INFO    ][478] Executing state pkg.installed for bc
2017-10-24 11:12:02,527 [salt.state       ][INFO    ][478] Package bc is already installed
2017-10-24 11:12:02,527 [salt.state       ][INFO    ][478] Completed state [bc] at time 11:12:02.527358 duration_in_ms=2.962
2017-10-24 11:12:02,528 [salt.state       ][INFO    ][478] Running state [glusterfs-server] at time 11:12:02.528078
2017-10-24 11:12:02,528 [salt.state       ][INFO    ][478] Executing state service.running for glusterfs-server
2017-10-24 11:12:02,529 [salt.loaded.int.module.cmdmod][INFO    ][478] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-24 11:12:02,546 [salt.loaded.int.module.cmdmod][INFO    ][478] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-24 11:12:02,554 [salt.loaded.int.module.cmdmod][INFO    ][478] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-24 11:12:02,564 [salt.state       ][INFO    ][478] The service glusterfs-server is already running
2017-10-24 11:12:02,565 [salt.state       ][INFO    ][478] Completed state [glusterfs-server] at time 11:12:02.564613 duration_in_ms=36.534
2017-10-24 11:12:02,566 [salt.state       ][INFO    ][478] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 11:12:02.566047
2017-10-24 11:12:02,566 [salt.state       ][INFO    ][478] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-24 11:12:02,576 [salt.state       ][INFO    ][478] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-24 11:12:02,576 [salt.state       ][INFO    ][478] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 11:12:02.576103 duration_in_ms=10.056
2017-10-24 11:12:02,576 [salt.state       ][INFO    ][478] Running state [/srv/glusterfs/glance] at time 11:12:02.576332
2017-10-24 11:12:02,577 [salt.state       ][INFO    ][478] Executing state file.directory for /srv/glusterfs/glance
2017-10-24 11:12:02,577 [salt.state       ][INFO    ][478] Directory /srv/glusterfs/glance is in the correct state
2017-10-24 11:12:02,577 [salt.state       ][INFO    ][478] Completed state [/srv/glusterfs/glance] at time 11:12:02.577089 duration_in_ms=0.757
2017-10-24 11:12:02,577 [salt.state       ][INFO    ][478] Running state [/srv/glusterfs/keystone-keys] at time 11:12:02.577291
2017-10-24 11:12:02,578 [salt.state       ][INFO    ][478] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-24 11:12:02,578 [salt.state       ][INFO    ][478] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-24 11:12:02,578 [salt.state       ][INFO    ][478] Completed state [/srv/glusterfs/keystone-keys] at time 11:12:02.578070 duration_in_ms=0.779
2017-10-24 11:12:02,578 [salt.state       ][INFO    ][478] Running state [/srv/glusterfs/nova_instances] at time 11:12:02.578269
2017-10-24 11:12:02,578 [salt.state       ][INFO    ][478] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-24 11:12:02,579 [salt.state       ][INFO    ][478] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-24 11:12:02,579 [salt.state       ][INFO    ][478] Completed state [/srv/glusterfs/nova_instances] at time 11:12:02.579014 duration_in_ms=0.745
2017-10-24 11:12:02,579 [salt.state       ][INFO    ][478] Running state [/srv/glusterfs/keystone-credential-keys] at time 11:12:02.579215
2017-10-24 11:12:02,579 [salt.state       ][INFO    ][478] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-24 11:12:02,580 [salt.state       ][INFO    ][478] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-24 11:12:02,580 [salt.state       ][INFO    ][478] Completed state [/srv/glusterfs/keystone-credential-keys] at time 11:12:02.579938 duration_in_ms=0.723
2017-10-24 11:12:02,581 [salt.minion      ][INFO    ][478] Returning information for job: 20171024111201534992
2017-10-24 11:12:03,890 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command test.ping with jid 20171024111203884302
2017-10-24 11:12:03,906 [salt.minion      ][INFO    ][501] Starting a new job with PID 501
2017-10-24 11:12:03,933 [salt.minion      ][INFO    ][501] Returning information for job: 20171024111203884302
2017-10-24 11:12:13,058 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command state.sls with jid 20171024111213048263
2017-10-24 11:12:13,074 [salt.minion      ][INFO    ][506] Starting a new job with PID 506
2017-10-24 11:12:13,495 [salt.state       ][INFO    ][506] Loading fresh modules for state activity
2017-10-24 11:12:13,518 [salt.fileclient  ][INFO    ][506] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-10-24 11:12:13,550 [salt.fileclient  ][INFO    ][506] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-24 11:12:13,616 [salt.fileclient  ][INFO    ][506] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-24 11:12:13,636 [salt.fileclient  ][INFO    ][506] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-24 11:12:13,896 [salt.state       ][INFO    ][506] Running state [attr] at time 11:12:13.896373
2017-10-24 11:12:13,897 [salt.state       ][INFO    ][506] Executing state pkg.installed for attr
2017-10-24 11:12:13,897 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-24 11:12:14,166 [salt.state       ][INFO    ][506] Package attr is already installed
2017-10-24 11:12:14,166 [salt.state       ][INFO    ][506] Completed state [attr] at time 11:12:14.166238 duration_in_ms=269.865
2017-10-24 11:12:14,166 [salt.state       ][INFO    ][506] Running state [glusterfs-server] at time 11:12:14.166453
2017-10-24 11:12:14,167 [salt.state       ][INFO    ][506] Executing state pkg.installed for glusterfs-server
2017-10-24 11:12:14,169 [salt.state       ][INFO    ][506] Package glusterfs-server is already installed
2017-10-24 11:12:14,169 [salt.state       ][INFO    ][506] Completed state [glusterfs-server] at time 11:12:14.169299 duration_in_ms=2.846
2017-10-24 11:12:14,169 [salt.state       ][INFO    ][506] Running state [bc] at time 11:12:14.169456
2017-10-24 11:12:14,170 [salt.state       ][INFO    ][506] Executing state pkg.installed for bc
2017-10-24 11:12:14,172 [salt.state       ][INFO    ][506] Package bc is already installed
2017-10-24 11:12:14,172 [salt.state       ][INFO    ][506] Completed state [bc] at time 11:12:14.172235 duration_in_ms=2.778
2017-10-24 11:12:14,173 [salt.state       ][INFO    ][506] Running state [glusterfs-server] at time 11:12:14.173006
2017-10-24 11:12:14,173 [salt.state       ][INFO    ][506] Executing state service.running for glusterfs-server
2017-10-24 11:12:14,174 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-24 11:12:14,190 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-24 11:12:14,198 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-24 11:12:14,207 [salt.state       ][INFO    ][506] The service glusterfs-server is already running
2017-10-24 11:12:14,208 [salt.state       ][INFO    ][506] Completed state [glusterfs-server] at time 11:12:14.207476 duration_in_ms=34.47
2017-10-24 11:12:14,209 [salt.state       ][INFO    ][506] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 11:12:14.208856
2017-10-24 11:12:14,209 [salt.state       ][INFO    ][506] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-24 11:12:14,216 [salt.state       ][INFO    ][506] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-24 11:12:14,216 [salt.state       ][INFO    ][506] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 11:12:14.216308 duration_in_ms=7.452
2017-10-24 11:12:14,216 [salt.state       ][INFO    ][506] Running state [/srv/glusterfs/glance] at time 11:12:14.216478
2017-10-24 11:12:14,217 [salt.state       ][INFO    ][506] Executing state file.directory for /srv/glusterfs/glance
2017-10-24 11:12:14,217 [salt.state       ][INFO    ][506] Directory /srv/glusterfs/glance is in the correct state
2017-10-24 11:12:14,217 [salt.state       ][INFO    ][506] Completed state [/srv/glusterfs/glance] at time 11:12:14.217119 duration_in_ms=0.642
2017-10-24 11:12:14,217 [salt.state       ][INFO    ][506] Running state [/srv/glusterfs/keystone-keys] at time 11:12:14.217257
2017-10-24 11:12:14,217 [salt.state       ][INFO    ][506] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-24 11:12:14,218 [salt.state       ][INFO    ][506] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-24 11:12:14,218 [salt.state       ][INFO    ][506] Completed state [/srv/glusterfs/keystone-keys] at time 11:12:14.217876 duration_in_ms=0.619
2017-10-24 11:12:14,218 [salt.state       ][INFO    ][506] Running state [/srv/glusterfs/nova_instances] at time 11:12:14.218012
2017-10-24 11:12:14,218 [salt.state       ][INFO    ][506] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-24 11:12:14,218 [salt.state       ][INFO    ][506] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-24 11:12:14,219 [salt.state       ][INFO    ][506] Completed state [/srv/glusterfs/nova_instances] at time 11:12:14.218609 duration_in_ms=0.596
2017-10-24 11:12:14,219 [salt.state       ][INFO    ][506] Running state [/srv/glusterfs/keystone-credential-keys] at time 11:12:14.218748
2017-10-24 11:12:14,219 [salt.state       ][INFO    ][506] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-24 11:12:14,219 [salt.state       ][INFO    ][506] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-24 11:12:14,219 [salt.state       ][INFO    ][506] Completed state [/srv/glusterfs/keystone-credential-keys] at time 11:12:14.219313 duration_in_ms=0.565
2017-10-24 11:12:14,257 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command ['git', '--version'] in directory '/root'
2017-10-24 11:12:14,352 [salt.state       ][INFO    ][506] Running state [sleep 5] at time 11:12:14.352077
2017-10-24 11:12:14,352 [salt.state       ][INFO    ][506] Executing state cmd.wait for sleep 5
2017-10-24 11:12:14,353 [salt.state       ][INFO    ][506] No changes made for sleep 5
2017-10-24 11:12:14,353 [salt.state       ][INFO    ][506] Completed state [sleep 5] at time 11:12:14.352715 duration_in_ms=0.639
2017-10-24 11:12:14,353 [salt.state       ][INFO    ][506] Running state [10.167.4.141] at time 11:12:14.353120
2017-10-24 11:12:14,353 [salt.state       ][INFO    ][506] Executing state glusterfs.peered for 10.167.4.141
2017-10-24 11:12:14,354 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:14,362 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:14,410 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:14,418 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:14,466 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:14,474 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:14,523 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:14,531 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:14,576 [salt.state       ][INFO    ][506] Peering with localhost is not needed
2017-10-24 11:12:14,576 [salt.state       ][INFO    ][506] Completed state [10.167.4.141] at time 11:12:14.575811 duration_in_ms=222.689
2017-10-24 11:12:14,578 [salt.state       ][INFO    ][506] Running state [10.167.4.142] at time 11:12:14.578151
2017-10-24 11:12:14,578 [salt.state       ][INFO    ][506] Executing state glusterfs.peered for 10.167.4.142
2017-10-24 11:12:14,579 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:14,587 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:14,633 [salt.state       ][INFO    ][506] Host 10.167.4.142 already peered
2017-10-24 11:12:14,633 [salt.state       ][INFO    ][506] Completed state [10.167.4.142] at time 11:12:14.632941 duration_in_ms=54.79
2017-10-24 11:12:14,635 [salt.state       ][INFO    ][506] Running state [10.167.4.143] at time 11:12:14.635015
2017-10-24 11:12:14,635 [salt.state       ][INFO    ][506] Executing state glusterfs.peered for 10.167.4.143
2017-10-24 11:12:14,636 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:14,644 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:14,691 [salt.state       ][INFO    ][506] Host 10.167.4.143 already peered
2017-10-24 11:12:14,691 [salt.state       ][INFO    ][506] Completed state [10.167.4.143] at time 11:12:14.690949 duration_in_ms=55.932
2017-10-24 11:12:14,692 [salt.state       ][INFO    ][506] Running state [glance] at time 11:12:14.691652
2017-10-24 11:12:14,692 [salt.state       ][INFO    ][506] Executing state glusterfs.created for glance
2017-10-24 11:12:14,692 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:14,700 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:14,747 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:14,754 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:14,804 [salt.state       ][INFO    ][506] Volume glance already exists and is started.
2017-10-24 11:12:14,805 [salt.state       ][INFO    ][506] Completed state [glance] at time 11:12:14.804545 duration_in_ms=112.891
2017-10-24 11:12:14,807 [salt.state       ][INFO    ][506] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 11:12:14.807152
2017-10-24 11:12:14,807 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-10-24 11:12:14,808 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-24 11:12:14,856 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:14,856 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 11:12:14.856183 duration_in_ms=49.029
2017-10-24 11:12:14,857 [salt.state       ][INFO    ][506] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 11:12:14.856619
2017-10-24 11:12:14,857 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-10-24 11:12:14,857 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-24 11:12:14,905 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:14,906 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 11:12:14.905532 duration_in_ms=48.912
2017-10-24 11:12:14,906 [salt.state       ][INFO    ][506] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 11:12:14.906002
2017-10-24 11:12:14,906 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-10-24 11:12:14,907 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-10-24 11:12:14,958 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:14,958 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 11:12:14.958075 duration_in_ms=52.073
2017-10-24 11:12:14,959 [salt.state       ][INFO    ][506] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 11:12:14.958528
2017-10-24 11:12:14,959 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-10-24 11:12:14,959 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-24 11:12:15,010 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:15,010 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 11:12:15.009920 duration_in_ms=51.392
2017-10-24 11:12:15,010 [salt.state       ][INFO    ][506] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 11:12:15.010350
2017-10-24 11:12:15,011 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-10-24 11:12:15,011 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-24 11:12:15,059 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:15,059 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 11:12:15.058701 duration_in_ms=48.351
2017-10-24 11:12:15,060 [salt.state       ][INFO    ][506] Running state [glance] at time 11:12:15.059593
2017-10-24 11:12:15,060 [salt.state       ][INFO    ][506] Executing state glusterfs.started for glance
2017-10-24 11:12:15,060 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:15,068 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:15,116 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:15,125 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:15,175 [salt.state       ][INFO    ][506] Volume glance is already started
2017-10-24 11:12:15,176 [salt.state       ][INFO    ][506] Completed state [glance] at time 11:12:15.175586 duration_in_ms=115.992
2017-10-24 11:12:15,176 [salt.state       ][INFO    ][506] Running state [keystone-keys] at time 11:12:15.176320
2017-10-24 11:12:15,177 [salt.state       ][INFO    ][506] Executing state glusterfs.created for keystone-keys
2017-10-24 11:12:15,177 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:15,185 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:15,232 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:15,240 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:15,290 [salt.state       ][INFO    ][506] Volume keystone-keys already exists and is started.
2017-10-24 11:12:15,290 [salt.state       ][INFO    ][506] Completed state [keystone-keys] at time 11:12:15.290408 duration_in_ms=114.088
2017-10-24 11:12:15,293 [salt.state       ][INFO    ][506] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 11:12:15.293226
2017-10-24 11:12:15,293 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-10-24 11:12:15,294 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-24 11:12:15,343 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:15,343 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 11:12:15.343460 duration_in_ms=50.233
2017-10-24 11:12:15,344 [salt.state       ][INFO    ][506] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 11:12:15.343890
2017-10-24 11:12:15,344 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-24 11:12:15,344 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-24 11:12:15,391 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:15,391 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 11:12:15.391050 duration_in_ms=47.159
2017-10-24 11:12:15,391 [salt.state       ][INFO    ][506] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 11:12:15.391453
2017-10-24 11:12:15,392 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-24 11:12:15,392 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-24 11:12:15,439 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:15,439 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 11:12:15.438955 duration_in_ms=47.5
2017-10-24 11:12:15,439 [salt.state       ][INFO    ][506] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 11:12:15.439401
2017-10-24 11:12:15,440 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-10-24 11:12:15,440 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-24 11:12:15,488 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:15,488 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 11:12:15.487682 duration_in_ms=48.281
2017-10-24 11:12:15,488 [salt.state       ][INFO    ][506] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 11:12:15.488107
2017-10-24 11:12:15,488 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-10-24 11:12:15,489 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-24 11:12:15,534 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:15,534 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 11:12:15.533944 duration_in_ms=45.836
2017-10-24 11:12:15,535 [salt.state       ][INFO    ][506] Running state [keystone-keys] at time 11:12:15.534873
2017-10-24 11:12:15,535 [salt.state       ][INFO    ][506] Executing state glusterfs.started for keystone-keys
2017-10-24 11:12:15,535 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:15,544 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:15,590 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:15,598 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:15,649 [salt.state       ][INFO    ][506] Volume keystone-keys is already started
2017-10-24 11:12:15,650 [salt.state       ][INFO    ][506] Completed state [keystone-keys] at time 11:12:15.649658 duration_in_ms=114.784
2017-10-24 11:12:15,650 [salt.state       ][INFO    ][506] Running state [nova_instances] at time 11:12:15.650400
2017-10-24 11:12:15,651 [salt.state       ][INFO    ][506] Executing state glusterfs.created for nova_instances
2017-10-24 11:12:15,651 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:15,659 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:15,707 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:15,714 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:15,762 [salt.state       ][INFO    ][506] Volume nova_instances already exists and is started.
2017-10-24 11:12:15,762 [salt.state       ][INFO    ][506] Completed state [nova_instances] at time 11:12:15.761983 duration_in_ms=111.582
2017-10-24 11:12:15,765 [salt.state       ][INFO    ][506] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 11:12:15.764845
2017-10-24 11:12:15,765 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-10-24 11:12:15,766 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-24 11:12:15,813 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:15,813 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 11:12:15.812758 duration_in_ms=47.913
2017-10-24 11:12:15,813 [salt.state       ][INFO    ][506] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 11:12:15.813208
2017-10-24 11:12:15,813 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-10-24 11:12:15,814 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-10-24 11:12:15,861 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:15,862 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 11:12:15.861468 duration_in_ms=48.259
2017-10-24 11:12:15,862 [salt.state       ][INFO    ][506] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 11:12:15.861915
2017-10-24 11:12:15,862 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-10-24 11:12:15,863 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-10-24 11:12:15,910 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:15,910 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 11:12:15.909899 duration_in_ms=47.984
2017-10-24 11:12:15,910 [salt.state       ][INFO    ][506] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 11:12:15.910290
2017-10-24 11:12:15,911 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-10-24 11:12:15,911 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-10-24 11:12:15,958 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:15,958 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 11:12:15.958193 duration_in_ms=47.902
2017-10-24 11:12:15,959 [salt.state       ][INFO    ][506] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 11:12:15.958610
2017-10-24 11:12:15,959 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-10-24 11:12:15,959 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-24 11:12:16,006 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:16,006 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 11:12:16.005866 duration_in_ms=47.256
2017-10-24 11:12:16,007 [salt.state       ][INFO    ][506] Running state [nova_instances] at time 11:12:16.006757
2017-10-24 11:12:16,007 [salt.state       ][INFO    ][506] Executing state glusterfs.started for nova_instances
2017-10-24 11:12:16,007 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:16,014 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:16,059 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:16,067 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:16,117 [salt.state       ][INFO    ][506] Volume nova_instances is already started
2017-10-24 11:12:16,117 [salt.state       ][INFO    ][506] Completed state [nova_instances] at time 11:12:16.117108 duration_in_ms=110.351
2017-10-24 11:12:16,118 [salt.state       ][INFO    ][506] Running state [keystone-credential-keys] at time 11:12:16.118019
2017-10-24 11:12:16,118 [salt.state       ][INFO    ][506] Executing state glusterfs.created for keystone-credential-keys
2017-10-24 11:12:16,119 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:16,126 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:16,171 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:16,179 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:16,228 [salt.state       ][INFO    ][506] Volume keystone-credential-keys already exists and is started.
2017-10-24 11:12:16,229 [salt.state       ][INFO    ][506] Completed state [keystone-credential-keys] at time 11:12:16.228638 duration_in_ms=110.619
2017-10-24 11:12:16,235 [salt.state       ][INFO    ][506] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 11:12:16.234734
2017-10-24 11:12:16,236 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-10-24 11:12:16,237 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-24 11:12:16,285 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:16,285 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 11:12:16.285300 duration_in_ms=50.576
2017-10-24 11:12:16,286 [salt.state       ][INFO    ][506] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 11:12:16.285783
2017-10-24 11:12:16,286 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-24 11:12:16,286 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-24 11:12:16,334 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:16,334 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 11:12:16.334175 duration_in_ms=48.39
2017-10-24 11:12:16,335 [salt.state       ][INFO    ][506] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 11:12:16.334607
2017-10-24 11:12:16,335 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-10-24 11:12:16,335 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-24 11:12:16,381 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:16,381 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 11:12:16.380960 duration_in_ms=46.353
2017-10-24 11:12:16,381 [salt.state       ][INFO    ][506] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 11:12:16.381404
2017-10-24 11:12:16,382 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-24 11:12:16,382 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-24 11:12:16,429 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:16,429 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 11:12:16.428840 duration_in_ms=47.436
2017-10-24 11:12:16,429 [salt.state       ][INFO    ][506] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 11:12:16.429240
2017-10-24 11:12:16,429 [salt.state       ][INFO    ][506] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-10-24 11:12:16,430 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-24 11:12:16,477 [salt.state       ][INFO    ][506] unless execution succeeded
2017-10-24 11:12:16,477 [salt.state       ][INFO    ][506] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 11:12:16.476774 duration_in_ms=47.533
2017-10-24 11:12:16,478 [salt.state       ][INFO    ][506] Running state [keystone-credential-keys] at time 11:12:16.477723
2017-10-24 11:12:16,478 [salt.state       ][INFO    ][506] Executing state glusterfs.started for keystone-credential-keys
2017-10-24 11:12:16,478 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:16,486 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:16,532 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --version' in directory '/root'
2017-10-24 11:12:16,540 [salt.loaded.int.module.cmdmod][INFO    ][506] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-24 11:12:16,591 [salt.state       ][INFO    ][506] Volume keystone-credential-keys is already started
2017-10-24 11:12:16,591 [salt.state       ][INFO    ][506] Completed state [keystone-credential-keys] at time 11:12:16.591016 duration_in_ms=113.293
2017-10-24 11:12:16,593 [salt.minion      ][INFO    ][506] Returning information for job: 20171024111213048263
2017-10-24 11:12:17,743 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command test.ping with jid 20171024111217735046
2017-10-24 11:12:17,758 [salt.minion      ][INFO    ][897] Starting a new job with PID 897
2017-10-24 11:12:17,783 [salt.minion      ][INFO    ][897] Returning information for job: 20171024111217735046
2017-10-24 11:12:18,442 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command cmd.run with jid 20171024111218433554
2017-10-24 11:12:18,456 [salt.minion      ][INFO    ][902] Starting a new job with PID 902
2017-10-24 11:12:18,460 [salt.loaded.int.module.cmdmod][INFO    ][902] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-10-24 11:12:18,565 [salt.minion      ][INFO    ][902] Returning information for job: 20171024111218433554
2017-10-24 11:25:56,159 [salt.minion      ][INFO    ][23720] User sudo_ubuntu Executing command cp.push_dir with jid 20171024112556151966
2017-10-24 11:25:56,188 [salt.minion      ][INFO    ][1084] Starting a new job with PID 1084
