2017-11-06 04:35:42,474 [salt.cli.daemons ][WARNING ][2493] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-06 04:36:11,860 [salt.state       ][ERROR   ][3785] No contents found in top file
2017-11-06 04:38:03,568 [salt.utils.parsers][WARNING ][2493] Minion received a SIGTERM. Exiting.
2017-11-06 04:40:16,565 [salt.cli.daemons ][WARNING ][3461] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-06 04:43:17,261 [salt.loaded.int.module.cmdmod][ERROR   ][11408] Command 'virsh net-destroy default 2>&1 1>/dev/null; virsh net-undefine default' failed with return code: 1
2017-11-06 04:43:17,261 [salt.loaded.int.module.cmdmod][ERROR   ][11408] stderr: error: failed to get network 'default'
error: Network not found: no network with matching name 'default'
2017-11-06 04:43:17,262 [salt.loaded.int.module.cmdmod][ERROR   ][11408] retcode: 1
2017-11-06 04:43:17,262 [salt.state       ][ERROR   ][11408] {'pid': 16328, 'retcode': 1, 'stderr': "error: failed to get network 'default'\nerror: Network not found: no network with matching name 'default'", 'stdout': ''}
2017-11-06 04:43:37,513 [py.warnings      ][WARNING ][16409] /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-11-06 04:44:47,781 [salt.loaded.int.module.cmdmod][ERROR   ][16409] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-06 04:44:47,781 [salt.loaded.int.module.cmdmod][ERROR   ][16409] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-06 04:44:54,155 [salt.loaded.int.module.cmdmod][ERROR   ][16409] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-06 04:44:54,156 [salt.loaded.int.module.cmdmod][ERROR   ][16409] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-06 04:45:02,081 [salt.loaded.int.module.cmdmod][ERROR   ][16409] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-06 04:45:02,081 [salt.loaded.int.module.cmdmod][ERROR   ][16409] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-06 04:45:11,055 [salt.loaded.int.module.cmdmod][ERROR   ][16409] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-06 04:45:11,056 [salt.loaded.int.module.cmdmod][ERROR   ][16409] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-06 04:45:20,177 [salt.loaded.int.module.cmdmod][ERROR   ][16409] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-06 04:45:20,177 [salt.loaded.int.module.cmdmod][ERROR   ][16409] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-06 04:45:28,535 [salt.loaded.int.module.cmdmod][ERROR   ][16409] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-06 04:45:28,536 [salt.loaded.int.module.cmdmod][ERROR   ][16409] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-06 04:45:35,835 [salt.loaded.int.module.cmdmod][ERROR   ][16409] 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-11-06 04:45:39,399 [salt.state       ][ERROR   ][24368] No contents found in top file
2017-11-06 04:45:48,584 [salt.loaded.int.module.cmdmod][INFO    ][24999] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-06 04:45:48,591 [salt.loaded.int.module.cmdmod][INFO    ][24999] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-06 04:45:48,602 [salt.loaded.int.module.cmdmod][INFO    ][24999] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-11-06 04:45:48,611 [salt.utils.parsers][WARNING ][3461] Minion received a SIGTERM. Exiting.
2017-11-06 04:45:48,864 [salt.cli.daemons ][INFO    ][25116] Setting up the Salt Minion "kvm03.baremetal-mcp-ocata-odl-ha.local"
2017-11-06 04:45:49,384 [salt.minion      ][INFO    ][25116] Creating minion process manager
2017-11-06 04:45:49,384 [salt.cli.daemons ][WARNING ][25116] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-06 04:45:49,384 [salt.cli.daemons ][INFO    ][25116] The Salt Minion is starting up
2017-11-06 04:45:49,385 [salt.minion      ][INFO    ][25116] Minion is starting as user 'root'
2017-11-06 04:45:49,385 [salt.utils.event ][INFO    ][25116] Starting pull socket on /var/run/salt/minion/minion_event_2d6d7b82d1_pull.ipc
2017-11-06 04:45:50,301 [salt.loaded.int.module.cmdmod][INFO    ][25116] Executing command ['date', '+%z'] in directory '/root'
2017-11-06 04:45:50,309 [salt.utils.schedule][INFO    ][25116] Updating job settings for scheduled job: __mine_interval
2017-11-06 04:45:50,311 [salt.minion      ][INFO    ][25116] Added mine.update to scheduler
2017-11-06 04:45:50,501 [salt.minion      ][INFO    ][25116] Minion is ready to receive requests!
2017-11-06 04:45:51,502 [salt.utils.schedule][INFO    ][25116] Running scheduled job: __mine_interval
2017-11-06 04:45:57,752 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command saltutil.find_job with jid 20171106044557744403
2017-11-06 04:45:57,767 [salt.minion      ][INFO    ][25243] Starting a new job with PID 25243
2017-11-06 04:45:57,793 [salt.minion      ][INFO    ][25243] Returning information for job: 20171106044557744403
2017-11-06 04:46:18,807 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command state.sls with jid 20171106044618799578
2017-11-06 04:46:18,822 [salt.minion      ][INFO    ][25251] Starting a new job with PID 25251
2017-11-06 04:46:21,246 [salt.state       ][INFO    ][25251] Loading fresh modules for state activity
2017-11-06 04:46:21,284 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-11-06 04:46:21,301 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-11-06 04:46:21,324 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-06 04:46:21,431 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-11-06 04:46:21,571 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-11-06 04:46:21,597 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-06 04:46:21,733 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-06 04:46:21,752 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-06 04:46:21,875 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-06 04:46:22,008 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-06 04:46:22,021 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-06 04:46:22,035 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-06 04:46:22,043 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-06 04:46:22,065 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-06 04:46:22,080 [py.warnings      ][WARNING ][25251] /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-11-06 04:46:22,081 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'salt-minion --version' in directory '/root'
2017-11-06 04:46:22,315 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-06 04:46:22,333 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-06 04:46:22,345 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-06 04:46:22,357 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-06 04:46:22,400 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-06 04:46:22,487 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-06 04:46:22,579 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-11-06 04:46:22,600 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-06 04:46:22,669 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-06 04:46:22,688 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-06 04:46:22,729 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-06 04:46:22,758 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-06 04:46:22,770 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-06 04:46:22,784 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-06 04:46:22,790 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-06 04:46:22,812 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-06 04:46:22,832 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'salt-minion --version' in directory '/root'
2017-11-06 04:46:23,119 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-06 04:46:23,138 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-06 04:46:23,149 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-06 04:46:23,161 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-06 04:46:23,204 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-06 04:46:23,308 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-06 04:46:23,383 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-11-06 04:46:23,439 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-06 04:46:23,883 [salt.state       ][INFO    ][25251] Running state [salt-minion] at time 04:46:23.882624
2017-11-06 04:46:23,883 [salt.state       ][INFO    ][25251] Executing state pkg.installed for salt-minion
2017-11-06 04:46:23,884 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-06 04:46:24,142 [salt.state       ][INFO    ][25251] Package salt-minion is already installed
2017-11-06 04:46:24,181 [salt.state       ][INFO    ][25251] Completed state [salt-minion] at time 04:46:24.180999 duration_in_ms=298.375
2017-11-06 04:46:24,181 [salt.state       ][INFO    ][25251] Running state [salt_minion_dependency_packages] at time 04:46:24.181311
2017-11-06 04:46:24,182 [salt.state       ][INFO    ][25251] Executing state pkg.installed for salt_minion_dependency_packages
2017-11-06 04:46:24,184 [salt.state       ][INFO    ][25251] All specified packages are already installed
2017-11-06 04:46:24,184 [salt.state       ][INFO    ][25251] Completed state [salt_minion_dependency_packages] at time 04:46:24.184297 duration_in_ms=2.985
2017-11-06 04:46:24,186 [salt.state       ][INFO    ][25251] Running state [/etc/salt/minion.d/minion.conf] at time 04:46:24.185814
2017-11-06 04:46:24,186 [salt.state       ][INFO    ][25251] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-11-06 04:46:24,235 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-11-06 04:46:24,282 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-06 04:46:24,333 [salt.fileclient  ][INFO    ][25251] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-06 04:46:24,366 [salt.state       ][INFO    ][25251] File /etc/salt/minion.d/minion.conf is in the correct state
2017-11-06 04:46:24,366 [salt.state       ][INFO    ][25251] Completed state [/etc/salt/minion.d/minion.conf] at time 04:46:24.365925 duration_in_ms=180.111
2017-11-06 04:46:24,366 [salt.state       ][INFO    ][25251] Running state [/etc/salt/minion.d/_virt.conf] at time 04:46:24.366234
2017-11-06 04:46:24,366 [salt.state       ][INFO    ][25251] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-11-06 04:46:24,495 [salt.state       ][INFO    ][25251] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-11-06 04:46:24,495 [salt.state       ][INFO    ][25251] Completed state [/etc/salt/minion.d/_virt.conf] at time 04:46:24.495095 duration_in_ms=128.861
2017-11-06 04:46:24,497 [salt.state       ][INFO    ][25251] Running state [salt-minion] at time 04:46:24.496653
2017-11-06 04:46:24,497 [salt.state       ][INFO    ][25251] Executing state service.running for salt-minion
2017-11-06 04:46:24,497 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-06 04:46:24,513 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-11-06 04:46:24,521 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-06 04:46:24,527 [salt.state       ][INFO    ][25251] The service salt-minion is already running
2017-11-06 04:46:24,527 [salt.state       ][INFO    ][25251] Completed state [salt-minion] at time 04:46:24.527337 duration_in_ms=30.683
2017-11-06 04:46:24,529 [salt.state       ][INFO    ][25251] Running state [/etc/salt/grains.d] at time 04:46:24.528754
2017-11-06 04:46:24,529 [salt.state       ][INFO    ][25251] Executing state file.directory for /etc/salt/grains.d
2017-11-06 04:46:24,530 [salt.state       ][INFO    ][25251] Directory /etc/salt/grains.d is in the correct state
2017-11-06 04:46:24,530 [salt.state       ][INFO    ][25251] Completed state [/etc/salt/grains.d] at time 04:46:24.529796 duration_in_ms=1.042
2017-11-06 04:46:24,530 [salt.state       ][INFO    ][25251] Running state [/etc/salt/grains] at time 04:46:24.530252
2017-11-06 04:46:24,530 [salt.state       ][INFO    ][25251] Executing state file.managed for /etc/salt/grains
2017-11-06 04:46:24,531 [salt.state       ][INFO    ][25251] File /etc/salt/grains exists with proper permissions. No changes made.
2017-11-06 04:46:24,531 [salt.state       ][INFO    ][25251] Completed state [/etc/salt/grains] at time 04:46:24.530978 duration_in_ms=0.727
2017-11-06 04:46:24,531 [salt.state       ][INFO    ][25251] Running state [/etc/salt/grains.d/placeholder] at time 04:46:24.531291
2017-11-06 04:46:24,532 [salt.state       ][INFO    ][25251] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-11-06 04:46:24,532 [salt.state       ][INFO    ][25251] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-11-06 04:46:24,532 [salt.state       ][INFO    ][25251] Completed state [/etc/salt/grains.d/placeholder] at time 04:46:24.532030 duration_in_ms=0.74
2017-11-06 04:46:24,532 [salt.state       ][INFO    ][25251] Running state [/etc/salt/grains.d/sphinx] at time 04:46:24.532331
2017-11-06 04:46:24,533 [salt.state       ][INFO    ][25251] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-11-06 04:46:24,678 [salt.state       ][INFO    ][25251] File changed:
--- 
+++ 
@@ -49,7 +49,6 @@
               - 10.167.4.143
               - 127.0.0.1
               - 192.168.11.253
-              - 192.168.122.1
         system:
           name: System
           param:

2017-11-06 04:46:24,678 [salt.state       ][INFO    ][25251] Completed state [/etc/salt/grains.d/sphinx] at time 04:46:24.678205 duration_in_ms=145.872
2017-11-06 04:46:24,679 [salt.state       ][INFO    ][25251] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 04:46:24.678981
2017-11-06 04:46:24,679 [salt.state       ][INFO    ][25251] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-06 04:46:24,680 [salt.state       ][INFO    ][25251] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-06 04:46:24,680 [salt.state       ][INFO    ][25251] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 04:46:24.679653 duration_in_ms=0.672
2017-11-06 04:46:24,680 [salt.state       ][INFO    ][25251] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 04:46:24.679814
2017-11-06 04:46:24,680 [salt.state       ][INFO    ][25251] Executing state cmd.mod_watch for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-06 04:46:24,681 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"' in directory '/root'
2017-11-06 04:46:24,767 [salt.state       ][INFO    ][25251] {'pid': 25300, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-06 04:46:24,789 [salt.state       ][INFO    ][25251] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 04:46:24.788850 duration_in_ms=109.034
2017-11-06 04:46:24,789 [salt.state       ][INFO    ][25251] Running state [/etc/salt/grains.d/dns_records] at time 04:46:24.789367
2017-11-06 04:46:24,790 [salt.state       ][INFO    ][25251] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-11-06 04:46:24,840 [salt.state       ][INFO    ][25251] File /etc/salt/grains.d/dns_records is in the correct state
2017-11-06 04:46:24,840 [salt.state       ][INFO    ][25251] Completed state [/etc/salt/grains.d/dns_records] at time 04:46:24.839861 duration_in_ms=50.493
2017-11-06 04:46:24,841 [salt.state       ][INFO    ][25251] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 04:46:24.840592
2017-11-06 04:46:24,841 [salt.state       ][INFO    ][25251] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-06 04:46:24,841 [salt.state       ][INFO    ][25251] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-06 04:46:24,841 [salt.state       ][INFO    ][25251] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 04:46:24.841186 duration_in_ms=0.594
2017-11-06 04:46:24,842 [salt.state       ][INFO    ][25251] Running state [/etc/salt/grains.d/salt] at time 04:46:24.841517
2017-11-06 04:46:24,842 [salt.state       ][INFO    ][25251] Executing state file.managed for /etc/salt/grains.d/salt
2017-11-06 04:46:24,871 [salt.state       ][INFO    ][25251] File /etc/salt/grains.d/salt is in the correct state
2017-11-06 04:46:24,871 [salt.state       ][INFO    ][25251] Completed state [/etc/salt/grains.d/salt] at time 04:46:24.870981 duration_in_ms=29.465
2017-11-06 04:46:24,872 [salt.state       ][INFO    ][25251] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 04:46:24.871553
2017-11-06 04:46:24,872 [salt.state       ][INFO    ][25251] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-06 04:46:24,872 [salt.state       ][INFO    ][25251] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-06 04:46:24,872 [salt.state       ][INFO    ][25251] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 04:46:24.872112 duration_in_ms=0.558
2017-11-06 04:46:24,873 [salt.state       ][INFO    ][25251] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 04:46:24.873355
2017-11-06 04:46:24,874 [salt.state       ][INFO    ][25251] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-06 04:46:24,874 [salt.state       ][INFO    ][25251] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-06 04:46:24,874 [salt.state       ][INFO    ][25251] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 04:46:24.873892 duration_in_ms=0.537
2017-11-06 04:46:24,874 [salt.state       ][INFO    ][25251] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 04:46:24.874031
2017-11-06 04:46:24,874 [salt.state       ][INFO    ][25251] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-06 04:46:24,876 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-11-06 04:46:24,883 [salt.state       ][INFO    ][25251] {'pid': 25302, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-06 04:46:24,884 [salt.state       ][INFO    ][25251] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 04:46:24.883643 duration_in_ms=9.611
2017-11-06 04:46:24,884 [salt.state       ][INFO    ][25251] Running state [mine.update] at time 04:46:24.884283
2017-11-06 04:46:24,885 [salt.state       ][INFO    ][25251] Executing state module.wait for mine.update
2017-11-06 04:46:24,885 [salt.state       ][INFO    ][25251] No changes made for mine.update
2017-11-06 04:46:24,885 [salt.state       ][INFO    ][25251] Completed state [mine.update] at time 04:46:24.884882 duration_in_ms=0.6
2017-11-06 04:46:24,885 [salt.state       ][INFO    ][25251] Running state [mine.update] at time 04:46:24.885030
2017-11-06 04:46:24,885 [salt.state       ][INFO    ][25251] Executing state module.mod_watch for mine.update
2017-11-06 04:46:25,404 [salt.state       ][INFO    ][25251] {'ret': True}
2017-11-06 04:46:25,404 [salt.state       ][INFO    ][25251] Completed state [mine.update] at time 04:46:25.404179 duration_in_ms=519.148
2017-11-06 04:46:25,405 [salt.state       ][INFO    ][25251] Running state [ca-certificates] at time 04:46:25.404519
2017-11-06 04:46:25,405 [salt.state       ][INFO    ][25251] Executing state pkg.installed for ca-certificates
2017-11-06 04:46:25,410 [salt.state       ][INFO    ][25251] Package ca-certificates is already installed
2017-11-06 04:46:25,411 [salt.state       ][INFO    ][25251] Completed state [ca-certificates] at time 04:46:25.410633 duration_in_ms=6.114
2017-11-06 04:46:25,412 [salt.state       ][INFO    ][25251] Running state [update-ca-certificates] at time 04:46:25.411469
2017-11-06 04:46:25,412 [salt.state       ][INFO    ][25251] Executing state cmd.wait for update-ca-certificates
2017-11-06 04:46:25,412 [salt.state       ][INFO    ][25251] No changes made for update-ca-certificates
2017-11-06 04:46:25,412 [salt.state       ][INFO    ][25251] Completed state [update-ca-certificates] at time 04:46:25.412229 duration_in_ms=0.76
2017-11-06 04:46:25,412 [salt.state       ][INFO    ][25251] Running state [libvirt-dev] at time 04:46:25.412452
2017-11-06 04:46:25,413 [salt.state       ][INFO    ][25251] Executing state pkg.installed for libvirt-dev
2017-11-06 04:46:25,418 [salt.state       ][INFO    ][25251] Package libvirt-dev is already installed
2017-11-06 04:46:25,418 [salt.state       ][INFO    ][25251] Completed state [libvirt-dev] at time 04:46:25.417844 duration_in_ms=5.392
2017-11-06 04:46:25,418 [salt.state       ][INFO    ][25251] Running state [pkg-config] at time 04:46:25.418075
2017-11-06 04:46:25,418 [salt.state       ][INFO    ][25251] Executing state pkg.installed for pkg-config
2017-11-06 04:46:25,423 [salt.state       ][INFO    ][25251] Package pkg-config is already installed
2017-11-06 04:46:25,423 [salt.state       ][INFO    ][25251] Completed state [pkg-config] at time 04:46:25.423379 duration_in_ms=5.304
2017-11-06 04:46:25,424 [salt.state       ][INFO    ][25251] Running state [virtng.init] at time 04:46:25.423603
2017-11-06 04:46:25,424 [salt.state       ][INFO    ][25251] Executing state module.run for virtng.init
2017-11-06 04:46:25,477 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command ['git', '--version'] in directory '/root'
2017-11-06 04:46:25,653 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'virsh list --all --name| grep -E "^mdb03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-06 04:46:25,673 [salt.state       ][INFO    ][25251] unless execution succeeded
2017-11-06 04:46:25,673 [salt.state       ][INFO    ][25251] Completed state [virtng.init] at time 04:46:25.672969 duration_in_ms=249.365
2017-11-06 04:46:25,673 [salt.state       ][INFO    ][25251] Running state [virt.set_autostart] at time 04:46:25.673293
2017-11-06 04:46:25,674 [salt.state       ][INFO    ][25251] Executing state module.run for virt.set_autostart
2017-11-06 04:46:25,676 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'virsh list --autostart --name| grep -E "^mdb03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-06 04:46:25,693 [salt.state       ][INFO    ][25251] unless execution succeeded
2017-11-06 04:46:25,693 [salt.state       ][INFO    ][25251] Completed state [virt.set_autostart] at time 04:46:25.692927 duration_in_ms=19.634
2017-11-06 04:46:25,693 [salt.state       ][INFO    ][25251] Running state [virtng.init] at time 04:46:25.693190
2017-11-06 04:46:25,693 [salt.state       ][INFO    ][25251] Executing state module.run for virtng.init
2017-11-06 04:46:25,696 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'virsh list --all --name| grep -E "^dbs03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-06 04:46:25,710 [salt.state       ][INFO    ][25251] unless execution succeeded
2017-11-06 04:46:25,710 [salt.state       ][INFO    ][25251] Completed state [virtng.init] at time 04:46:25.710449 duration_in_ms=17.259
2017-11-06 04:46:25,711 [salt.state       ][INFO    ][25251] Running state [virt.set_autostart] at time 04:46:25.710696
2017-11-06 04:46:25,711 [salt.state       ][INFO    ][25251] Executing state module.run for virt.set_autostart
2017-11-06 04:46:25,713 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'virsh list --autostart --name| grep -E "^dbs03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-06 04:46:25,729 [salt.state       ][INFO    ][25251] unless execution succeeded
2017-11-06 04:46:25,736 [salt.state       ][INFO    ][25251] Completed state [virt.set_autostart] at time 04:46:25.736223 duration_in_ms=25.526
2017-11-06 04:46:25,737 [salt.state       ][INFO    ][25251] Running state [virtng.init] at time 04:46:25.736596
2017-11-06 04:46:25,737 [salt.state       ][INFO    ][25251] Executing state module.run for virtng.init
2017-11-06 04:46:25,739 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'virsh list --all --name| grep -E "^prx02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-06 04:46:25,754 [salt.state       ][INFO    ][25251] unless execution succeeded
2017-11-06 04:46:25,754 [salt.state       ][INFO    ][25251] Completed state [virtng.init] at time 04:46:25.754320 duration_in_ms=17.723
2017-11-06 04:46:25,755 [salt.state       ][INFO    ][25251] Running state [virt.set_autostart] at time 04:46:25.754533
2017-11-06 04:46:25,755 [salt.state       ][INFO    ][25251] Executing state module.run for virt.set_autostart
2017-11-06 04:46:25,757 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'virsh list --autostart --name| grep -E "^prx02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-06 04:46:25,771 [salt.state       ][INFO    ][25251] unless execution succeeded
2017-11-06 04:46:25,772 [salt.state       ][INFO    ][25251] Completed state [virt.set_autostart] at time 04:46:25.771597 duration_in_ms=17.064
2017-11-06 04:46:25,772 [salt.state       ][INFO    ][25251] Running state [virtng.init] at time 04:46:25.771853
2017-11-06 04:46:25,772 [salt.state       ][INFO    ][25251] Executing state module.run for virtng.init
2017-11-06 04:46:25,774 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'virsh list --all --name| grep -E "^odl01.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-06 04:46:25,790 [salt.state       ][INFO    ][25251] unless execution succeeded
2017-11-06 04:46:25,790 [salt.state       ][INFO    ][25251] Completed state [virtng.init] at time 04:46:25.789852 duration_in_ms=17.999
2017-11-06 04:46:25,790 [salt.state       ][INFO    ][25251] Running state [virt.set_autostart] at time 04:46:25.790099
2017-11-06 04:46:25,790 [salt.state       ][INFO    ][25251] Executing state module.run for virt.set_autostart
2017-11-06 04:46:25,792 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'virsh list --autostart --name| grep -E "^odl01.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-06 04:46:25,807 [salt.state       ][INFO    ][25251] unless execution succeeded
2017-11-06 04:46:25,807 [salt.state       ][INFO    ][25251] Completed state [virt.set_autostart] at time 04:46:25.806897 duration_in_ms=16.797
2017-11-06 04:46:25,807 [salt.state       ][INFO    ][25251] Running state [virtng.init] at time 04:46:25.807136
2017-11-06 04:46:25,807 [salt.state       ][INFO    ][25251] Executing state module.run for virtng.init
2017-11-06 04:46:25,809 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'virsh list --all --name| grep -E "^msg03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-06 04:46:25,823 [salt.state       ][INFO    ][25251] unless execution succeeded
2017-11-06 04:46:25,823 [salt.state       ][INFO    ][25251] Completed state [virtng.init] at time 04:46:25.823036 duration_in_ms=15.899
2017-11-06 04:46:25,823 [salt.state       ][INFO    ][25251] Running state [virt.set_autostart] at time 04:46:25.823255
2017-11-06 04:46:25,823 [salt.state       ][INFO    ][25251] Executing state module.run for virt.set_autostart
2017-11-06 04:46:25,825 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'virsh list --autostart --name| grep -E "^msg03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-06 04:46:25,840 [salt.state       ][INFO    ][25251] unless execution succeeded
2017-11-06 04:46:25,840 [salt.state       ][INFO    ][25251] Completed state [virt.set_autostart] at time 04:46:25.839954 duration_in_ms=16.698
2017-11-06 04:46:25,840 [salt.state       ][INFO    ][25251] Running state [virtng.init] at time 04:46:25.840186
2017-11-06 04:46:25,840 [salt.state       ][INFO    ][25251] Executing state module.run for virtng.init
2017-11-06 04:46:25,842 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'virsh list --all --name| grep -E "^ctl03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-06 04:46:25,856 [salt.state       ][INFO    ][25251] unless execution succeeded
2017-11-06 04:46:25,871 [salt.state       ][INFO    ][25251] Completed state [virtng.init] at time 04:46:25.871060 duration_in_ms=30.873
2017-11-06 04:46:25,871 [salt.state       ][INFO    ][25251] Running state [virt.set_autostart] at time 04:46:25.871310
2017-11-06 04:46:25,872 [salt.state       ][INFO    ][25251] Executing state module.run for virt.set_autostart
2017-11-06 04:46:25,873 [salt.loaded.int.module.cmdmod][INFO    ][25251] Executing command 'virsh list --autostart --name| grep -E "^ctl03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-06 04:46:25,888 [salt.state       ][INFO    ][25251] unless execution succeeded
2017-11-06 04:46:25,888 [salt.state       ][INFO    ][25251] Completed state [virt.set_autostart] at time 04:46:25.888119 duration_in_ms=16.808
2017-11-06 04:46:25,890 [salt.minion      ][INFO    ][25251] Returning information for job: 20171106044618799578
2017-11-06 04:47:11,863 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171106044711859442
2017-11-06 04:47:11,879 [salt.minion      ][INFO    ][25366] Starting a new job with PID 25366
2017-11-06 04:47:15,326 [salt.state       ][INFO    ][25366] Loading fresh modules for state activity
2017-11-06 04:47:15,382 [salt.state       ][ERROR   ][25366] No contents found in top file
2017-11-06 04:47:15,383 [salt.utils.extmods][INFO    ][25366] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-11-06 04:47:15,386 [salt.utils.extmods][INFO    ][25366] Syncing beacons for environment 'base'
2017-11-06 04:47:15,386 [salt.utils.extmods][INFO    ][25366] Loading cache from salt://_beacons, for base)
2017-11-06 04:47:15,387 [salt.fileclient  ][INFO    ][25366] Caching directory '_beacons/' for environment 'base'
2017-11-06 04:47:15,407 [salt.utils.extmods][INFO    ][25366] Syncing modules for environment 'base'
2017-11-06 04:47:15,408 [salt.utils.extmods][INFO    ][25366] Loading cache from salt://_modules, for base)
2017-11-06 04:47:15,408 [salt.fileclient  ][INFO    ][25366] Caching directory '_modules/' for environment 'base'
2017-11-06 04:47:15,438 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-11-06 04:47:15,448 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-11-06 04:47:15,457 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-11-06 04:47:15,475 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-11-06 04:47:15,483 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-11-06 04:47:15,497 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-11-06 04:47:15,652 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-11-06 04:47:15,661 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-11-06 04:47:15,671 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-11-06 04:47:15,801 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-11-06 04:47:16,023 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-11-06 04:47:16,046 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-11-06 04:47:16,099 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-11-06 04:47:16,156 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-11-06 04:47:16,213 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-11-06 04:47:16,244 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-11-06 04:47:16,274 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-11-06 04:47:16,321 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-11-06 04:47:16,367 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-11-06 04:47:16,401 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-11-06 04:47:16,433 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-11-06 04:47:16,447 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-11-06 04:47:16,512 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-11-06 04:47:16,536 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-11-06 04:47:16,557 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-11-06 04:47:16,597 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-11-06 04:47:16,607 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-11-06 04:47:16,660 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-11-06 04:47:16,676 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-11-06 04:47:16,687 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-11-06 04:47:16,714 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-11-06 04:47:16,723 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-11-06 04:47:16,746 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-11-06 04:47:16,750 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-11-06 04:47:16,750 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-11-06 04:47:16,751 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-11-06 04:47:16,751 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-11-06 04:47:16,751 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-11-06 04:47:16,752 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-11-06 04:47:16,752 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-11-06 04:47:16,752 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-11-06 04:47:16,752 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-11-06 04:47:16,753 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-11-06 04:47:16,753 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-11-06 04:47:16,753 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-11-06 04:47:16,753 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-11-06 04:47:16,753 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-11-06 04:47:16,754 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-11-06 04:47:16,754 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-11-06 04:47:16,754 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-11-06 04:47:16,754 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-11-06 04:47:16,754 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-11-06 04:47:16,755 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-11-06 04:47:16,755 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-11-06 04:47:16,755 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-11-06 04:47:16,755 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-11-06 04:47:16,756 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-11-06 04:47:16,756 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-11-06 04:47:16,756 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-11-06 04:47:16,756 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-11-06 04:47:16,756 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-11-06 04:47:16,757 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-11-06 04:47:16,757 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-11-06 04:47:16,757 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-11-06 04:47:16,757 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-11-06 04:47:16,757 [salt.utils.extmods][INFO    ][25366] 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-11-06 04:47:16,758 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-11-06 04:47:16,758 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-11-06 04:47:16,762 [salt.utils.extmods][INFO    ][25366] Syncing states for environment 'base'
2017-11-06 04:47:16,762 [salt.utils.extmods][INFO    ][25366] Loading cache from salt://_states, for base)
2017-11-06 04:47:16,762 [salt.fileclient  ][INFO    ][25366] Caching directory '_states/' for environment 'base'
2017-11-06 04:47:16,831 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-11-06 04:47:16,858 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-11-06 04:47:16,884 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-11-06 04:47:16,945 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-11-06 04:47:16,981 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-11-06 04:47:17,061 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-11-06 04:47:17,145 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-11-06 04:47:17,155 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-11-06 04:47:17,202 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-11-06 04:47:17,217 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-11-06 04:47:17,232 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-11-06 04:47:17,245 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-11-06 04:47:17,261 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-11-06 04:47:17,322 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-11-06 04:47:17,333 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-11-06 04:47:17,374 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-11-06 04:47:17,396 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-11-06 04:47:17,410 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-11-06 04:47:17,419 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-11-06 04:47:17,438 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-11-06 04:47:17,450 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-11-06 04:47:17,463 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-11-06 04:47:17,482 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-11-06 04:47:17,514 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-11-06 04:47:17,535 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-11-06 04:47:17,573 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-11-06 04:47:17,597 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-11-06 04:47:17,605 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-11-06 04:47:17,690 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-11-06 04:47:17,694 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-11-06 04:47:17,694 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-11-06 04:47:17,694 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-11-06 04:47:17,695 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-11-06 04:47:17,695 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-11-06 04:47:17,695 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-11-06 04:47:17,695 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-11-06 04:47:17,695 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-11-06 04:47:17,696 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-11-06 04:47:17,696 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-11-06 04:47:17,696 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-11-06 04:47:17,696 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-11-06 04:47:17,696 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-11-06 04:47:17,697 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-11-06 04:47:17,697 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-11-06 04:47:17,697 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-11-06 04:47:17,698 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-11-06 04:47:17,698 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-11-06 04:47:17,698 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-11-06 04:47:17,698 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-11-06 04:47:17,698 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-11-06 04:47:17,699 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-11-06 04:47:17,699 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-11-06 04:47:17,699 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-11-06 04:47:17,699 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-11-06 04:47:17,699 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-11-06 04:47:17,700 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-11-06 04:47:17,700 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-11-06 04:47:17,700 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-11-06 04:47:17,700 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-11-06 04:47:17,702 [salt.utils.extmods][INFO    ][25366] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-11-06 04:47:17,705 [salt.utils.extmods][INFO    ][25366] Syncing sdb for environment 'base'
2017-11-06 04:47:17,742 [salt.utils.extmods][INFO    ][25366] Loading cache from salt://_sdb, for base)
2017-11-06 04:47:17,743 [salt.fileclient  ][INFO    ][25366] Caching directory '_sdb/' for environment 'base'
2017-11-06 04:47:17,775 [salt.utils.extmods][INFO    ][25366] Syncing grains for environment 'base'
2017-11-06 04:47:17,775 [salt.utils.extmods][INFO    ][25366] Loading cache from salt://_grains, for base)
2017-11-06 04:47:17,775 [salt.fileclient  ][INFO    ][25366] Caching directory '_grains/' for environment 'base'
2017-11-06 04:47:17,861 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-11-06 04:47:17,872 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-11-06 04:47:17,903 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-11-06 04:47:17,917 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-11-06 04:47:17,926 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-11-06 04:47:17,934 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-11-06 04:47:17,948 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-11-06 04:47:17,959 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-11-06 04:47:18,087 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-11-06 04:47:18,093 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-11-06 04:47:18,374 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-11-06 04:47:18,384 [salt.fileclient  ][INFO    ][25366] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-11-06 04:47:18,387 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-11-06 04:47:18,387 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-11-06 04:47:18,387 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-11-06 04:47:18,388 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-11-06 04:47:18,388 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-11-06 04:47:18,388 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-11-06 04:47:18,388 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-11-06 04:47:18,388 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-11-06 04:47:18,389 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-11-06 04:47:18,389 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-11-06 04:47:18,389 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-11-06 04:47:18,389 [salt.utils.extmods][INFO    ][25366] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-11-06 04:47:18,390 [salt.utils.extmods][INFO    ][25366] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-11-06 04:47:18,393 [salt.utils.extmods][INFO    ][25366] Syncing renderers for environment 'base'
2017-11-06 04:47:18,393 [salt.utils.extmods][INFO    ][25366] Loading cache from salt://_renderers, for base)
2017-11-06 04:47:18,393 [salt.fileclient  ][INFO    ][25366] Caching directory '_renderers/' for environment 'base'
2017-11-06 04:47:18,411 [salt.utils.extmods][INFO    ][25366] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-11-06 04:47:18,413 [salt.utils.extmods][INFO    ][25366] Syncing returners for environment 'base'
2017-11-06 04:47:18,413 [salt.utils.extmods][INFO    ][25366] Loading cache from salt://_returners, for base)
2017-11-06 04:47:18,413 [salt.fileclient  ][INFO    ][25366] Caching directory '_returners/' for environment 'base'
2017-11-06 04:47:18,436 [salt.utils.extmods][INFO    ][25366] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-11-06 04:47:18,438 [salt.utils.extmods][INFO    ][25366] Syncing output for environment 'base'
2017-11-06 04:47:18,438 [salt.utils.extmods][INFO    ][25366] Loading cache from salt://_output, for base)
2017-11-06 04:47:18,438 [salt.fileclient  ][INFO    ][25366] Caching directory '_output/' for environment 'base'
2017-11-06 04:47:18,458 [salt.utils.extmods][INFO    ][25366] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-11-06 04:47:18,461 [salt.utils.extmods][INFO    ][25366] Syncing utils for environment 'base'
2017-11-06 04:47:18,461 [salt.utils.extmods][INFO    ][25366] Loading cache from salt://_utils, for base)
2017-11-06 04:47:18,461 [salt.fileclient  ][INFO    ][25366] Caching directory '_utils/' for environment 'base'
2017-11-06 04:47:18,546 [salt.utils.extmods][INFO    ][25366] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-11-06 04:47:18,549 [salt.utils.extmods][INFO    ][25366] Syncing log_handlers for environment 'base'
2017-11-06 04:47:18,549 [salt.utils.extmods][INFO    ][25366] Loading cache from salt://_log_handlers, for base)
2017-11-06 04:47:18,549 [salt.fileclient  ][INFO    ][25366] Caching directory '_log_handlers/' for environment 'base'
2017-11-06 04:47:18,570 [salt.utils.extmods][INFO    ][25366] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-11-06 04:47:18,573 [salt.utils.extmods][INFO    ][25366] Syncing proxy for environment 'base'
2017-11-06 04:47:18,573 [salt.utils.extmods][INFO    ][25366] Loading cache from salt://_proxy, for base)
2017-11-06 04:47:18,573 [salt.fileclient  ][INFO    ][25366] Caching directory '_proxy/' for environment 'base'
2017-11-06 04:47:18,667 [salt.utils.extmods][INFO    ][25366] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-11-06 04:47:18,671 [salt.utils.extmods][INFO    ][25366] Syncing engines for environment 'base'
2017-11-06 04:47:18,671 [salt.utils.extmods][INFO    ][25366] Loading cache from salt://_engines, for base)
2017-11-06 04:47:18,671 [salt.fileclient  ][INFO    ][25366] Caching directory '_engines/' for environment 'base'
2017-11-06 04:47:18,698 [salt.minion      ][INFO    ][25366] Returning information for job: 20171106044711859442
2017-11-06 05:11:40,166 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command test.ping with jid 20171106051140155584
2017-11-06 05:11:40,188 [salt.minion      ][INFO    ][25916] Starting a new job with PID 25916
2017-11-06 05:11:40,213 [salt.minion      ][INFO    ][25916] Returning information for job: 20171106051140155584
2017-11-06 05:11:57,804 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command state.sls with jid 20171106051157793720
2017-11-06 05:11:57,816 [salt.minion      ][INFO    ][25925] Starting a new job with PID 25925
2017-11-06 05:11:58,248 [salt.state       ][INFO    ][25925] Loading fresh modules for state activity
2017-11-06 05:11:58,336 [salt.fileclient  ][INFO    ][25925] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-06 05:11:58,353 [salt.fileclient  ][INFO    ][25925] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-06 05:11:58,376 [salt.fileclient  ][INFO    ][25925] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-06 05:11:58,698 [salt.state       ][INFO    ][25925] Running state [lsof] at time 05:11:58.698404
2017-11-06 05:11:58,699 [salt.state       ][INFO    ][25925] Executing state pkg.installed for lsof
2017-11-06 05:11:58,699 [salt.loaded.int.module.cmdmod][INFO    ][25925] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-06 05:11:58,965 [salt.state       ][INFO    ][25925] Package lsof is already installed
2017-11-06 05:11:58,966 [salt.state       ][INFO    ][25925] Completed state [lsof] at time 05:11:58.965628 duration_in_ms=267.224
2017-11-06 05:11:58,966 [salt.state       ][INFO    ][25925] Running state [keepalived] at time 05:11:58.965826
2017-11-06 05:11:58,966 [salt.state       ][INFO    ][25925] Executing state pkg.installed for keepalived
2017-11-06 05:11:58,980 [salt.loaded.int.module.cmdmod][INFO    ][25925] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-06 05:12:00,593 [salt.loaded.int.module.cmdmod][INFO    ][25925] 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-11-06 05:12:07,849 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command saltutil.find_job with jid 20171106051207837607
2017-11-06 05:12:07,862 [salt.minion      ][INFO    ][27426] Starting a new job with PID 27426
2017-11-06 05:12:07,872 [salt.minion      ][INFO    ][27426] Returning information for job: 20171106051207837607
2017-11-06 05:12:08,492 [salt.loaded.int.module.cmdmod][INFO    ][25925] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-06 05:12:08,516 [salt.state       ][INFO    ][25925] 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-11-06 05:12:08,524 [salt.state       ][INFO    ][25925] Loading fresh modules for state activity
2017-11-06 05:12:08,536 [salt.state       ][INFO    ][25925] Completed state [keepalived] at time 05:12:08.536162 duration_in_ms=9570.336
2017-11-06 05:12:08,538 [salt.state       ][INFO    ][25925] Running state [/etc/keepalived/keepalived.conf] at time 05:12:08.538397
2017-11-06 05:12:08,539 [salt.state       ][INFO    ][25925] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-06 05:12:08,562 [salt.fileclient  ][INFO    ][25925] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-06 05:12:08,594 [salt.fileclient  ][INFO    ][25925] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-06 05:12:08,600 [salt.state       ][INFO    ][25925] File changed:
New file
2017-11-06 05:12:08,600 [salt.state       ][INFO    ][25925] Completed state [/etc/keepalived/keepalived.conf] at time 05:12:08.600019 duration_in_ms=61.62
2017-11-06 05:12:08,658 [salt.state       ][INFO    ][25925] Running state [keepalived] at time 05:12:08.657588
2017-11-06 05:12:08,658 [salt.state       ][INFO    ][25925] Executing state service.running for keepalived
2017-11-06 05:12:08,660 [salt.loaded.int.module.cmdmod][INFO    ][25925] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-06 05:12:08,669 [salt.loaded.int.module.cmdmod][INFO    ][25925] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-06 05:12:08,677 [salt.loaded.int.module.cmdmod][INFO    ][25925] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-06 05:12:08,682 [salt.loaded.int.module.cmdmod][INFO    ][25925] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-06 05:12:08,689 [salt.loaded.int.module.cmdmod][INFO    ][25925] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-11-06 05:12:08,746 [salt.loaded.int.module.cmdmod][INFO    ][25925] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-06 05:12:08,753 [salt.loaded.int.module.cmdmod][INFO    ][25925] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-06 05:12:08,759 [salt.loaded.int.module.cmdmod][INFO    ][25925] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-06 05:12:08,765 [salt.state       ][INFO    ][25925] {'keepalived': True}
2017-11-06 05:12:08,766 [salt.state       ][INFO    ][25925] Completed state [keepalived] at time 05:12:08.765677 duration_in_ms=108.089
2017-11-06 05:12:08,766 [salt.minion      ][INFO    ][25925] Returning information for job: 20171106051157793720
2017-11-06 05:15:49,405 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command pillar.get with jid 20171106051549397341
2017-11-06 05:15:49,428 [salt.minion      ][INFO    ][27619] Starting a new job with PID 27619
2017-11-06 05:15:49,435 [salt.minion      ][INFO    ][27619] Returning information for job: 20171106051549397341
2017-11-06 05:21:04,974 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command state.sls with jid 20171106052104964654
2017-11-06 05:21:04,990 [salt.minion      ][INFO    ][27737] Starting a new job with PID 27737
2017-11-06 05:21:07,502 [salt.state       ][INFO    ][27737] Loading fresh modules for state activity
2017-11-06 05:21:07,523 [salt.fileclient  ][INFO    ][27737] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-06 05:21:07,542 [salt.fileclient  ][INFO    ][27737] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-06 05:21:07,803 [salt.state       ][INFO    ][27737] Running state [attr] at time 05:21:07.803004
2017-11-06 05:21:07,803 [salt.state       ][INFO    ][27737] Executing state pkg.installed for attr
2017-11-06 05:21:07,804 [salt.loaded.int.module.cmdmod][INFO    ][27737] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-06 05:21:08,090 [salt.loaded.int.module.cmdmod][INFO    ][27737] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-06 05:21:09,387 [salt.loaded.int.module.cmdmod][INFO    ][27737] 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-11-06 05:21:11,659 [salt.loaded.int.module.cmdmod][INFO    ][27737] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-06 05:21:11,682 [salt.state       ][INFO    ][27737] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-11-06 05:21:11,690 [salt.state       ][INFO    ][27737] Loading fresh modules for state activity
2017-11-06 05:21:11,702 [salt.state       ][INFO    ][27737] Completed state [attr] at time 05:21:11.701689 duration_in_ms=3898.686
2017-11-06 05:21:11,707 [salt.state       ][INFO    ][27737] Running state [glusterfs-server] at time 05:21:11.706565
2017-11-06 05:21:11,707 [salt.state       ][INFO    ][27737] Executing state pkg.installed for glusterfs-server
2017-11-06 05:21:11,889 [salt.loaded.int.module.cmdmod][INFO    ][27737] 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-11-06 05:21:15,075 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command saltutil.find_job with jid 20171106052115064682
2017-11-06 05:21:15,090 [salt.minion      ][INFO    ][28793] Starting a new job with PID 28793
2017-11-06 05:21:15,105 [salt.minion      ][INFO    ][28793] Returning information for job: 20171106052115064682
2017-11-06 05:21:25,276 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command saltutil.find_job with jid 20171106052125264448
2017-11-06 05:21:25,291 [salt.minion      ][INFO    ][31602] Starting a new job with PID 31602
2017-11-06 05:21:25,301 [salt.minion      ][INFO    ][31602] Returning information for job: 20171106052125264448
2017-11-06 05:21:30,129 [salt.loaded.int.module.cmdmod][INFO    ][27737] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-06 05:21:30,152 [salt.state       ][INFO    ][27737] 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-11-06 05:21:30,158 [salt.state       ][INFO    ][27737] Loading fresh modules for state activity
2017-11-06 05:21:30,170 [salt.state       ][INFO    ][27737] Completed state [glusterfs-server] at time 05:21:30.169844 duration_in_ms=18463.278
2017-11-06 05:21:30,174 [salt.state       ][INFO    ][27737] Running state [bc] at time 05:21:30.174119
2017-11-06 05:21:30,174 [salt.state       ][INFO    ][27737] Executing state pkg.installed for bc
2017-11-06 05:21:30,419 [salt.loaded.int.module.cmdmod][INFO    ][27737] 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-11-06 05:21:33,343 [salt.loaded.int.module.cmdmod][INFO    ][27737] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-06 05:21:33,371 [salt.state       ][INFO    ][27737] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-11-06 05:21:33,379 [salt.state       ][INFO    ][27737] Loading fresh modules for state activity
2017-11-06 05:21:33,392 [salt.state       ][INFO    ][27737] Completed state [bc] at time 05:21:33.391575 duration_in_ms=3217.455
2017-11-06 05:21:33,452 [salt.state       ][INFO    ][27737] Running state [glusterfs-server] at time 05:21:33.451637
2017-11-06 05:21:33,452 [salt.state       ][INFO    ][27737] Executing state service.running for glusterfs-server
2017-11-06 05:21:33,453 [salt.loaded.int.module.cmdmod][INFO    ][27737] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-06 05:21:33,462 [salt.loaded.int.module.cmdmod][INFO    ][27737] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-06 05:21:33,470 [salt.loaded.int.module.cmdmod][INFO    ][27737] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-06 05:21:33,478 [salt.state       ][INFO    ][27737] The service glusterfs-server is already running
2017-11-06 05:21:33,479 [salt.state       ][INFO    ][27737] Completed state [glusterfs-server] at time 05:21:33.478680 duration_in_ms=27.043
2017-11-06 05:21:33,480 [salt.state       ][INFO    ][27737] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 05:21:33.480181
2017-11-06 05:21:33,480 [salt.state       ][INFO    ][27737] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-06 05:21:33,492 [salt.state       ][INFO    ][27737] File changed:
New file
2017-11-06 05:21:33,492 [salt.state       ][INFO    ][27737] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 05:21:33.492420 duration_in_ms=12.239
2017-11-06 05:21:33,493 [salt.state       ][INFO    ][27737] Running state [/srv/glusterfs/glance] at time 05:21:33.492585
2017-11-06 05:21:33,493 [salt.state       ][INFO    ][27737] Executing state file.directory for /srv/glusterfs/glance
2017-11-06 05:21:33,493 [salt.state       ][INFO    ][27737] {'/srv/glusterfs/glance': 'New Dir'}
2017-11-06 05:21:33,493 [salt.state       ][INFO    ][27737] Completed state [/srv/glusterfs/glance] at time 05:21:33.493437 duration_in_ms=0.853
2017-11-06 05:21:33,494 [salt.state       ][INFO    ][27737] Running state [/srv/glusterfs/keystone-keys] at time 05:21:33.493576
2017-11-06 05:21:33,494 [salt.state       ][INFO    ][27737] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-06 05:21:33,494 [salt.state       ][INFO    ][27737] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-11-06 05:21:33,494 [salt.state       ][INFO    ][27737] Completed state [/srv/glusterfs/keystone-keys] at time 05:21:33.494189 duration_in_ms=0.613
2017-11-06 05:21:33,494 [salt.state       ][INFO    ][27737] Running state [/srv/glusterfs/nova_instances] at time 05:21:33.494331
2017-11-06 05:21:33,494 [salt.state       ][INFO    ][27737] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-06 05:21:33,495 [salt.state       ][INFO    ][27737] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-11-06 05:21:33,495 [salt.state       ][INFO    ][27737] Completed state [/srv/glusterfs/nova_instances] at time 05:21:33.494933 duration_in_ms=0.602
2017-11-06 05:21:33,495 [salt.state       ][INFO    ][27737] Running state [/srv/glusterfs/keystone-credential-keys] at time 05:21:33.495071
2017-11-06 05:21:33,495 [salt.state       ][INFO    ][27737] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-06 05:21:33,496 [salt.state       ][INFO    ][27737] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-11-06 05:21:33,496 [salt.state       ][INFO    ][27737] Completed state [/srv/glusterfs/keystone-credential-keys] at time 05:21:33.495675 duration_in_ms=0.603
2017-11-06 05:21:33,496 [salt.minion      ][INFO    ][27737] Returning information for job: 20171106052104964654
2017-11-06 05:21:34,743 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command test.ping with jid 20171106052134732599
2017-11-06 05:21:34,759 [salt.minion      ][INFO    ][32527] Starting a new job with PID 32527
2017-11-06 05:21:34,788 [salt.minion      ][INFO    ][32527] Returning information for job: 20171106052134732599
2017-11-06 05:22:15,067 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command state.sls with jid 20171106052215055214
2017-11-06 05:22:15,082 [salt.minion      ][INFO    ][32747] Starting a new job with PID 32747
2017-11-06 05:22:16,583 [salt.state       ][INFO    ][32747] Loading fresh modules for state activity
2017-11-06 05:22:16,605 [salt.fileclient  ][INFO    ][32747] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-06 05:22:16,640 [salt.fileclient  ][INFO    ][32747] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-06 05:22:16,702 [salt.fileclient  ][INFO    ][32747] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-06 05:22:16,721 [salt.fileclient  ][INFO    ][32747] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-06 05:22:16,984 [salt.state       ][INFO    ][32747] Running state [attr] at time 05:22:16.983936
2017-11-06 05:22:16,984 [salt.state       ][INFO    ][32747] Executing state pkg.installed for attr
2017-11-06 05:22:16,985 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-06 05:22:17,260 [salt.state       ][INFO    ][32747] Package attr is already installed
2017-11-06 05:22:17,260 [salt.state       ][INFO    ][32747] Completed state [attr] at time 05:22:17.260313 duration_in_ms=276.377
2017-11-06 05:22:17,261 [salt.state       ][INFO    ][32747] Running state [glusterfs-server] at time 05:22:17.260512
2017-11-06 05:22:17,261 [salt.state       ][INFO    ][32747] Executing state pkg.installed for glusterfs-server
2017-11-06 05:22:17,263 [salt.state       ][INFO    ][32747] Package glusterfs-server is already installed
2017-11-06 05:22:17,263 [salt.state       ][INFO    ][32747] Completed state [glusterfs-server] at time 05:22:17.263400 duration_in_ms=2.889
2017-11-06 05:22:17,264 [salt.state       ][INFO    ][32747] Running state [bc] at time 05:22:17.263549
2017-11-06 05:22:17,264 [salt.state       ][INFO    ][32747] Executing state pkg.installed for bc
2017-11-06 05:22:17,266 [salt.state       ][INFO    ][32747] Package bc is already installed
2017-11-06 05:22:17,266 [salt.state       ][INFO    ][32747] Completed state [bc] at time 05:22:17.266310 duration_in_ms=2.76
2017-11-06 05:22:17,267 [salt.state       ][INFO    ][32747] Running state [glusterfs-server] at time 05:22:17.267094
2017-11-06 05:22:17,267 [salt.state       ][INFO    ][32747] Executing state service.running for glusterfs-server
2017-11-06 05:22:17,268 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-06 05:22:17,277 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-11-06 05:22:17,318 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-06 05:22:17,330 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-06 05:22:17,335 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-06 05:22:17,343 [salt.state       ][INFO    ][32747] The service glusterfs-server is already running
2017-11-06 05:22:17,343 [salt.state       ][INFO    ][32747] Completed state [glusterfs-server] at time 05:22:17.342930 duration_in_ms=75.835
2017-11-06 05:22:17,344 [salt.state       ][INFO    ][32747] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 05:22:17.344339
2017-11-06 05:22:17,345 [salt.state       ][INFO    ][32747] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-06 05:22:17,354 [salt.state       ][INFO    ][32747] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-06 05:22:17,354 [salt.state       ][INFO    ][32747] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 05:22:17.354370 duration_in_ms=10.031
2017-11-06 05:22:17,355 [salt.state       ][INFO    ][32747] Running state [/srv/glusterfs/glance] at time 05:22:17.354539
2017-11-06 05:22:17,355 [salt.state       ][INFO    ][32747] Executing state file.directory for /srv/glusterfs/glance
2017-11-06 05:22:17,355 [salt.state       ][INFO    ][32747] Directory /srv/glusterfs/glance is in the correct state
2017-11-06 05:22:17,355 [salt.state       ][INFO    ][32747] Completed state [/srv/glusterfs/glance] at time 05:22:17.355162 duration_in_ms=0.623
2017-11-06 05:22:17,355 [salt.state       ][INFO    ][32747] Running state [/srv/glusterfs/keystone-keys] at time 05:22:17.355321
2017-11-06 05:22:17,355 [salt.state       ][INFO    ][32747] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-06 05:22:17,356 [salt.state       ][INFO    ][32747] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-06 05:22:17,356 [salt.state       ][INFO    ][32747] Completed state [/srv/glusterfs/keystone-keys] at time 05:22:17.355947 duration_in_ms=0.625
2017-11-06 05:22:17,356 [salt.state       ][INFO    ][32747] Running state [/srv/glusterfs/nova_instances] at time 05:22:17.356106
2017-11-06 05:22:17,356 [salt.state       ][INFO    ][32747] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-06 05:22:17,357 [salt.state       ][INFO    ][32747] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-06 05:22:17,357 [salt.state       ][INFO    ][32747] Completed state [/srv/glusterfs/nova_instances] at time 05:22:17.356730 duration_in_ms=0.624
2017-11-06 05:22:17,357 [salt.state       ][INFO    ][32747] Running state [/srv/glusterfs/keystone-credential-keys] at time 05:22:17.356887
2017-11-06 05:22:17,357 [salt.state       ][INFO    ][32747] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-06 05:22:17,357 [salt.state       ][INFO    ][32747] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-06 05:22:17,358 [salt.state       ][INFO    ][32747] Completed state [/srv/glusterfs/keystone-credential-keys] at time 05:22:17.357542 duration_in_ms=0.654
2017-11-06 05:22:17,409 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command ['git', '--version'] in directory '/root'
2017-11-06 05:22:17,494 [salt.state       ][INFO    ][32747] Running state [sleep 5] at time 05:22:17.494086
2017-11-06 05:22:17,494 [salt.state       ][INFO    ][32747] Executing state cmd.wait for sleep 5
2017-11-06 05:22:17,495 [salt.state       ][INFO    ][32747] No changes made for sleep 5
2017-11-06 05:22:17,495 [salt.state       ][INFO    ][32747] Completed state [sleep 5] at time 05:22:17.494679 duration_in_ms=0.593
2017-11-06 05:22:17,495 [salt.state       ][INFO    ][32747] Running state [10.167.4.141] at time 05:22:17.495065
2017-11-06 05:22:17,495 [salt.state       ][INFO    ][32747] Executing state glusterfs.peered for 10.167.4.141
2017-11-06 05:22:17,496 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:17,505 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:17,554 [salt.state       ][INFO    ][32747] Host 10.167.4.141 already peered
2017-11-06 05:22:17,554 [salt.state       ][INFO    ][32747] Completed state [10.167.4.141] at time 05:22:17.554362 duration_in_ms=59.296
2017-11-06 05:22:17,557 [salt.state       ][INFO    ][32747] Running state [10.167.4.142] at time 05:22:17.556476
2017-11-06 05:22:17,557 [salt.state       ][INFO    ][32747] Executing state glusterfs.peered for 10.167.4.142
2017-11-06 05:22:17,557 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:17,564 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:17,612 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:17,619 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:17,671 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:17,679 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:17,736 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:17,743 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:17,788 [salt.state       ][INFO    ][32747] {'new': {'kvm02.baremetal-mcp-ocata-odl-ha.local': ['10.167.4.142'], '10.167.4.141': []}, 'old': {'kvm02.baremetal-mcp-ocata-odl-ha.local': [], '10.167.4.141': []}}
2017-11-06 05:22:17,788 [salt.state       ][INFO    ][32747] Completed state [10.167.4.142] at time 05:22:17.788178 duration_in_ms=231.701
2017-11-06 05:22:17,790 [salt.state       ][INFO    ][32747] Running state [10.167.4.143] at time 05:22:17.790405
2017-11-06 05:22:17,791 [salt.state       ][INFO    ][32747] Executing state glusterfs.peered for 10.167.4.143
2017-11-06 05:22:17,791 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:17,799 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:17,845 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:17,853 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:17,900 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:17,908 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:17,956 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:17,963 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:18,009 [salt.state       ][INFO    ][32747] Peering with localhost is not needed
2017-11-06 05:22:18,009 [salt.state       ][INFO    ][32747] Completed state [10.167.4.143] at time 05:22:18.009351 duration_in_ms=218.946
2017-11-06 05:22:18,010 [salt.state       ][INFO    ][32747] Running state [glance] at time 05:22:18.010109
2017-11-06 05:22:18,010 [salt.state       ][INFO    ][32747] Executing state glusterfs.created for glance
2017-11-06 05:22:18,011 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:18,018 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:18,062 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:18,069 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:18,121 [salt.state       ][INFO    ][32747] Volume glance already exists and is started.
2017-11-06 05:22:18,121 [salt.state       ][INFO    ][32747] Completed state [glance] at time 05:22:18.120893 duration_in_ms=110.783
2017-11-06 05:22:18,123 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 05:22:18.123457
2017-11-06 05:22:18,124 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-06 05:22:18,124 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-06 05:22:18,170 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:18,171 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 05:22:18.170465 duration_in_ms=47.008
2017-11-06 05:22:18,171 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 05:22:18.170909
2017-11-06 05:22:18,171 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-06 05:22:18,172 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-06 05:22:18,220 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:18,220 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 05:22:18.220347 duration_in_ms=49.438
2017-11-06 05:22:18,221 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 05:22:18.220754
2017-11-06 05:22:18,221 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-06 05:22:18,221 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-06 05:22:18,269 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:18,269 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 05:22:18.268849 duration_in_ms=48.093
2017-11-06 05:22:18,269 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 05:22:18.269250
2017-11-06 05:22:18,269 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-06 05:22:18,270 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-06 05:22:18,316 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:18,316 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 05:22:18.316446 duration_in_ms=47.195
2017-11-06 05:22:18,317 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 05:22:18.316851
2017-11-06 05:22:18,317 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-06 05:22:18,317 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-06 05:22:18,362 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:18,362 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 05:22:18.362369 duration_in_ms=45.518
2017-11-06 05:22:18,363 [salt.state       ][INFO    ][32747] Running state [glance] at time 05:22:18.363242
2017-11-06 05:22:18,363 [salt.state       ][INFO    ][32747] Executing state glusterfs.started for glance
2017-11-06 05:22:18,364 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:18,371 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:18,419 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:18,426 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:18,480 [salt.state       ][INFO    ][32747] Volume glance is already started
2017-11-06 05:22:18,480 [salt.state       ][INFO    ][32747] Completed state [glance] at time 05:22:18.480033 duration_in_ms=116.789
2017-11-06 05:22:18,481 [salt.state       ][INFO    ][32747] Running state [keystone-keys] at time 05:22:18.480838
2017-11-06 05:22:18,481 [salt.state       ][INFO    ][32747] Executing state glusterfs.created for keystone-keys
2017-11-06 05:22:18,482 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:18,489 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:18,537 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:18,545 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:18,596 [salt.state       ][INFO    ][32747] Volume keystone-keys already exists and is started.
2017-11-06 05:22:18,597 [salt.state       ][INFO    ][32747] Completed state [keystone-keys] at time 05:22:18.596475 duration_in_ms=115.637
2017-11-06 05:22:18,599 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 05:22:18.599247
2017-11-06 05:22:18,600 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-06 05:22:18,600 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-06 05:22:18,650 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:18,650 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 05:22:18.650184 duration_in_ms=50.936
2017-11-06 05:22:18,651 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 05:22:18.650592
2017-11-06 05:22:18,651 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-06 05:22:18,651 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-06 05:22:18,699 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:18,699 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 05:22:18.699173 duration_in_ms=48.581
2017-11-06 05:22:18,700 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 05:22:18.699615
2017-11-06 05:22:18,700 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-06 05:22:18,700 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-06 05:22:18,751 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:18,751 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 05:22:18.751376 duration_in_ms=51.761
2017-11-06 05:22:18,752 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 05:22:18.751807
2017-11-06 05:22:18,752 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-06 05:22:18,752 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-06 05:22:18,801 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:18,801 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 05:22:18.800807 duration_in_ms=48.999
2017-11-06 05:22:18,801 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 05:22:18.801288
2017-11-06 05:22:18,802 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-06 05:22:18,802 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-06 05:22:18,848 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:18,849 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 05:22:18.848473 duration_in_ms=47.185
2017-11-06 05:22:18,849 [salt.state       ][INFO    ][32747] Running state [keystone-keys] at time 05:22:18.849442
2017-11-06 05:22:18,850 [salt.state       ][INFO    ][32747] Executing state glusterfs.started for keystone-keys
2017-11-06 05:22:18,850 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:18,858 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:18,905 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:18,913 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:18,963 [salt.state       ][INFO    ][32747] Volume keystone-keys is already started
2017-11-06 05:22:18,963 [salt.state       ][INFO    ][32747] Completed state [keystone-keys] at time 05:22:18.963021 duration_in_ms=113.578
2017-11-06 05:22:18,964 [salt.state       ][INFO    ][32747] Running state [nova_instances] at time 05:22:18.963970
2017-11-06 05:22:18,964 [salt.state       ][INFO    ][32747] Executing state glusterfs.created for nova_instances
2017-11-06 05:22:18,965 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:18,971 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:19,018 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:19,025 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:19,078 [salt.state       ][INFO    ][32747] Volume nova_instances already exists and is started.
2017-11-06 05:22:19,078 [salt.state       ][INFO    ][32747] Completed state [nova_instances] at time 05:22:19.077916 duration_in_ms=113.944
2017-11-06 05:22:19,081 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 05:22:19.080554
2017-11-06 05:22:19,081 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-06 05:22:19,081 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-06 05:22:19,134 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:19,134 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 05:22:19.134067 duration_in_ms=53.512
2017-11-06 05:22:19,135 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 05:22:19.134868
2017-11-06 05:22:19,135 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-11-06 05:22:19,136 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-11-06 05:22:19,183 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:19,184 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 05:22:19.183518 duration_in_ms=48.649
2017-11-06 05:22:19,184 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 05:22:19.184052
2017-11-06 05:22:19,184 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-11-06 05:22:19,185 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-11-06 05:22:19,237 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:19,237 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 05:22:19.237453 duration_in_ms=53.4
2017-11-06 05:22:19,238 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 05:22:19.237962
2017-11-06 05:22:19,238 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-11-06 05:22:19,239 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-11-06 05:22:19,284 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:19,284 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 05:22:19.284374 duration_in_ms=46.412
2017-11-06 05:22:19,285 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 05:22:19.284828
2017-11-06 05:22:19,285 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-06 05:22:19,286 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-06 05:22:19,331 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:19,331 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 05:22:19.330812 duration_in_ms=45.983
2017-11-06 05:22:19,332 [salt.state       ][INFO    ][32747] Running state [nova_instances] at time 05:22:19.331778
2017-11-06 05:22:19,332 [salt.state       ][INFO    ][32747] Executing state glusterfs.started for nova_instances
2017-11-06 05:22:19,333 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:19,340 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:19,388 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:19,396 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:19,447 [salt.state       ][INFO    ][32747] Volume nova_instances is already started
2017-11-06 05:22:19,447 [salt.state       ][INFO    ][32747] Completed state [nova_instances] at time 05:22:19.447055 duration_in_ms=115.276
2017-11-06 05:22:19,448 [salt.state       ][INFO    ][32747] Running state [keystone-credential-keys] at time 05:22:19.448155
2017-11-06 05:22:19,448 [salt.state       ][INFO    ][32747] Executing state glusterfs.created for keystone-credential-keys
2017-11-06 05:22:19,449 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:19,457 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:19,507 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:19,515 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:19,566 [salt.state       ][INFO    ][32747] Volume keystone-credential-keys already exists and is started.
2017-11-06 05:22:19,567 [salt.state       ][INFO    ][32747] Completed state [keystone-credential-keys] at time 05:22:19.566572 duration_in_ms=118.417
2017-11-06 05:22:19,570 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 05:22:19.569744
2017-11-06 05:22:19,570 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-06 05:22:19,571 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-06 05:22:19,620 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:19,620 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 05:22:19.620318 duration_in_ms=50.574
2017-11-06 05:22:19,621 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 05:22:19.620693
2017-11-06 05:22:19,621 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-06 05:22:19,621 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-06 05:22:19,669 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:19,669 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 05:22:19.669096 duration_in_ms=48.402
2017-11-06 05:22:19,670 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 05:22:19.669569
2017-11-06 05:22:19,670 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-06 05:22:19,670 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-06 05:22:19,716 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:19,716 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 05:22:19.716199 duration_in_ms=46.625
2017-11-06 05:22:19,717 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 05:22:19.716722
2017-11-06 05:22:19,717 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-06 05:22:19,717 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-06 05:22:19,764 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:19,764 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 05:22:19.764388 duration_in_ms=47.666
2017-11-06 05:22:19,765 [salt.state       ][INFO    ][32747] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 05:22:19.764927
2017-11-06 05:22:19,765 [salt.state       ][INFO    ][32747] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-06 05:22:19,766 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-06 05:22:19,812 [salt.state       ][INFO    ][32747] unless execution succeeded
2017-11-06 05:22:19,812 [salt.state       ][INFO    ][32747] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 05:22:19.812027 duration_in_ms=47.101
2017-11-06 05:22:19,813 [salt.state       ][INFO    ][32747] Running state [keystone-credential-keys] at time 05:22:19.812936
2017-11-06 05:22:19,813 [salt.state       ][INFO    ][32747] Executing state glusterfs.started for keystone-credential-keys
2017-11-06 05:22:19,814 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:19,820 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:19,868 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --version' in directory '/root'
2017-11-06 05:22:19,876 [salt.loaded.int.module.cmdmod][INFO    ][32747] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 05:22:19,928 [salt.state       ][INFO    ][32747] Volume keystone-credential-keys is already started
2017-11-06 05:22:19,928 [salt.state       ][INFO    ][32747] Completed state [keystone-credential-keys] at time 05:22:19.927728 duration_in_ms=114.792
2017-11-06 05:22:19,930 [salt.minion      ][INFO    ][32747] Returning information for job: 20171106052215055214
2017-11-06 05:22:24,992 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command test.ping with jid 20171106052224981918
2017-11-06 05:22:25,007 [salt.minion      ][INFO    ][709] Starting a new job with PID 709
2017-11-06 05:22:25,031 [salt.minion      ][INFO    ][709] Returning information for job: 20171106052224981918
2017-11-06 05:22:25,426 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command cmd.run with jid 20171106052225416769
2017-11-06 05:22:25,441 [salt.minion      ][INFO    ][714] Starting a new job with PID 714
2017-11-06 05:22:25,445 [salt.loaded.int.module.cmdmod][INFO    ][714] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-06 05:22:25,556 [salt.minion      ][INFO    ][714] Returning information for job: 20171106052225416769
2017-11-06 05:45:51,502 [salt.utils.schedule][INFO    ][25116] Running scheduled job: __mine_interval
2017-11-06 06:20:44,287 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command test.ping with jid 20171106062044282201
2017-11-06 06:20:44,304 [salt.minion      ][INFO    ][1976] Starting a new job with PID 1976
2017-11-06 06:20:44,381 [salt.minion      ][INFO    ][1976] Returning information for job: 20171106062044282201
2017-11-06 06:20:50,710 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command state.sls with jid 20171106062050702032
2017-11-06 06:20:50,724 [salt.minion      ][INFO    ][1985] Starting a new job with PID 1985
2017-11-06 06:20:52,151 [salt.state       ][INFO    ][1985] Loading fresh modules for state activity
2017-11-06 06:20:52,178 [salt.fileclient  ][INFO    ][1985] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-06 06:20:52,197 [salt.fileclient  ][INFO    ][1985] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-06 06:20:52,221 [salt.fileclient  ][INFO    ][1985] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-06 06:20:52,598 [salt.state       ][INFO    ][1985] Running state [lsof] at time 06:20:52.598080
2017-11-06 06:20:52,598 [salt.state       ][INFO    ][1985] Executing state pkg.installed for lsof
2017-11-06 06:20:52,599 [salt.loaded.int.module.cmdmod][INFO    ][1985] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-06 06:20:52,953 [salt.state       ][INFO    ][1985] Package lsof is already installed
2017-11-06 06:20:52,953 [salt.state       ][INFO    ][1985] Completed state [lsof] at time 06:20:52.952725 duration_in_ms=354.645
2017-11-06 06:20:52,953 [salt.state       ][INFO    ][1985] Running state [keepalived] at time 06:20:52.952920
2017-11-06 06:20:52,953 [salt.state       ][INFO    ][1985] Executing state pkg.installed for keepalived
2017-11-06 06:20:52,956 [salt.state       ][INFO    ][1985] Package keepalived is already installed
2017-11-06 06:20:52,956 [salt.state       ][INFO    ][1985] Completed state [keepalived] at time 06:20:52.955973 duration_in_ms=3.053
2017-11-06 06:20:52,957 [salt.state       ][INFO    ][1985] Running state [/etc/keepalived/keepalived.conf] at time 06:20:52.957376
2017-11-06 06:20:52,958 [salt.state       ][INFO    ][1985] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-06 06:20:52,981 [salt.fileclient  ][INFO    ][1985] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-06 06:20:53,011 [salt.fileclient  ][INFO    ][1985] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-06 06:20:53,015 [salt.state       ][INFO    ][1985] File /etc/keepalived/keepalived.conf is in the correct state
2017-11-06 06:20:53,016 [salt.state       ][INFO    ][1985] Completed state [/etc/keepalived/keepalived.conf] at time 06:20:53.015473 duration_in_ms=58.097
2017-11-06 06:20:53,016 [salt.state       ][INFO    ][1985] Running state [keepalived] at time 06:20:53.015966
2017-11-06 06:20:53,016 [salt.state       ][INFO    ][1985] Executing state service.running for keepalived
2017-11-06 06:20:53,016 [salt.loaded.int.module.cmdmod][INFO    ][1985] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-06 06:20:53,030 [salt.loaded.int.module.cmdmod][INFO    ][1985] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-06 06:20:53,036 [salt.loaded.int.module.cmdmod][INFO    ][1985] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-06 06:20:53,041 [salt.state       ][INFO    ][1985] The service keepalived is already running
2017-11-06 06:20:53,041 [salt.state       ][INFO    ][1985] Completed state [keepalived] at time 06:20:53.041353 duration_in_ms=25.387
2017-11-06 06:20:53,042 [salt.minion      ][INFO    ][1985] Returning information for job: 20171106062050702032
2017-11-06 06:21:16,777 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command pillar.get with jid 20171106062116767845
2017-11-06 06:21:16,795 [salt.minion      ][INFO    ][2016] Starting a new job with PID 2016
2017-11-06 06:21:16,800 [salt.minion      ][INFO    ][2016] Returning information for job: 20171106062116767845
2017-11-06 06:21:54,075 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command state.sls with jid 20171106062154067300
2017-11-06 06:21:54,089 [salt.minion      ][INFO    ][2025] Starting a new job with PID 2025
2017-11-06 06:21:54,492 [salt.state       ][INFO    ][2025] Loading fresh modules for state activity
2017-11-06 06:21:54,520 [salt.fileclient  ][INFO    ][2025] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-06 06:21:54,544 [salt.fileclient  ][INFO    ][2025] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-06 06:21:54,831 [salt.state       ][INFO    ][2025] Running state [attr] at time 06:21:54.830906
2017-11-06 06:21:54,831 [salt.state       ][INFO    ][2025] Executing state pkg.installed for attr
2017-11-06 06:21:54,832 [salt.loaded.int.module.cmdmod][INFO    ][2025] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-06 06:21:55,080 [salt.state       ][INFO    ][2025] Package attr is already installed
2017-11-06 06:21:55,080 [salt.state       ][INFO    ][2025] Completed state [attr] at time 06:21:55.079818 duration_in_ms=248.912
2017-11-06 06:21:55,080 [salt.state       ][INFO    ][2025] Running state [glusterfs-server] at time 06:21:55.080021
2017-11-06 06:21:55,080 [salt.state       ][INFO    ][2025] Executing state pkg.installed for glusterfs-server
2017-11-06 06:21:55,083 [salt.state       ][INFO    ][2025] Package glusterfs-server is already installed
2017-11-06 06:21:55,083 [salt.state       ][INFO    ][2025] Completed state [glusterfs-server] at time 06:21:55.082904 duration_in_ms=2.882
2017-11-06 06:21:55,083 [salt.state       ][INFO    ][2025] Running state [bc] at time 06:21:55.083046
2017-11-06 06:21:55,083 [salt.state       ][INFO    ][2025] Executing state pkg.installed for bc
2017-11-06 06:21:55,086 [salt.state       ][INFO    ][2025] Package bc is already installed
2017-11-06 06:21:55,086 [salt.state       ][INFO    ][2025] Completed state [bc] at time 06:21:55.085977 duration_in_ms=2.931
2017-11-06 06:21:55,087 [salt.state       ][INFO    ][2025] Running state [glusterfs-server] at time 06:21:55.086643
2017-11-06 06:21:55,087 [salt.state       ][INFO    ][2025] Executing state service.running for glusterfs-server
2017-11-06 06:21:55,087 [salt.loaded.int.module.cmdmod][INFO    ][2025] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-06 06:21:55,103 [salt.loaded.int.module.cmdmod][INFO    ][2025] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-06 06:21:55,111 [salt.loaded.int.module.cmdmod][INFO    ][2025] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-06 06:21:55,120 [salt.state       ][INFO    ][2025] The service glusterfs-server is already running
2017-11-06 06:21:55,121 [salt.state       ][INFO    ][2025] Completed state [glusterfs-server] at time 06:21:55.120610 duration_in_ms=33.967
2017-11-06 06:21:55,122 [salt.state       ][INFO    ][2025] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 06:21:55.121954
2017-11-06 06:21:55,122 [salt.state       ][INFO    ][2025] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-06 06:21:55,130 [salt.state       ][INFO    ][2025] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-06 06:21:55,130 [salt.state       ][INFO    ][2025] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 06:21:55.130286 duration_in_ms=8.332
2017-11-06 06:21:55,130 [salt.state       ][INFO    ][2025] Running state [/srv/glusterfs/glance] at time 06:21:55.130434
2017-11-06 06:21:55,131 [salt.state       ][INFO    ][2025] Executing state file.directory for /srv/glusterfs/glance
2017-11-06 06:21:55,131 [salt.state       ][INFO    ][2025] Directory /srv/glusterfs/glance is in the correct state
2017-11-06 06:21:55,131 [salt.state       ][INFO    ][2025] Completed state [/srv/glusterfs/glance] at time 06:21:55.131072 duration_in_ms=0.638
2017-11-06 06:21:55,131 [salt.state       ][INFO    ][2025] Running state [/srv/glusterfs/keystone-keys] at time 06:21:55.131210
2017-11-06 06:21:55,131 [salt.state       ][INFO    ][2025] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-06 06:21:55,132 [salt.state       ][INFO    ][2025] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-06 06:21:55,132 [salt.state       ][INFO    ][2025] Completed state [/srv/glusterfs/keystone-keys] at time 06:21:55.131786 duration_in_ms=0.575
2017-11-06 06:21:55,132 [salt.state       ][INFO    ][2025] Running state [/srv/glusterfs/nova_instances] at time 06:21:55.131920
2017-11-06 06:21:55,132 [salt.state       ][INFO    ][2025] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-06 06:21:55,132 [salt.state       ][INFO    ][2025] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-06 06:21:55,133 [salt.state       ][INFO    ][2025] Completed state [/srv/glusterfs/nova_instances] at time 06:21:55.132526 duration_in_ms=0.606
2017-11-06 06:21:55,133 [salt.state       ][INFO    ][2025] Running state [/srv/glusterfs/keystone-credential-keys] at time 06:21:55.132672
2017-11-06 06:21:55,133 [salt.state       ][INFO    ][2025] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-06 06:21:55,133 [salt.state       ][INFO    ][2025] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-06 06:21:55,133 [salt.state       ][INFO    ][2025] Completed state [/srv/glusterfs/keystone-credential-keys] at time 06:21:55.133265 duration_in_ms=0.593
2017-11-06 06:21:55,134 [salt.minion      ][INFO    ][2025] Returning information for job: 20171106062154067300
2017-11-06 06:21:56,361 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command test.ping with jid 20171106062156353450
2017-11-06 06:21:56,376 [salt.minion      ][INFO    ][2048] Starting a new job with PID 2048
2017-11-06 06:21:56,402 [salt.minion      ][INFO    ][2048] Returning information for job: 20171106062156353450
2017-11-06 06:22:01,469 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command state.sls with jid 20171106062201460685
2017-11-06 06:22:01,483 [salt.minion      ][INFO    ][2053] Starting a new job with PID 2053
2017-11-06 06:22:01,902 [salt.state       ][INFO    ][2053] Loading fresh modules for state activity
2017-11-06 06:22:01,925 [salt.fileclient  ][INFO    ][2053] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-06 06:22:01,959 [salt.fileclient  ][INFO    ][2053] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-06 06:22:02,022 [salt.fileclient  ][INFO    ][2053] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-06 06:22:02,041 [salt.fileclient  ][INFO    ][2053] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-06 06:22:02,299 [salt.state       ][INFO    ][2053] Running state [attr] at time 06:22:02.299299
2017-11-06 06:22:02,300 [salt.state       ][INFO    ][2053] Executing state pkg.installed for attr
2017-11-06 06:22:02,300 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-06 06:22:02,595 [salt.state       ][INFO    ][2053] Package attr is already installed
2017-11-06 06:22:02,595 [salt.state       ][INFO    ][2053] Completed state [attr] at time 06:22:02.595232 duration_in_ms=295.933
2017-11-06 06:22:02,595 [salt.state       ][INFO    ][2053] Running state [glusterfs-server] at time 06:22:02.595427
2017-11-06 06:22:02,596 [salt.state       ][INFO    ][2053] Executing state pkg.installed for glusterfs-server
2017-11-06 06:22:02,598 [salt.state       ][INFO    ][2053] Package glusterfs-server is already installed
2017-11-06 06:22:02,598 [salt.state       ][INFO    ][2053] Completed state [glusterfs-server] at time 06:22:02.598395 duration_in_ms=2.968
2017-11-06 06:22:02,599 [salt.state       ][INFO    ][2053] Running state [bc] at time 06:22:02.598539
2017-11-06 06:22:02,599 [salt.state       ][INFO    ][2053] Executing state pkg.installed for bc
2017-11-06 06:22:02,601 [salt.state       ][INFO    ][2053] Package bc is already installed
2017-11-06 06:22:02,601 [salt.state       ][INFO    ][2053] Completed state [bc] at time 06:22:02.601336 duration_in_ms=2.797
2017-11-06 06:22:02,602 [salt.state       ][INFO    ][2053] Running state [glusterfs-server] at time 06:22:02.602108
2017-11-06 06:22:02,602 [salt.state       ][INFO    ][2053] Executing state service.running for glusterfs-server
2017-11-06 06:22:02,603 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-06 06:22:02,619 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-06 06:22:02,627 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-06 06:22:02,637 [salt.state       ][INFO    ][2053] The service glusterfs-server is already running
2017-11-06 06:22:02,638 [salt.state       ][INFO    ][2053] Completed state [glusterfs-server] at time 06:22:02.637609 duration_in_ms=35.501
2017-11-06 06:22:02,639 [salt.state       ][INFO    ][2053] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 06:22:02.639249
2017-11-06 06:22:02,640 [salt.state       ][INFO    ][2053] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-06 06:22:02,646 [salt.state       ][INFO    ][2053] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-06 06:22:02,647 [salt.state       ][INFO    ][2053] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 06:22:02.646490 duration_in_ms=7.241
2017-11-06 06:22:02,647 [salt.state       ][INFO    ][2053] Running state [/srv/glusterfs/glance] at time 06:22:02.646699
2017-11-06 06:22:02,647 [salt.state       ][INFO    ][2053] Executing state file.directory for /srv/glusterfs/glance
2017-11-06 06:22:02,647 [salt.state       ][INFO    ][2053] Directory /srv/glusterfs/glance is in the correct state
2017-11-06 06:22:02,648 [salt.state       ][INFO    ][2053] Completed state [/srv/glusterfs/glance] at time 06:22:02.647529 duration_in_ms=0.829
2017-11-06 06:22:02,648 [salt.state       ][INFO    ][2053] Running state [/srv/glusterfs/keystone-keys] at time 06:22:02.647721
2017-11-06 06:22:02,648 [salt.state       ][INFO    ][2053] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-06 06:22:02,648 [salt.state       ][INFO    ][2053] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-06 06:22:02,649 [salt.state       ][INFO    ][2053] Completed state [/srv/glusterfs/keystone-keys] at time 06:22:02.648512 duration_in_ms=0.791
2017-11-06 06:22:02,649 [salt.state       ][INFO    ][2053] Running state [/srv/glusterfs/nova_instances] at time 06:22:02.648706
2017-11-06 06:22:02,649 [salt.state       ][INFO    ][2053] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-06 06:22:02,649 [salt.state       ][INFO    ][2053] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-06 06:22:02,650 [salt.state       ][INFO    ][2053] Completed state [/srv/glusterfs/nova_instances] at time 06:22:02.649522 duration_in_ms=0.815
2017-11-06 06:22:02,650 [salt.state       ][INFO    ][2053] Running state [/srv/glusterfs/keystone-credential-keys] at time 06:22:02.649715
2017-11-06 06:22:02,650 [salt.state       ][INFO    ][2053] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-06 06:22:02,650 [salt.state       ][INFO    ][2053] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-06 06:22:02,651 [salt.state       ][INFO    ][2053] Completed state [/srv/glusterfs/keystone-credential-keys] at time 06:22:02.650514 duration_in_ms=0.8
2017-11-06 06:22:02,703 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command ['git', '--version'] in directory '/root'
2017-11-06 06:22:02,815 [salt.state       ][INFO    ][2053] Running state [sleep 5] at time 06:22:02.814690
2017-11-06 06:22:02,815 [salt.state       ][INFO    ][2053] Executing state cmd.wait for sleep 5
2017-11-06 06:22:02,815 [salt.state       ][INFO    ][2053] No changes made for sleep 5
2017-11-06 06:22:02,815 [salt.state       ][INFO    ][2053] Completed state [sleep 5] at time 06:22:02.815273 duration_in_ms=0.584
2017-11-06 06:22:02,816 [salt.state       ][INFO    ][2053] Running state [10.167.4.141] at time 06:22:02.815649
2017-11-06 06:22:02,816 [salt.state       ][INFO    ][2053] Executing state glusterfs.peered for 10.167.4.141
2017-11-06 06:22:02,816 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:02,825 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:02,903 [salt.state       ][INFO    ][2053] Host 10.167.4.141 already peered
2017-11-06 06:22:02,903 [salt.state       ][INFO    ][2053] Completed state [10.167.4.141] at time 06:22:02.902983 duration_in_ms=87.332
2017-11-06 06:22:02,905 [salt.state       ][INFO    ][2053] Running state [10.167.4.142] at time 06:22:02.905388
2017-11-06 06:22:02,906 [salt.state       ][INFO    ][2053] Executing state glusterfs.peered for 10.167.4.142
2017-11-06 06:22:02,906 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:02,914 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:02,962 [salt.state       ][INFO    ][2053] Host 10.167.4.142 already peered
2017-11-06 06:22:02,962 [salt.state       ][INFO    ][2053] Completed state [10.167.4.142] at time 06:22:02.961799 duration_in_ms=56.41
2017-11-06 06:22:02,964 [salt.state       ][INFO    ][2053] Running state [10.167.4.143] at time 06:22:02.964072
2017-11-06 06:22:02,964 [salt.state       ][INFO    ][2053] Executing state glusterfs.peered for 10.167.4.143
2017-11-06 06:22:02,965 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:02,973 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:03,021 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:03,029 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:03,078 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:03,087 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:03,137 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:03,145 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:03,193 [salt.state       ][INFO    ][2053] Peering with localhost is not needed
2017-11-06 06:22:03,193 [salt.state       ][INFO    ][2053] Completed state [10.167.4.143] at time 06:22:03.192983 duration_in_ms=228.91
2017-11-06 06:22:03,194 [salt.state       ][INFO    ][2053] Running state [glance] at time 06:22:03.193861
2017-11-06 06:22:03,194 [salt.state       ][INFO    ][2053] Executing state glusterfs.created for glance
2017-11-06 06:22:03,195 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:03,202 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:03,253 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:03,261 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:03,312 [salt.state       ][INFO    ][2053] Volume glance already exists and is started.
2017-11-06 06:22:03,312 [salt.state       ][INFO    ][2053] Completed state [glance] at time 06:22:03.312050 duration_in_ms=118.189
2017-11-06 06:22:03,315 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 06:22:03.314786
2017-11-06 06:22:03,315 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-06 06:22:03,316 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-06 06:22:03,362 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:03,362 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 06:22:03.362022 duration_in_ms=47.236
2017-11-06 06:22:03,362 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 06:22:03.362458
2017-11-06 06:22:03,363 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-06 06:22:03,363 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-06 06:22:03,411 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:03,411 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 06:22:03.411224 duration_in_ms=48.766
2017-11-06 06:22:03,412 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 06:22:03.411650
2017-11-06 06:22:03,412 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-06 06:22:03,412 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-06 06:22:03,461 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:03,461 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 06:22:03.461313 duration_in_ms=49.663
2017-11-06 06:22:03,462 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 06:22:03.461806
2017-11-06 06:22:03,462 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-06 06:22:03,463 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-06 06:22:03,515 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:03,516 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 06:22:03.515610 duration_in_ms=53.803
2017-11-06 06:22:03,516 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 06:22:03.516037
2017-11-06 06:22:03,516 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-06 06:22:03,517 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-06 06:22:03,568 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:03,568 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 06:22:03.568032 duration_in_ms=51.995
2017-11-06 06:22:03,569 [salt.state       ][INFO    ][2053] Running state [glance] at time 06:22:03.569003
2017-11-06 06:22:03,569 [salt.state       ][INFO    ][2053] Executing state glusterfs.started for glance
2017-11-06 06:22:03,570 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:03,578 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:03,625 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:03,632 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:03,683 [salt.state       ][INFO    ][2053] Volume glance is already started
2017-11-06 06:22:03,684 [salt.state       ][INFO    ][2053] Completed state [glance] at time 06:22:03.683479 duration_in_ms=114.474
2017-11-06 06:22:03,684 [salt.state       ][INFO    ][2053] Running state [keystone-keys] at time 06:22:03.684273
2017-11-06 06:22:03,685 [salt.state       ][INFO    ][2053] Executing state glusterfs.created for keystone-keys
2017-11-06 06:22:03,685 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:03,692 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:03,740 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:03,748 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:03,798 [salt.state       ][INFO    ][2053] Volume keystone-keys already exists and is started.
2017-11-06 06:22:03,799 [salt.state       ][INFO    ][2053] Completed state [keystone-keys] at time 06:22:03.798520 duration_in_ms=114.246
2017-11-06 06:22:03,801 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 06:22:03.801334
2017-11-06 06:22:03,802 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-06 06:22:03,802 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-06 06:22:03,849 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:03,850 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 06:22:03.849465 duration_in_ms=48.13
2017-11-06 06:22:03,850 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 06:22:03.849905
2017-11-06 06:22:03,850 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-06 06:22:03,851 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-06 06:22:03,902 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:03,902 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 06:22:03.902182 duration_in_ms=52.276
2017-11-06 06:22:03,903 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 06:22:03.902622
2017-11-06 06:22:03,903 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-06 06:22:03,903 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-06 06:22:03,952 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:03,952 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 06:22:03.952145 duration_in_ms=49.522
2017-11-06 06:22:03,953 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 06:22:03.952642
2017-11-06 06:22:03,953 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-06 06:22:03,953 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-06 06:22:04,004 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:04,004 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 06:22:04.004384 duration_in_ms=51.741
2017-11-06 06:22:04,005 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 06:22:04.004791
2017-11-06 06:22:04,005 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-06 06:22:04,005 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-06 06:22:04,054 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:04,054 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 06:22:04.054206 duration_in_ms=49.414
2017-11-06 06:22:04,055 [salt.state       ][INFO    ][2053] Running state [keystone-keys] at time 06:22:04.055141
2017-11-06 06:22:04,055 [salt.state       ][INFO    ][2053] Executing state glusterfs.started for keystone-keys
2017-11-06 06:22:04,056 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:04,063 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:04,109 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:04,116 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:04,166 [salt.state       ][INFO    ][2053] Volume keystone-keys is already started
2017-11-06 06:22:04,166 [salt.state       ][INFO    ][2053] Completed state [keystone-keys] at time 06:22:04.166184 duration_in_ms=111.043
2017-11-06 06:22:04,167 [salt.state       ][INFO    ][2053] Running state [nova_instances] at time 06:22:04.167125
2017-11-06 06:22:04,167 [salt.state       ][INFO    ][2053] Executing state glusterfs.created for nova_instances
2017-11-06 06:22:04,168 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:04,174 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:04,222 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:04,230 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:04,280 [salt.state       ][INFO    ][2053] Volume nova_instances already exists and is started.
2017-11-06 06:22:04,281 [salt.state       ][INFO    ][2053] Completed state [nova_instances] at time 06:22:04.280572 duration_in_ms=113.446
2017-11-06 06:22:04,283 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 06:22:04.283249
2017-11-06 06:22:04,283 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-06 06:22:04,284 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-06 06:22:04,332 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:04,332 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 06:22:04.331797 duration_in_ms=48.548
2017-11-06 06:22:04,332 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 06:22:04.332223
2017-11-06 06:22:04,332 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-11-06 06:22:04,333 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-11-06 06:22:04,378 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:04,379 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 06:22:04.378535 duration_in_ms=46.312
2017-11-06 06:22:04,379 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 06:22:04.378971
2017-11-06 06:22:04,379 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-11-06 06:22:04,380 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-11-06 06:22:04,426 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:04,426 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 06:22:04.425780 duration_in_ms=46.809
2017-11-06 06:22:04,426 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 06:22:04.426218
2017-11-06 06:22:04,426 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-11-06 06:22:04,427 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-11-06 06:22:04,473 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:04,474 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 06:22:04.473686 duration_in_ms=47.468
2017-11-06 06:22:04,474 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 06:22:04.474119
2017-11-06 06:22:04,474 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-06 06:22:04,475 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-06 06:22:04,524 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:04,524 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 06:22:04.524434 duration_in_ms=50.315
2017-11-06 06:22:04,526 [salt.state       ][INFO    ][2053] Running state [nova_instances] at time 06:22:04.525436
2017-11-06 06:22:04,526 [salt.state       ][INFO    ][2053] Executing state glusterfs.started for nova_instances
2017-11-06 06:22:04,526 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:04,534 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:04,580 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:04,588 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:04,640 [salt.state       ][INFO    ][2053] Volume nova_instances is already started
2017-11-06 06:22:04,640 [salt.state       ][INFO    ][2053] Completed state [nova_instances] at time 06:22:04.640247 duration_in_ms=114.811
2017-11-06 06:22:04,641 [salt.state       ][INFO    ][2053] Running state [keystone-credential-keys] at time 06:22:04.641068
2017-11-06 06:22:04,641 [salt.state       ][INFO    ][2053] Executing state glusterfs.created for keystone-credential-keys
2017-11-06 06:22:04,642 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:04,649 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:04,697 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:04,703 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:04,757 [salt.state       ][INFO    ][2053] Volume keystone-credential-keys already exists and is started.
2017-11-06 06:22:04,758 [salt.state       ][INFO    ][2053] Completed state [keystone-credential-keys] at time 06:22:04.757894 duration_in_ms=116.825
2017-11-06 06:22:04,762 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 06:22:04.761506
2017-11-06 06:22:04,762 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-06 06:22:04,763 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-06 06:22:04,811 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:04,811 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 06:22:04.810821 duration_in_ms=49.315
2017-11-06 06:22:04,811 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 06:22:04.811317
2017-11-06 06:22:04,812 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-06 06:22:04,812 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-06 06:22:04,859 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:04,860 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 06:22:04.859606 duration_in_ms=48.287
2017-11-06 06:22:04,860 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 06:22:04.860026
2017-11-06 06:22:04,860 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-06 06:22:04,861 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-06 06:22:04,908 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:04,908 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 06:22:04.908296 duration_in_ms=48.27
2017-11-06 06:22:04,909 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 06:22:04.908804
2017-11-06 06:22:04,909 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-06 06:22:04,910 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-06 06:22:04,956 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:04,957 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 06:22:04.956742 duration_in_ms=47.936
2017-11-06 06:22:04,957 [salt.state       ][INFO    ][2053] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 06:22:04.957199
2017-11-06 06:22:04,957 [salt.state       ][INFO    ][2053] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-06 06:22:04,958 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-06 06:22:05,005 [salt.state       ][INFO    ][2053] unless execution succeeded
2017-11-06 06:22:05,006 [salt.state       ][INFO    ][2053] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 06:22:05.005638 duration_in_ms=48.439
2017-11-06 06:22:05,007 [salt.state       ][INFO    ][2053] Running state [keystone-credential-keys] at time 06:22:05.006594
2017-11-06 06:22:05,007 [salt.state       ][INFO    ][2053] Executing state glusterfs.started for keystone-credential-keys
2017-11-06 06:22:05,007 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:05,014 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:05,061 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --version' in directory '/root'
2017-11-06 06:22:05,067 [salt.loaded.int.module.cmdmod][INFO    ][2053] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-06 06:22:05,117 [salt.state       ][INFO    ][2053] Volume keystone-credential-keys is already started
2017-11-06 06:22:05,117 [salt.state       ][INFO    ][2053] Completed state [keystone-credential-keys] at time 06:22:05.117129 duration_in_ms=110.535
2017-11-06 06:22:05,119 [salt.minion      ][INFO    ][2053] Returning information for job: 20171106062201460685
2017-11-06 06:22:09,977 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command test.ping with jid 20171106062209972164
2017-11-06 06:22:09,989 [salt.minion      ][INFO    ][2448] Starting a new job with PID 2448
2017-11-06 06:22:10,014 [salt.minion      ][INFO    ][2448] Returning information for job: 20171106062209972164
2017-11-06 06:22:10,410 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command cmd.run with jid 20171106062210402545
2017-11-06 06:22:10,423 [salt.minion      ][INFO    ][2453] Starting a new job with PID 2453
2017-11-06 06:22:10,427 [salt.loaded.int.module.cmdmod][INFO    ][2453] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-06 06:22:10,538 [salt.minion      ][INFO    ][2453] Returning information for job: 20171106062210402545
2017-11-06 06:45:51,502 [salt.utils.schedule][INFO    ][25116] Running scheduled job: __mine_interval
2017-11-06 06:52:37,223 [salt.minion      ][INFO    ][25116] User sudo_ubuntu Executing command cp.push_dir with jid 20171106065237215819
2017-11-06 06:52:37,247 [salt.minion      ][INFO    ][3023] Starting a new job with PID 3023
