2017-11-13 02:26:17,021 [salt.cli.daemons ][WARNING ][2086] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-13 02:26:31,476 [salt.state       ][ERROR   ][3638] No contents found in top file
2017-11-13 02:28:24,189 [salt.utils.parsers][WARNING ][2086] Minion received a SIGTERM. Exiting.
2017-11-13 02:32:49,546 [salt.cli.daemons ][WARNING ][3328] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-13 02:34:33,227 [salt.transport.ipc][ERROR   ][3328] Exception occurred while handling stream: [Errno 0] Success
2017-11-13 02:35:19,514 [py.warnings      ][WARNING ][15512] /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-13 02:35:46,020 [salt.loaded.int.module.cmdmod][ERROR   ][15512] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-13 02:35:46,021 [salt.loaded.int.module.cmdmod][ERROR   ][15512] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-13 02:35:50,198 [salt.loaded.int.module.cmdmod][ERROR   ][15512] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-13 02:35:50,199 [salt.loaded.int.module.cmdmod][ERROR   ][15512] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-13 02:35:54,744 [salt.loaded.int.module.cmdmod][ERROR   ][15512] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-13 02:35:54,745 [salt.loaded.int.module.cmdmod][ERROR   ][15512] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-13 02:35:59,023 [salt.loaded.int.module.cmdmod][ERROR   ][15512] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-13 02:35:59,024 [salt.loaded.int.module.cmdmod][ERROR   ][15512] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-13 02:36:03,156 [salt.loaded.int.module.cmdmod][ERROR   ][15512] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-13 02:36:03,156 [salt.loaded.int.module.cmdmod][ERROR   ][15512] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-13 02:36:06,568 [salt.loaded.int.module.cmdmod][ERROR   ][15512] 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-13 02:36:09,126 [salt.loaded.int.module.cmdmod][INFO    ][23033] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-13 02:36:09,133 [salt.loaded.int.module.cmdmod][INFO    ][23033] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-13 02:36:09,144 [salt.loaded.int.module.cmdmod][INFO    ][23033] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-11-13 02:36:09,152 [salt.utils.parsers][WARNING ][3328] Minion received a SIGTERM. Exiting.
2017-11-13 02:36:09,465 [salt.cli.daemons ][INFO    ][23124] Setting up the Salt Minion "kvm01.baremetal-mcp-ocata-ovs-ha.local"
2017-11-13 02:36:10,161 [salt.minion      ][INFO    ][23124] Creating minion process manager
2017-11-13 02:36:10,162 [salt.cli.daemons ][WARNING ][23124] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-13 02:36:10,162 [salt.cli.daemons ][INFO    ][23124] The Salt Minion is starting up
2017-11-13 02:36:10,162 [salt.minion      ][INFO    ][23124] Minion is starting as user 'root'
2017-11-13 02:36:10,163 [salt.utils.event ][INFO    ][23124] Starting pull socket on /var/run/salt/minion/minion_event_fd678c5357_pull.ipc
2017-11-13 02:36:11,288 [salt.loaded.int.module.cmdmod][INFO    ][23124] Executing command ['date', '+%z'] in directory '/root'
2017-11-13 02:36:11,302 [salt.utils.schedule][INFO    ][23124] Updating job settings for scheduled job: __mine_interval
2017-11-13 02:36:11,306 [salt.minion      ][INFO    ][23124] Added mine.update to scheduler
2017-11-13 02:36:11,330 [salt.minion      ][INFO    ][23124] Minion is ready to receive requests!
2017-11-13 02:36:12,331 [salt.utils.schedule][INFO    ][23124] Running scheduled job: __mine_interval
2017-11-13 02:36:19,067 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command saltutil.find_job with jid 20171113023619058764
2017-11-13 02:36:19,078 [salt.minion      ][INFO    ][23249] Starting a new job with PID 23249
2017-11-13 02:36:19,086 [salt.minion      ][INFO    ][23249] Returning information for job: 20171113023619058764
2017-11-13 02:36:30,092 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command state.sls with jid 20171113023630087292
2017-11-13 02:36:30,102 [salt.minion      ][INFO    ][23254] Starting a new job with PID 23254
2017-11-13 02:36:32,681 [salt.state       ][INFO    ][23254] Loading fresh modules for state activity
2017-11-13 02:36:32,717 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-11-13 02:36:32,863 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-11-13 02:36:32,891 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-13 02:36:32,978 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-11-13 02:36:32,994 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-11-13 02:36:33,021 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-13 02:36:33,147 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-13 02:36:33,164 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-13 02:36:33,217 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-13 02:36:33,540 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-13 02:36:33,552 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 02:36:33,569 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-13 02:36:33,578 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-13 02:36:33,597 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-13 02:36:33,615 [py.warnings      ][WARNING ][23254] /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-13 02:36:33,616 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command 'salt-minion --version' in directory '/root'
2017-11-13 02:36:33,873 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-13 02:36:33,895 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-13 02:36:33,909 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-13 02:36:33,923 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-13 02:36:33,978 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-13 02:36:34,018 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-13 02:36:34,085 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-11-13 02:36:34,111 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-13 02:36:34,171 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-13 02:36:34,190 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-13 02:36:34,245 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-13 02:36:34,270 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-13 02:36:34,285 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 02:36:34,302 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-13 02:36:34,310 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-13 02:36:34,330 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-13 02:36:34,348 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command 'salt-minion --version' in directory '/root'
2017-11-13 02:36:34,618 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-13 02:36:34,639 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-13 02:36:34,654 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-13 02:36:34,669 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-13 02:36:34,726 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-13 02:36:34,766 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-13 02:36:34,849 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-11-13 02:36:34,928 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-13 02:36:35,278 [salt.state       ][INFO    ][23254] Running state [salt-minion] at time 02:36:35.277851
2017-11-13 02:36:35,278 [salt.state       ][INFO    ][23254] Executing state pkg.installed for salt-minion
2017-11-13 02:36:35,279 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 02:36:35,565 [salt.state       ][INFO    ][23254] Package salt-minion is already installed
2017-11-13 02:36:35,566 [salt.state       ][INFO    ][23254] Completed state [salt-minion] at time 02:36:35.565520 duration_in_ms=287.669
2017-11-13 02:36:35,566 [salt.state       ][INFO    ][23254] Running state [salt_minion_dependency_packages] at time 02:36:35.565810
2017-11-13 02:36:35,566 [salt.state       ][INFO    ][23254] Executing state pkg.installed for salt_minion_dependency_packages
2017-11-13 02:36:35,570 [salt.state       ][INFO    ][23254] All specified packages are already installed
2017-11-13 02:36:35,570 [salt.state       ][INFO    ][23254] Completed state [salt_minion_dependency_packages] at time 02:36:35.570045 duration_in_ms=4.235
2017-11-13 02:36:35,572 [salt.state       ][INFO    ][23254] Running state [/etc/salt/minion.d/minion.conf] at time 02:36:35.571709
2017-11-13 02:36:35,572 [salt.state       ][INFO    ][23254] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-11-13 02:36:35,595 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-11-13 02:36:35,658 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-13 02:36:35,718 [salt.fileclient  ][INFO    ][23254] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-13 02:36:35,770 [salt.state       ][INFO    ][23254] File /etc/salt/minion.d/minion.conf is in the correct state
2017-11-13 02:36:35,770 [salt.state       ][INFO    ][23254] Completed state [/etc/salt/minion.d/minion.conf] at time 02:36:35.769739 duration_in_ms=198.03
2017-11-13 02:36:35,770 [salt.state       ][INFO    ][23254] Running state [/etc/salt/minion.d/_virt.conf] at time 02:36:35.770150
2017-11-13 02:36:35,770 [salt.state       ][INFO    ][23254] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-11-13 02:36:35,771 [salt.state       ][INFO    ][23254] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-11-13 02:36:35,771 [salt.state       ][INFO    ][23254] Completed state [/etc/salt/minion.d/_virt.conf] at time 02:36:35.771301 duration_in_ms=1.151
2017-11-13 02:36:35,773 [salt.state       ][INFO    ][23254] Running state [salt-minion] at time 02:36:35.773108
2017-11-13 02:36:35,773 [salt.state       ][INFO    ][23254] Executing state service.running for salt-minion
2017-11-13 02:36:35,774 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-13 02:36:35,788 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-11-13 02:36:35,795 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-13 02:36:35,801 [salt.state       ][INFO    ][23254] The service salt-minion is already running
2017-11-13 02:36:35,801 [salt.state       ][INFO    ][23254] Completed state [salt-minion] at time 02:36:35.800864 duration_in_ms=27.756
2017-11-13 02:36:35,803 [salt.state       ][INFO    ][23254] Running state [/etc/salt/grains.d] at time 02:36:35.802592
2017-11-13 02:36:35,803 [salt.state       ][INFO    ][23254] Executing state file.directory for /etc/salt/grains.d
2017-11-13 02:36:35,804 [salt.state       ][INFO    ][23254] Directory /etc/salt/grains.d is in the correct state
2017-11-13 02:36:35,804 [salt.state       ][INFO    ][23254] Completed state [/etc/salt/grains.d] at time 02:36:35.803692 duration_in_ms=1.101
2017-11-13 02:36:35,804 [salt.state       ][INFO    ][23254] Running state [/etc/salt/grains] at time 02:36:35.804313
2017-11-13 02:36:35,805 [salt.state       ][INFO    ][23254] Executing state file.managed for /etc/salt/grains
2017-11-13 02:36:35,805 [salt.state       ][INFO    ][23254] File /etc/salt/grains exists with proper permissions. No changes made.
2017-11-13 02:36:35,805 [salt.state       ][INFO    ][23254] Completed state [/etc/salt/grains] at time 02:36:35.805170 duration_in_ms=0.856
2017-11-13 02:36:35,806 [salt.state       ][INFO    ][23254] Running state [/etc/salt/grains.d/placeholder] at time 02:36:35.805567
2017-11-13 02:36:35,806 [salt.state       ][INFO    ][23254] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-11-13 02:36:35,806 [salt.state       ][INFO    ][23254] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-11-13 02:36:35,806 [salt.state       ][INFO    ][23254] Completed state [/etc/salt/grains.d/placeholder] at time 02:36:35.806357 duration_in_ms=0.791
2017-11-13 02:36:35,807 [salt.state       ][INFO    ][23254] Running state [/etc/salt/grains.d/sphinx] at time 02:36:35.806776
2017-11-13 02:36:35,807 [salt.state       ][INFO    ][23254] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-11-13 02:36:35,809 [salt.state       ][INFO    ][23254] File changed:
--- 
+++ 
@@ -49,7 +49,6 @@
               - 127.0.0.1
               - 192.168.10.141
               - 192.168.11.2
-              - 192.168.122.1
         system:
           name: System
           param:

2017-11-13 02:36:35,809 [salt.state       ][INFO    ][23254] Completed state [/etc/salt/grains.d/sphinx] at time 02:36:35.808746 duration_in_ms=1.97
2017-11-13 02:36:35,810 [salt.state       ][INFO    ][23254] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:36:35.809557
2017-11-13 02:36:35,810 [salt.state       ][INFO    ][23254] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-13 02:36:35,810 [salt.state       ][INFO    ][23254] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-13 02:36:35,810 [salt.state       ][INFO    ][23254] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:36:35.810177 duration_in_ms=0.62
2017-11-13 02:36:35,810 [salt.state       ][INFO    ][23254] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:36:35.810352
2017-11-13 02:36:35,811 [salt.state       ][INFO    ][23254] 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-13 02:36:35,811 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command 'python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"' in directory '/root'
2017-11-13 02:36:35,892 [salt.state       ][INFO    ][23254] {'pid': 23302, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-13 02:36:35,893 [salt.state       ][INFO    ][23254] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:36:35.892754 duration_in_ms=82.402
2017-11-13 02:36:35,893 [salt.state       ][INFO    ][23254] Running state [/etc/salt/grains.d/dns_records] at time 02:36:35.893385
2017-11-13 02:36:35,894 [salt.state       ][INFO    ][23254] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-11-13 02:36:35,895 [salt.state       ][INFO    ][23254] File /etc/salt/grains.d/dns_records is in the correct state
2017-11-13 02:36:35,895 [salt.state       ][INFO    ][23254] Completed state [/etc/salt/grains.d/dns_records] at time 02:36:35.894963 duration_in_ms=1.578
2017-11-13 02:36:35,896 [salt.state       ][INFO    ][23254] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:36:35.895904
2017-11-13 02:36:35,896 [salt.state       ][INFO    ][23254] 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-13 02:36:35,896 [salt.state       ][INFO    ][23254] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-13 02:36:35,897 [salt.state       ][INFO    ][23254] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:36:35.896568 duration_in_ms=0.664
2017-11-13 02:36:35,897 [salt.state       ][INFO    ][23254] Running state [/etc/salt/grains.d/salt] at time 02:36:35.896976
2017-11-13 02:36:35,897 [salt.state       ][INFO    ][23254] Executing state file.managed for /etc/salt/grains.d/salt
2017-11-13 02:36:35,898 [salt.state       ][INFO    ][23254] File /etc/salt/grains.d/salt is in the correct state
2017-11-13 02:36:35,898 [salt.state       ][INFO    ][23254] Completed state [/etc/salt/grains.d/salt] at time 02:36:35.898163 duration_in_ms=1.187
2017-11-13 02:36:35,899 [salt.state       ][INFO    ][23254] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:36:35.898953
2017-11-13 02:36:35,899 [salt.state       ][INFO    ][23254] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-13 02:36:35,899 [salt.state       ][INFO    ][23254] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-13 02:36:35,900 [salt.state       ][INFO    ][23254] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:36:35.899580 duration_in_ms=0.627
2017-11-13 02:36:35,901 [salt.state       ][INFO    ][23254] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:36:35.901341
2017-11-13 02:36:35,902 [salt.state       ][INFO    ][23254] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-13 02:36:35,902 [salt.state       ][INFO    ][23254] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-13 02:36:35,902 [salt.state       ][INFO    ][23254] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:36:35.902003 duration_in_ms=0.662
2017-11-13 02:36:35,902 [salt.state       ][INFO    ][23254] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:36:35.902180
2017-11-13 02:36:35,902 [salt.state       ][INFO    ][23254] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-13 02:36:35,904 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-11-13 02:36:35,911 [salt.state       ][INFO    ][23254] {'pid': 23304, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-13 02:36:35,911 [salt.state       ][INFO    ][23254] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:36:35.911284 duration_in_ms=9.103
2017-11-13 02:36:35,912 [salt.state       ][INFO    ][23254] Running state [mine.update] at time 02:36:35.912021
2017-11-13 02:36:35,912 [salt.state       ][INFO    ][23254] Executing state module.wait for mine.update
2017-11-13 02:36:35,913 [salt.state       ][INFO    ][23254] No changes made for mine.update
2017-11-13 02:36:35,913 [salt.state       ][INFO    ][23254] Completed state [mine.update] at time 02:36:35.912686 duration_in_ms=0.665
2017-11-13 02:36:35,913 [salt.state       ][INFO    ][23254] Running state [mine.update] at time 02:36:35.912863
2017-11-13 02:36:35,913 [salt.state       ][INFO    ][23254] Executing state module.mod_watch for mine.update
2017-11-13 02:36:36,428 [salt.state       ][INFO    ][23254] {'ret': True}
2017-11-13 02:36:36,428 [salt.state       ][INFO    ][23254] Completed state [mine.update] at time 02:36:36.428235 duration_in_ms=515.371
2017-11-13 02:36:36,429 [salt.state       ][INFO    ][23254] Running state [ca-certificates] at time 02:36:36.428513
2017-11-13 02:36:36,429 [salt.state       ][INFO    ][23254] Executing state pkg.installed for ca-certificates
2017-11-13 02:36:36,433 [salt.state       ][INFO    ][23254] Package ca-certificates is already installed
2017-11-13 02:36:36,433 [salt.state       ][INFO    ][23254] Completed state [ca-certificates] at time 02:36:36.432934 duration_in_ms=4.421
2017-11-13 02:36:36,434 [salt.state       ][INFO    ][23254] Running state [update-ca-certificates] at time 02:36:36.433620
2017-11-13 02:36:36,434 [salt.state       ][INFO    ][23254] Executing state cmd.wait for update-ca-certificates
2017-11-13 02:36:36,434 [salt.state       ][INFO    ][23254] No changes made for update-ca-certificates
2017-11-13 02:36:36,434 [salt.state       ][INFO    ][23254] Completed state [update-ca-certificates] at time 02:36:36.434204 duration_in_ms=0.584
2017-11-13 02:36:36,434 [salt.state       ][INFO    ][23254] Running state [libvirt-dev] at time 02:36:36.434385
2017-11-13 02:36:36,435 [salt.state       ][INFO    ][23254] Executing state pkg.installed for libvirt-dev
2017-11-13 02:36:36,438 [salt.state       ][INFO    ][23254] Package libvirt-dev is already installed
2017-11-13 02:36:36,439 [salt.state       ][INFO    ][23254] Completed state [libvirt-dev] at time 02:36:36.438457 duration_in_ms=4.072
2017-11-13 02:36:36,439 [salt.state       ][INFO    ][23254] Running state [pkg-config] at time 02:36:36.438650
2017-11-13 02:36:36,439 [salt.state       ][INFO    ][23254] Executing state pkg.installed for pkg-config
2017-11-13 02:36:36,443 [salt.state       ][INFO    ][23254] Package pkg-config is already installed
2017-11-13 02:36:36,443 [salt.state       ][INFO    ][23254] Completed state [pkg-config] at time 02:36:36.442814 duration_in_ms=4.164
2017-11-13 02:36:36,443 [salt.state       ][INFO    ][23254] Running state [virtng.init] at time 02:36:36.442998
2017-11-13 02:36:36,443 [salt.state       ][INFO    ][23254] Executing state module.run for virtng.init
2017-11-13 02:36:36,485 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command ['git', '--version'] in directory '/root'
2017-11-13 02:36:36,562 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command 'virsh list --all --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-13 02:36:36,579 [salt.state       ][INFO    ][23254] unless execution succeeded
2017-11-13 02:36:36,580 [salt.state       ][INFO    ][23254] Completed state [virtng.init] at time 02:36:36.579701 duration_in_ms=136.702
2017-11-13 02:36:36,580 [salt.state       ][INFO    ][23254] Running state [virt.set_autostart] at time 02:36:36.579975
2017-11-13 02:36:36,580 [salt.state       ][INFO    ][23254] Executing state module.run for virt.set_autostart
2017-11-13 02:36:36,583 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command 'virsh list --autostart --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-13 02:36:36,598 [salt.state       ][INFO    ][23254] unless execution succeeded
2017-11-13 02:36:36,598 [salt.state       ][INFO    ][23254] Completed state [virt.set_autostart] at time 02:36:36.598317 duration_in_ms=18.342
2017-11-13 02:36:36,599 [salt.state       ][INFO    ][23254] Running state [virtng.init] at time 02:36:36.598572
2017-11-13 02:36:36,599 [salt.state       ][INFO    ][23254] Executing state module.run for virtng.init
2017-11-13 02:36:36,602 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command 'virsh list --all --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-13 02:36:36,617 [salt.state       ][INFO    ][23254] unless execution succeeded
2017-11-13 02:36:36,617 [salt.state       ][INFO    ][23254] Completed state [virtng.init] at time 02:36:36.616943 duration_in_ms=18.37
2017-11-13 02:36:36,617 [salt.state       ][INFO    ][23254] Running state [virt.set_autostart] at time 02:36:36.617201
2017-11-13 02:36:36,617 [salt.state       ][INFO    ][23254] Executing state module.run for virt.set_autostart
2017-11-13 02:36:36,620 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command 'virsh list --autostart --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-13 02:36:36,636 [salt.state       ][INFO    ][23254] unless execution succeeded
2017-11-13 02:36:36,636 [salt.state       ][INFO    ][23254] Completed state [virt.set_autostart] at time 02:36:36.635846 duration_in_ms=18.644
2017-11-13 02:36:36,636 [salt.state       ][INFO    ][23254] Running state [virtng.init] at time 02:36:36.636107
2017-11-13 02:36:36,636 [salt.state       ][INFO    ][23254] Executing state module.run for virtng.init
2017-11-13 02:36:36,639 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command 'virsh list --all --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-13 02:36:36,654 [salt.state       ][INFO    ][23254] unless execution succeeded
2017-11-13 02:36:36,654 [salt.state       ][INFO    ][23254] Completed state [virtng.init] at time 02:36:36.654107 duration_in_ms=18.0
2017-11-13 02:36:36,654 [salt.state       ][INFO    ][23254] Running state [virt.set_autostart] at time 02:36:36.654377
2017-11-13 02:36:36,655 [salt.state       ][INFO    ][23254] Executing state module.run for virt.set_autostart
2017-11-13 02:36:36,657 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command 'virsh list --autostart --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-13 02:36:36,671 [salt.state       ][INFO    ][23254] unless execution succeeded
2017-11-13 02:36:36,671 [salt.state       ][INFO    ][23254] Completed state [virt.set_autostart] at time 02:36:36.671457 duration_in_ms=17.08
2017-11-13 02:36:36,672 [salt.state       ][INFO    ][23254] Running state [virtng.init] at time 02:36:36.671703
2017-11-13 02:36:36,672 [salt.state       ][INFO    ][23254] Executing state module.run for virtng.init
2017-11-13 02:36:36,675 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command 'virsh list --all --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-13 02:36:36,689 [salt.state       ][INFO    ][23254] unless execution succeeded
2017-11-13 02:36:36,689 [salt.state       ][INFO    ][23254] Completed state [virtng.init] at time 02:36:36.688799 duration_in_ms=17.096
2017-11-13 02:36:36,689 [salt.state       ][INFO    ][23254] Running state [virt.set_autostart] at time 02:36:36.689061
2017-11-13 02:36:36,689 [salt.state       ][INFO    ][23254] Executing state module.run for virt.set_autostart
2017-11-13 02:36:36,692 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command 'virsh list --autostart --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-13 02:36:36,707 [salt.state       ][INFO    ][23254] unless execution succeeded
2017-11-13 02:36:36,707 [salt.state       ][INFO    ][23254] Completed state [virt.set_autostart] at time 02:36:36.707422 duration_in_ms=18.361
2017-11-13 02:36:36,708 [salt.state       ][INFO    ][23254] Running state [virtng.init] at time 02:36:36.707684
2017-11-13 02:36:36,708 [salt.state       ][INFO    ][23254] Executing state module.run for virtng.init
2017-11-13 02:36:36,711 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command 'virsh list --all --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-13 02:36:36,725 [salt.state       ][INFO    ][23254] unless execution succeeded
2017-11-13 02:36:36,726 [salt.state       ][INFO    ][23254] Completed state [virtng.init] at time 02:36:36.725698 duration_in_ms=18.014
2017-11-13 02:36:36,726 [salt.state       ][INFO    ][23254] Running state [virt.set_autostart] at time 02:36:36.725955
2017-11-13 02:36:36,726 [salt.state       ][INFO    ][23254] Executing state module.run for virt.set_autostart
2017-11-13 02:36:36,729 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command 'virsh list --autostart --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-13 02:36:36,743 [salt.state       ][INFO    ][23254] unless execution succeeded
2017-11-13 02:36:36,743 [salt.state       ][INFO    ][23254] Completed state [virt.set_autostart] at time 02:36:36.743432 duration_in_ms=17.475
2017-11-13 02:36:36,746 [salt.minion      ][INFO    ][23254] Returning information for job: 20171113023630087292
2017-11-13 02:36:37,581 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command pillar.get with jid 20171113023637569966
2017-11-13 02:36:37,591 [salt.minion      ][INFO    ][23357] Starting a new job with PID 23357
2017-11-13 02:36:37,593 [salt.minion      ][INFO    ][23357] Returning information for job: 20171113023637569966
2017-11-13 02:37:03,567 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171113023703556664
2017-11-13 02:37:03,579 [salt.minion      ][INFO    ][23373] Starting a new job with PID 23373
2017-11-13 02:37:07,572 [salt.state       ][INFO    ][23373] Loading fresh modules for state activity
2017-11-13 02:37:07,965 [salt.state       ][ERROR   ][23373] No contents found in top file
2017-11-13 02:37:07,966 [salt.utils.extmods][INFO    ][23373] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-11-13 02:37:07,969 [salt.utils.extmods][INFO    ][23373] Syncing beacons for environment 'base'
2017-11-13 02:37:07,970 [salt.utils.extmods][INFO    ][23373] Loading cache from salt://_beacons, for base)
2017-11-13 02:37:07,970 [salt.fileclient  ][INFO    ][23373] Caching directory '_beacons/' for environment 'base'
2017-11-13 02:37:08,062 [salt.utils.extmods][INFO    ][23373] Syncing modules for environment 'base'
2017-11-13 02:37:08,063 [salt.utils.extmods][INFO    ][23373] Loading cache from salt://_modules, for base)
2017-11-13 02:37:08,063 [salt.fileclient  ][INFO    ][23373] Caching directory '_modules/' for environment 'base'
2017-11-13 02:37:08,129 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-11-13 02:37:08,238 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-11-13 02:37:08,251 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-11-13 02:37:08,266 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-11-13 02:37:08,283 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-11-13 02:37:08,291 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-11-13 02:37:08,457 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-11-13 02:37:08,474 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-11-13 02:37:08,491 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-11-13 02:37:08,506 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-11-13 02:37:08,520 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-11-13 02:37:08,531 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-11-13 02:37:08,548 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-11-13 02:37:08,630 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-11-13 02:37:08,649 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-11-13 02:37:08,680 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-11-13 02:37:08,695 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-11-13 02:37:08,723 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-11-13 02:37:08,743 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-11-13 02:37:08,755 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-11-13 02:37:08,768 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-11-13 02:37:08,781 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-11-13 02:37:08,799 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-11-13 02:37:08,812 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-11-13 02:37:08,824 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-11-13 02:37:08,843 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-11-13 02:37:08,859 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-11-13 02:37:08,870 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-11-13 02:37:08,889 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-11-13 02:37:08,909 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-11-13 02:37:08,918 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-11-13 02:37:08,929 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-11-13 02:37:08,954 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-11-13 02:37:08,958 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-11-13 02:37:08,958 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-11-13 02:37:08,959 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-11-13 02:37:08,959 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-11-13 02:37:08,959 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-11-13 02:37:08,960 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-11-13 02:37:08,960 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-11-13 02:37:08,960 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-11-13 02:37:08,960 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-11-13 02:37:08,961 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-11-13 02:37:08,961 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-11-13 02:37:08,961 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-11-13 02:37:08,962 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-11-13 02:37:08,962 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-11-13 02:37:08,962 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-11-13 02:37:08,962 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-11-13 02:37:08,963 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-11-13 02:37:08,963 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-11-13 02:37:08,963 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-11-13 02:37:08,963 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-11-13 02:37:08,964 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-11-13 02:37:08,964 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-11-13 02:37:08,964 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-11-13 02:37:08,965 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-11-13 02:37:08,965 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-11-13 02:37:08,965 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-11-13 02:37:08,965 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-11-13 02:37:08,966 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-11-13 02:37:08,966 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-11-13 02:37:08,966 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-11-13 02:37:08,966 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-11-13 02:37:08,967 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-11-13 02:37:08,967 [salt.utils.extmods][INFO    ][23373] 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-13 02:37:08,967 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-11-13 02:37:08,967 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-11-13 02:37:08,973 [salt.utils.extmods][INFO    ][23373] Syncing states for environment 'base'
2017-11-13 02:37:08,973 [salt.utils.extmods][INFO    ][23373] Loading cache from salt://_states, for base)
2017-11-13 02:37:08,974 [salt.fileclient  ][INFO    ][23373] Caching directory '_states/' for environment 'base'
2017-11-13 02:37:09,074 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-11-13 02:37:09,094 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-11-13 02:37:09,116 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-11-13 02:37:09,140 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-11-13 02:37:09,197 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-11-13 02:37:09,210 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-11-13 02:37:09,230 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-11-13 02:37:09,261 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-11-13 02:37:09,271 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-11-13 02:37:09,288 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-11-13 02:37:09,317 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-11-13 02:37:09,340 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-11-13 02:37:09,357 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-11-13 02:37:09,390 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-11-13 02:37:09,411 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-11-13 02:37:09,459 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-11-13 02:37:09,482 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-11-13 02:37:09,501 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-11-13 02:37:09,531 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-11-13 02:37:09,542 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-11-13 02:37:09,581 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-11-13 02:37:09,594 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-11-13 02:37:09,603 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-11-13 02:37:09,626 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-11-13 02:37:09,645 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-11-13 02:37:09,659 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-11-13 02:37:09,670 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-11-13 02:37:09,715 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-11-13 02:37:09,749 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-11-13 02:37:09,754 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-11-13 02:37:09,754 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-11-13 02:37:09,755 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-11-13 02:37:09,755 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-11-13 02:37:09,755 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-11-13 02:37:09,755 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-11-13 02:37:09,756 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-11-13 02:37:09,756 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-11-13 02:37:09,756 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-11-13 02:37:09,756 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-11-13 02:37:09,757 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-11-13 02:37:09,757 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-11-13 02:37:09,757 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-11-13 02:37:09,757 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-11-13 02:37:09,758 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-11-13 02:37:09,758 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-11-13 02:37:09,758 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-11-13 02:37:09,758 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-11-13 02:37:09,759 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-11-13 02:37:09,759 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-11-13 02:37:09,759 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-11-13 02:37:09,759 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-11-13 02:37:09,760 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-11-13 02:37:09,760 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-11-13 02:37:09,760 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-11-13 02:37:09,761 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-11-13 02:37:09,761 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-11-13 02:37:09,761 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-11-13 02:37:09,761 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-11-13 02:37:09,762 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-11-13 02:37:09,764 [salt.utils.extmods][INFO    ][23373] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-11-13 02:37:09,767 [salt.utils.extmods][INFO    ][23373] Syncing sdb for environment 'base'
2017-11-13 02:37:09,767 [salt.utils.extmods][INFO    ][23373] Loading cache from salt://_sdb, for base)
2017-11-13 02:37:09,767 [salt.fileclient  ][INFO    ][23373] Caching directory '_sdb/' for environment 'base'
2017-11-13 02:37:09,831 [salt.utils.extmods][INFO    ][23373] Syncing grains for environment 'base'
2017-11-13 02:37:09,831 [salt.utils.extmods][INFO    ][23373] Loading cache from salt://_grains, for base)
2017-11-13 02:37:09,832 [salt.fileclient  ][INFO    ][23373] Caching directory '_grains/' for environment 'base'
2017-11-13 02:37:09,893 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-11-13 02:37:09,908 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-11-13 02:37:09,933 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-11-13 02:37:09,948 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-11-13 02:37:09,963 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-11-13 02:37:09,978 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-11-13 02:37:09,998 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-11-13 02:37:10,017 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-11-13 02:37:10,050 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-11-13 02:37:10,076 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-11-13 02:37:10,091 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-11-13 02:37:10,100 [salt.fileclient  ][INFO    ][23373] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-11-13 02:37:10,105 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-11-13 02:37:10,105 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-11-13 02:37:10,105 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-11-13 02:37:10,105 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-11-13 02:37:10,106 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-11-13 02:37:10,106 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-11-13 02:37:10,106 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-11-13 02:37:10,106 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-11-13 02:37:10,107 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-11-13 02:37:10,107 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-11-13 02:37:10,107 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-11-13 02:37:10,107 [salt.utils.extmods][INFO    ][23373] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-11-13 02:37:10,109 [salt.utils.extmods][INFO    ][23373] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-11-13 02:37:10,112 [salt.utils.extmods][INFO    ][23373] Syncing renderers for environment 'base'
2017-11-13 02:37:10,112 [salt.utils.extmods][INFO    ][23373] Loading cache from salt://_renderers, for base)
2017-11-13 02:37:10,112 [salt.fileclient  ][INFO    ][23373] Caching directory '_renderers/' for environment 'base'
2017-11-13 02:37:10,209 [salt.utils.extmods][INFO    ][23373] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-11-13 02:37:10,212 [salt.utils.extmods][INFO    ][23373] Syncing returners for environment 'base'
2017-11-13 02:37:10,212 [salt.utils.extmods][INFO    ][23373] Loading cache from salt://_returners, for base)
2017-11-13 02:37:10,212 [salt.fileclient  ][INFO    ][23373] Caching directory '_returners/' for environment 'base'
2017-11-13 02:37:10,259 [salt.utils.extmods][INFO    ][23373] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-11-13 02:37:10,262 [salt.utils.extmods][INFO    ][23373] Syncing output for environment 'base'
2017-11-13 02:37:10,263 [salt.utils.extmods][INFO    ][23373] Loading cache from salt://_output, for base)
2017-11-13 02:37:10,263 [salt.fileclient  ][INFO    ][23373] Caching directory '_output/' for environment 'base'
2017-11-13 02:37:10,311 [salt.utils.extmods][INFO    ][23373] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-11-13 02:37:10,313 [salt.utils.extmods][INFO    ][23373] Syncing utils for environment 'base'
2017-11-13 02:37:10,313 [salt.utils.extmods][INFO    ][23373] Loading cache from salt://_utils, for base)
2017-11-13 02:37:10,314 [salt.fileclient  ][INFO    ][23373] Caching directory '_utils/' for environment 'base'
2017-11-13 02:37:10,400 [salt.utils.extmods][INFO    ][23373] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-11-13 02:37:10,403 [salt.utils.extmods][INFO    ][23373] Syncing log_handlers for environment 'base'
2017-11-13 02:37:10,403 [salt.utils.extmods][INFO    ][23373] Loading cache from salt://_log_handlers, for base)
2017-11-13 02:37:10,403 [salt.fileclient  ][INFO    ][23373] Caching directory '_log_handlers/' for environment 'base'
2017-11-13 02:37:10,452 [salt.utils.extmods][INFO    ][23373] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-11-13 02:37:10,455 [salt.utils.extmods][INFO    ][23373] Syncing proxy for environment 'base'
2017-11-13 02:37:10,455 [salt.utils.extmods][INFO    ][23373] Loading cache from salt://_proxy, for base)
2017-11-13 02:37:10,455 [salt.fileclient  ][INFO    ][23373] Caching directory '_proxy/' for environment 'base'
2017-11-13 02:37:10,532 [salt.utils.extmods][INFO    ][23373] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-11-13 02:37:10,535 [salt.utils.extmods][INFO    ][23373] Syncing engines for environment 'base'
2017-11-13 02:37:10,535 [salt.utils.extmods][INFO    ][23373] Loading cache from salt://_engines, for base)
2017-11-13 02:37:10,536 [salt.fileclient  ][INFO    ][23373] Caching directory '_engines/' for environment 'base'
2017-11-13 02:37:10,596 [salt.minion      ][INFO    ][23373] Returning information for job: 20171113023703556664
2017-11-13 02:39:28,294 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command test.ping with jid 20171113023928286825
2017-11-13 02:39:28,306 [salt.minion      ][INFO    ][23495] Starting a new job with PID 23495
2017-11-13 02:39:28,341 [salt.minion      ][INFO    ][23495] Returning information for job: 20171113023928286825
2017-11-13 02:41:08,515 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command state.sls with jid 20171113024108500608
2017-11-13 02:41:08,527 [salt.minion      ][INFO    ][23515] Starting a new job with PID 23515
2017-11-13 02:41:09,105 [salt.state       ][INFO    ][23515] Loading fresh modules for state activity
2017-11-13 02:41:09,136 [salt.fileclient  ][INFO    ][23515] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-13 02:41:09,183 [salt.fileclient  ][INFO    ][23515] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-13 02:41:09,208 [salt.fileclient  ][INFO    ][23515] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 02:41:09,534 [salt.state       ][INFO    ][23515] Running state [lsof] at time 02:41:09.533802
2017-11-13 02:41:09,534 [salt.state       ][INFO    ][23515] Executing state pkg.installed for lsof
2017-11-13 02:41:09,535 [salt.loaded.int.module.cmdmod][INFO    ][23515] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 02:41:09,790 [salt.state       ][INFO    ][23515] Package lsof is already installed
2017-11-13 02:41:09,790 [salt.state       ][INFO    ][23515] Completed state [lsof] at time 02:41:09.790091 duration_in_ms=256.289
2017-11-13 02:41:09,790 [salt.state       ][INFO    ][23515] Running state [keepalived] at time 02:41:09.790370
2017-11-13 02:41:09,791 [salt.state       ][INFO    ][23515] Executing state pkg.installed for keepalived
2017-11-13 02:41:09,809 [salt.loaded.int.module.cmdmod][INFO    ][23515] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-13 02:41:11,184 [salt.loaded.int.module.cmdmod][INFO    ][23515] 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-13 02:41:13,803 [salt.loaded.int.module.cmdmod][INFO    ][23515] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 02:41:13,831 [salt.state       ][INFO    ][23515] 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-13 02:41:13,842 [salt.state       ][INFO    ][23515] Loading fresh modules for state activity
2017-11-13 02:41:13,858 [salt.state       ][INFO    ][23515] Completed state [keepalived] at time 02:41:13.857895 duration_in_ms=4067.524
2017-11-13 02:41:13,860 [salt.state       ][INFO    ][23515] Running state [/etc/keepalived/keepalived.conf] at time 02:41:13.859946
2017-11-13 02:41:13,860 [salt.state       ][INFO    ][23515] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-13 02:41:13,889 [salt.fileclient  ][INFO    ][23515] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-13 02:41:13,928 [salt.fileclient  ][INFO    ][23515] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 02:41:13,936 [salt.state       ][INFO    ][23515] File changed:
New file
2017-11-13 02:41:13,936 [salt.state       ][INFO    ][23515] Completed state [/etc/keepalived/keepalived.conf] at time 02:41:13.936222 duration_in_ms=76.276
2017-11-13 02:41:14,005 [salt.state       ][INFO    ][23515] Running state [keepalived] at time 02:41:14.005420
2017-11-13 02:41:14,006 [salt.state       ][INFO    ][23515] Executing state service.running for keepalived
2017-11-13 02:41:14,007 [salt.loaded.int.module.cmdmod][INFO    ][23515] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-13 02:41:14,017 [salt.loaded.int.module.cmdmod][INFO    ][23515] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-13 02:41:14,025 [salt.loaded.int.module.cmdmod][INFO    ][23515] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-13 02:41:14,031 [salt.loaded.int.module.cmdmod][INFO    ][23515] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-13 02:41:14,041 [salt.loaded.int.module.cmdmod][INFO    ][23515] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-11-13 02:41:14,073 [salt.loaded.int.module.cmdmod][INFO    ][23515] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-13 02:41:14,083 [salt.loaded.int.module.cmdmod][INFO    ][23515] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-13 02:41:14,090 [salt.loaded.int.module.cmdmod][INFO    ][23515] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-13 02:41:14,097 [salt.state       ][INFO    ][23515] {'keepalived': True}
2017-11-13 02:41:14,097 [salt.state       ][INFO    ][23515] Completed state [keepalived] at time 02:41:14.097423 duration_in_ms=92.005
2017-11-13 02:41:14,098 [salt.minion      ][INFO    ][23515] Returning information for job: 20171113024108500608
2017-11-13 02:41:23,410 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command pillar.get with jid 20171113024123398159
2017-11-13 02:41:23,425 [salt.minion      ][INFO    ][25070] Starting a new job with PID 25070
2017-11-13 02:41:23,429 [salt.minion      ][INFO    ][25070] Returning information for job: 20171113024123398159
2017-11-13 02:46:30,287 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command state.sls with jid 20171113024630273839
2017-11-13 02:46:30,298 [salt.minion      ][INFO    ][25166] Starting a new job with PID 25166
2017-11-13 02:46:32,933 [salt.state       ][INFO    ][25166] Loading fresh modules for state activity
2017-11-13 02:46:32,958 [salt.fileclient  ][INFO    ][25166] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-13 02:46:32,984 [salt.fileclient  ][INFO    ][25166] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 02:46:33,280 [salt.state       ][INFO    ][25166] Running state [attr] at time 02:46:33.279504
2017-11-13 02:46:33,280 [salt.state       ][INFO    ][25166] Executing state pkg.installed for attr
2017-11-13 02:46:33,280 [salt.loaded.int.module.cmdmod][INFO    ][25166] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 02:46:33,533 [salt.loaded.int.module.cmdmod][INFO    ][25166] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-13 02:46:35,475 [salt.loaded.int.module.cmdmod][INFO    ][25166] 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-13 02:46:36,850 [salt.loaded.int.module.cmdmod][INFO    ][25166] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 02:46:36,877 [salt.state       ][INFO    ][25166] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-11-13 02:46:36,888 [salt.state       ][INFO    ][25166] Loading fresh modules for state activity
2017-11-13 02:46:36,904 [salt.state       ][INFO    ][25166] Completed state [attr] at time 02:46:36.903483 duration_in_ms=3623.979
2017-11-13 02:46:36,909 [salt.state       ][INFO    ][25166] Running state [glusterfs-server] at time 02:46:36.909095
2017-11-13 02:46:36,909 [salt.state       ][INFO    ][25166] Executing state pkg.installed for glusterfs-server
2017-11-13 02:46:37,126 [salt.loaded.int.module.cmdmod][INFO    ][25166] 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-13 02:46:40,383 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command saltutil.find_job with jid 20171113024640370298
2017-11-13 02:46:40,393 [salt.minion      ][INFO    ][28987] Starting a new job with PID 28987
2017-11-13 02:46:40,405 [salt.minion      ][INFO    ][28987] Returning information for job: 20171113024640370298
2017-11-13 02:46:43,062 [salt.loaded.int.module.cmdmod][INFO    ][25166] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 02:46:43,089 [salt.state       ][INFO    ][25166] 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-13 02:46:43,100 [salt.state       ][INFO    ][25166] Loading fresh modules for state activity
2017-11-13 02:46:43,117 [salt.state       ][INFO    ][25166] Completed state [glusterfs-server] at time 02:46:43.116730 duration_in_ms=6207.634
2017-11-13 02:46:43,122 [salt.state       ][INFO    ][25166] Running state [bc] at time 02:46:43.122393
2017-11-13 02:46:43,123 [salt.state       ][INFO    ][25166] Executing state pkg.installed for bc
2017-11-13 02:46:43,410 [salt.loaded.int.module.cmdmod][INFO    ][25166] 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-13 02:46:44,820 [salt.loaded.int.module.cmdmod][INFO    ][25166] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 02:46:44,848 [salt.state       ][INFO    ][25166] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-11-13 02:46:44,858 [salt.state       ][INFO    ][25166] Loading fresh modules for state activity
2017-11-13 02:46:44,877 [salt.state       ][INFO    ][25166] Completed state [bc] at time 02:46:44.876512 duration_in_ms=1754.118
2017-11-13 02:46:44,948 [salt.state       ][INFO    ][25166] Running state [glusterfs-server] at time 02:46:44.947933
2017-11-13 02:46:44,948 [salt.state       ][INFO    ][25166] Executing state service.running for glusterfs-server
2017-11-13 02:46:44,950 [salt.loaded.int.module.cmdmod][INFO    ][25166] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-13 02:46:44,959 [salt.loaded.int.module.cmdmod][INFO    ][25166] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-13 02:46:44,967 [salt.loaded.int.module.cmdmod][INFO    ][25166] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-13 02:46:44,976 [salt.state       ][INFO    ][25166] The service glusterfs-server is already running
2017-11-13 02:46:44,976 [salt.state       ][INFO    ][25166] Completed state [glusterfs-server] at time 02:46:44.976129 duration_in_ms=28.206
2017-11-13 02:46:44,978 [salt.state       ][INFO    ][25166] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 02:46:44.977869
2017-11-13 02:46:44,978 [salt.state       ][INFO    ][25166] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-13 02:46:44,983 [salt.state       ][INFO    ][25166] File changed:
New file
2017-11-13 02:46:44,983 [salt.state       ][INFO    ][25166] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 02:46:44.983338 duration_in_ms=5.468
2017-11-13 02:46:44,984 [salt.state       ][INFO    ][25166] Running state [/srv/glusterfs/glance] at time 02:46:44.983534
2017-11-13 02:46:44,984 [salt.state       ][INFO    ][25166] Executing state file.directory for /srv/glusterfs/glance
2017-11-13 02:46:44,984 [salt.state       ][INFO    ][25166] {'/srv/glusterfs/glance': 'New Dir'}
2017-11-13 02:46:44,985 [salt.state       ][INFO    ][25166] Completed state [/srv/glusterfs/glance] at time 02:46:44.984559 duration_in_ms=1.025
2017-11-13 02:46:44,985 [salt.state       ][INFO    ][25166] Running state [/srv/glusterfs/keystone-keys] at time 02:46:44.984751
2017-11-13 02:46:44,985 [salt.state       ][INFO    ][25166] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-13 02:46:44,985 [salt.state       ][INFO    ][25166] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-11-13 02:46:44,986 [salt.state       ][INFO    ][25166] Completed state [/srv/glusterfs/keystone-keys] at time 02:46:44.985640 duration_in_ms=0.889
2017-11-13 02:46:44,986 [salt.state       ][INFO    ][25166] Running state [/srv/glusterfs/nova_instances] at time 02:46:44.985817
2017-11-13 02:46:44,986 [salt.state       ][INFO    ][25166] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-13 02:46:44,986 [salt.state       ][INFO    ][25166] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-11-13 02:46:44,987 [salt.state       ][INFO    ][25166] Completed state [/srv/glusterfs/nova_instances] at time 02:46:44.986616 duration_in_ms=0.8
2017-11-13 02:46:44,987 [salt.state       ][INFO    ][25166] Running state [/srv/glusterfs/keystone-credential-keys] at time 02:46:44.986790
2017-11-13 02:46:44,987 [salt.state       ][INFO    ][25166] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-13 02:46:44,987 [salt.state       ][INFO    ][25166] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-11-13 02:46:44,988 [salt.state       ][INFO    ][25166] Completed state [/srv/glusterfs/keystone-credential-keys] at time 02:46:44.987565 duration_in_ms=0.775
2017-11-13 02:46:44,988 [salt.minion      ][INFO    ][25166] Returning information for job: 20171113024630273839
2017-11-13 02:46:46,098 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command test.ping with jid 20171113024646085850
2017-11-13 02:46:46,109 [salt.minion      ][INFO    ][29915] Starting a new job with PID 29915
2017-11-13 02:46:46,144 [salt.minion      ][INFO    ][29915] Returning information for job: 20171113024646085850
2017-11-13 02:47:13,800 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command state.sls with jid 20171113024713788266
2017-11-13 02:47:13,814 [salt.minion      ][INFO    ][30128] Starting a new job with PID 30128
2017-11-13 02:47:14,362 [salt.state       ][INFO    ][30128] Loading fresh modules for state activity
2017-11-13 02:47:14,387 [salt.fileclient  ][INFO    ][30128] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-13 02:47:14,429 [salt.fileclient  ][INFO    ][30128] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 02:47:14,505 [salt.fileclient  ][INFO    ][30128] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-13 02:47:14,528 [salt.fileclient  ][INFO    ][30128] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 02:47:14,829 [salt.state       ][INFO    ][30128] Running state [attr] at time 02:47:14.828736
2017-11-13 02:47:14,829 [salt.state       ][INFO    ][30128] Executing state pkg.installed for attr
2017-11-13 02:47:14,830 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 02:47:15,060 [salt.state       ][INFO    ][30128] Package attr is already installed
2017-11-13 02:47:15,060 [salt.state       ][INFO    ][30128] Completed state [attr] at time 02:47:15.059870 duration_in_ms=231.122
2017-11-13 02:47:15,060 [salt.state       ][INFO    ][30128] Running state [glusterfs-server] at time 02:47:15.060126
2017-11-13 02:47:15,060 [salt.state       ][INFO    ][30128] Executing state pkg.installed for glusterfs-server
2017-11-13 02:47:15,064 [salt.state       ][INFO    ][30128] Package glusterfs-server is already installed
2017-11-13 02:47:15,064 [salt.state       ][INFO    ][30128] Completed state [glusterfs-server] at time 02:47:15.064129 duration_in_ms=4.002
2017-11-13 02:47:15,064 [salt.state       ][INFO    ][30128] Running state [bc] at time 02:47:15.064319
2017-11-13 02:47:15,065 [salt.state       ][INFO    ][30128] Executing state pkg.installed for bc
2017-11-13 02:47:15,068 [salt.state       ][INFO    ][30128] Package bc is already installed
2017-11-13 02:47:15,068 [salt.state       ][INFO    ][30128] Completed state [bc] at time 02:47:15.068192 duration_in_ms=3.872
2017-11-13 02:47:15,069 [salt.state       ][INFO    ][30128] Running state [glusterfs-server] at time 02:47:15.069152
2017-11-13 02:47:15,069 [salt.state       ][INFO    ][30128] Executing state service.running for glusterfs-server
2017-11-13 02:47:15,070 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-13 02:47:15,078 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-11-13 02:47:15,127 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-13 02:47:15,139 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-13 02:47:15,145 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-13 02:47:15,152 [salt.state       ][INFO    ][30128] The service glusterfs-server is already running
2017-11-13 02:47:15,152 [salt.state       ][INFO    ][30128] Completed state [glusterfs-server] at time 02:47:15.152005 duration_in_ms=82.852
2017-11-13 02:47:15,153 [salt.state       ][INFO    ][30128] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 02:47:15.153451
2017-11-13 02:47:15,154 [salt.state       ][INFO    ][30128] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-13 02:47:15,156 [salt.state       ][INFO    ][30128] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-13 02:47:15,156 [salt.state       ][INFO    ][30128] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 02:47:15.155651 duration_in_ms=2.201
2017-11-13 02:47:15,156 [salt.state       ][INFO    ][30128] Running state [/srv/glusterfs/glance] at time 02:47:15.155837
2017-11-13 02:47:15,156 [salt.state       ][INFO    ][30128] Executing state file.directory for /srv/glusterfs/glance
2017-11-13 02:47:15,156 [salt.state       ][INFO    ][30128] Directory /srv/glusterfs/glance is in the correct state
2017-11-13 02:47:15,157 [salt.state       ][INFO    ][30128] Completed state [/srv/glusterfs/glance] at time 02:47:15.156523 duration_in_ms=0.686
2017-11-13 02:47:15,157 [salt.state       ][INFO    ][30128] Running state [/srv/glusterfs/keystone-keys] at time 02:47:15.156694
2017-11-13 02:47:15,157 [salt.state       ][INFO    ][30128] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-13 02:47:15,157 [salt.state       ][INFO    ][30128] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-13 02:47:15,157 [salt.state       ][INFO    ][30128] Completed state [/srv/glusterfs/keystone-keys] at time 02:47:15.157385 duration_in_ms=0.691
2017-11-13 02:47:15,158 [salt.state       ][INFO    ][30128] Running state [/srv/glusterfs/nova_instances] at time 02:47:15.157563
2017-11-13 02:47:15,158 [salt.state       ][INFO    ][30128] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-13 02:47:15,158 [salt.state       ][INFO    ][30128] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-13 02:47:15,158 [salt.state       ][INFO    ][30128] Completed state [/srv/glusterfs/nova_instances] at time 02:47:15.158241 duration_in_ms=0.678
2017-11-13 02:47:15,158 [salt.state       ][INFO    ][30128] Running state [/srv/glusterfs/keystone-credential-keys] at time 02:47:15.158413
2017-11-13 02:47:15,159 [salt.state       ][INFO    ][30128] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-13 02:47:15,159 [salt.state       ][INFO    ][30128] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-13 02:47:15,159 [salt.state       ][INFO    ][30128] Completed state [/srv/glusterfs/keystone-credential-keys] at time 02:47:15.159062 duration_in_ms=0.649
2017-11-13 02:47:15,214 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command ['git', '--version'] in directory '/root'
2017-11-13 02:47:15,294 [salt.state       ][INFO    ][30128] Running state [sleep 5] at time 02:47:15.294237
2017-11-13 02:47:15,295 [salt.state       ][INFO    ][30128] Executing state cmd.wait for sleep 5
2017-11-13 02:47:15,295 [salt.state       ][INFO    ][30128] No changes made for sleep 5
2017-11-13 02:47:15,295 [salt.state       ][INFO    ][30128] Completed state [sleep 5] at time 02:47:15.294854 duration_in_ms=0.619
2017-11-13 02:47:15,295 [salt.state       ][INFO    ][30128] Running state [192.168.10.143] at time 02:47:15.295345
2017-11-13 02:47:15,296 [salt.state       ][INFO    ][30128] Executing state glusterfs.peered for 192.168.10.143
2017-11-13 02:47:15,296 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:15,304 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:15,355 [salt.state       ][INFO    ][30128] Host 192.168.10.143 already peered
2017-11-13 02:47:15,355 [salt.state       ][INFO    ][30128] Completed state [192.168.10.143] at time 02:47:15.354750 duration_in_ms=59.404
2017-11-13 02:47:15,358 [salt.state       ][INFO    ][30128] Running state [192.168.10.142] at time 02:47:15.357644
2017-11-13 02:47:15,358 [salt.state       ][INFO    ][30128] Executing state glusterfs.peered for 192.168.10.142
2017-11-13 02:47:15,358 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:15,365 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:15,415 [salt.state       ][INFO    ][30128] Host 192.168.10.142 already peered
2017-11-13 02:47:15,415 [salt.state       ][INFO    ][30128] Completed state [192.168.10.142] at time 02:47:15.414873 duration_in_ms=57.229
2017-11-13 02:47:15,418 [salt.state       ][INFO    ][30128] Running state [192.168.10.141] at time 02:47:15.417878
2017-11-13 02:47:15,418 [salt.state       ][INFO    ][30128] Executing state glusterfs.peered for 192.168.10.141
2017-11-13 02:47:15,419 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:15,426 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:15,478 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:15,485 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:15,535 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:15,543 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:15,594 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:15,602 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:15,653 [salt.state       ][INFO    ][30128] Peering with localhost is not needed
2017-11-13 02:47:15,653 [salt.state       ][INFO    ][30128] Completed state [192.168.10.141] at time 02:47:15.653423 duration_in_ms=235.544
2017-11-13 02:47:15,655 [salt.state       ][INFO    ][30128] Running state [glance] at time 02:47:15.654480
2017-11-13 02:47:15,655 [salt.state       ][INFO    ][30128] Executing state glusterfs.created for glance
2017-11-13 02:47:15,655 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:15,663 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:15,715 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:15,721 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:15,775 [salt.state       ][INFO    ][30128] Volume glance already exists and is started.
2017-11-13 02:47:15,775 [salt.state       ][INFO    ][30128] Completed state [glance] at time 02:47:15.774951 duration_in_ms=120.47
2017-11-13 02:47:15,779 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 02:47:15.778557
2017-11-13 02:47:15,779 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 02:47:15,779 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 02:47:15,830 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:15,830 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 02:47:15.830081 duration_in_ms=51.524
2017-11-13 02:47:15,831 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 02:47:15.830610
2017-11-13 02:47:15,831 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-13 02:47:15,831 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 02:47:15,883 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:15,883 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 02:47:15.882892 duration_in_ms=52.281
2017-11-13 02:47:15,883 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 02:47:15.883411
2017-11-13 02:47:15,884 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-13 02:47:15,884 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 02:47:15,935 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:15,936 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 02:47:15.935627 duration_in_ms=52.215
2017-11-13 02:47:15,936 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 02:47:15.936254
2017-11-13 02:47:15,937 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 02:47:15,937 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 02:47:15,987 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:15,987 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 02:47:15.987077 duration_in_ms=50.823
2017-11-13 02:47:15,988 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 02:47:15.987638
2017-11-13 02:47:15,988 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-13 02:47:15,988 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 02:47:16,040 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:16,041 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 02:47:16.040606 duration_in_ms=52.968
2017-11-13 02:47:16,042 [salt.state       ][INFO    ][30128] Running state [glance] at time 02:47:16.041948
2017-11-13 02:47:16,042 [salt.state       ][INFO    ][30128] Executing state glusterfs.started for glance
2017-11-13 02:47:16,043 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:16,049 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:16,100 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:16,108 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:16,162 [salt.state       ][INFO    ][30128] Volume glance is already started
2017-11-13 02:47:16,163 [salt.state       ][INFO    ][30128] Completed state [glance] at time 02:47:16.162535 duration_in_ms=120.587
2017-11-13 02:47:16,163 [salt.state       ][INFO    ][30128] Running state [keystone-keys] at time 02:47:16.163459
2017-11-13 02:47:16,164 [salt.state       ][INFO    ][30128] Executing state glusterfs.created for keystone-keys
2017-11-13 02:47:16,164 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:16,172 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:16,222 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:16,229 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:16,284 [salt.state       ][INFO    ][30128] Volume keystone-keys already exists and is started.
2017-11-13 02:47:16,284 [salt.state       ][INFO    ][30128] Completed state [keystone-keys] at time 02:47:16.284201 duration_in_ms=120.741
2017-11-13 02:47:16,288 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 02:47:16.287930
2017-11-13 02:47:16,288 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-13 02:47:16,289 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 02:47:16,340 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:16,340 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 02:47:16.339952 duration_in_ms=52.022
2017-11-13 02:47:16,341 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 02:47:16.340470
2017-11-13 02:47:16,341 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 02:47:16,341 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 02:47:16,393 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:16,393 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 02:47:16.392764 duration_in_ms=52.292
2017-11-13 02:47:16,393 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 02:47:16.393413
2017-11-13 02:47:16,394 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 02:47:16,394 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 02:47:16,446 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:16,446 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 02:47:16.446176 duration_in_ms=52.762
2017-11-13 02:47:16,447 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 02:47:16.446747
2017-11-13 02:47:16,447 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-13 02:47:16,448 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 02:47:16,498 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:16,498 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 02:47:16.498338 duration_in_ms=51.591
2017-11-13 02:47:16,499 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 02:47:16.498955
2017-11-13 02:47:16,499 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-13 02:47:16,500 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 02:47:16,549 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:16,549 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 02:47:16.549027 duration_in_ms=50.073
2017-11-13 02:47:16,550 [salt.state       ][INFO    ][30128] Running state [keystone-keys] at time 02:47:16.550315
2017-11-13 02:47:16,551 [salt.state       ][INFO    ][30128] Executing state glusterfs.started for keystone-keys
2017-11-13 02:47:16,551 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:16,557 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:16,607 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:16,614 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:16,669 [salt.state       ][INFO    ][30128] Volume keystone-keys is already started
2017-11-13 02:47:16,669 [salt.state       ][INFO    ][30128] Completed state [keystone-keys] at time 02:47:16.669130 duration_in_ms=118.814
2017-11-13 02:47:16,670 [salt.state       ][INFO    ][30128] Running state [nova_instances] at time 02:47:16.670235
2017-11-13 02:47:16,671 [salt.state       ][INFO    ][30128] Executing state glusterfs.created for nova_instances
2017-11-13 02:47:16,671 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:16,679 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:16,729 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:16,736 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:16,790 [salt.state       ][INFO    ][30128] Volume nova_instances already exists and is started.
2017-11-13 02:47:16,791 [salt.state       ][INFO    ][30128] Completed state [nova_instances] at time 02:47:16.790675 duration_in_ms=120.439
2017-11-13 02:47:16,795 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 02:47:16.794580
2017-11-13 02:47:16,795 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 02:47:16,795 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 02:47:16,846 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:16,846 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 02:47:16.845822 duration_in_ms=51.241
2017-11-13 02:47:16,846 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 02:47:16.846456
2017-11-13 02:47:16,847 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-11-13 02:47:16,847 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-11-13 02:47:16,898 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:16,898 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 02:47:16.897779 duration_in_ms=51.322
2017-11-13 02:47:16,898 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 02:47:16.898401
2017-11-13 02:47:16,899 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-11-13 02:47:16,899 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-11-13 02:47:16,951 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:16,952 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 02:47:16.951596 duration_in_ms=53.195
2017-11-13 02:47:16,952 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 02:47:16.952173
2017-11-13 02:47:16,952 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-11-13 02:47:16,953 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-11-13 02:47:17,005 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:17,005 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 02:47:17.005150 duration_in_ms=52.976
2017-11-13 02:47:17,006 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 02:47:17.006121
2017-11-13 02:47:17,006 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 02:47:17,007 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 02:47:17,059 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:17,059 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 02:47:17.058778 duration_in_ms=52.656
2017-11-13 02:47:17,060 [salt.state       ][INFO    ][30128] Running state [nova_instances] at time 02:47:17.060028
2017-11-13 02:47:17,060 [salt.state       ][INFO    ][30128] Executing state glusterfs.started for nova_instances
2017-11-13 02:47:17,061 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:17,068 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:17,119 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:17,126 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:17,180 [salt.state       ][INFO    ][30128] Volume nova_instances is already started
2017-11-13 02:47:17,180 [salt.state       ][INFO    ][30128] Completed state [nova_instances] at time 02:47:17.180388 duration_in_ms=120.36
2017-11-13 02:47:17,182 [salt.state       ][INFO    ][30128] Running state [keystone-credential-keys] at time 02:47:17.181731
2017-11-13 02:47:17,182 [salt.state       ][INFO    ][30128] Executing state glusterfs.created for keystone-credential-keys
2017-11-13 02:47:17,182 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:17,190 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:17,239 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:17,247 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:17,303 [salt.state       ][INFO    ][30128] Volume keystone-credential-keys already exists and is started.
2017-11-13 02:47:17,304 [salt.state       ][INFO    ][30128] Completed state [keystone-credential-keys] at time 02:47:17.303661 duration_in_ms=121.929
2017-11-13 02:47:17,308 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 02:47:17.307701
2017-11-13 02:47:17,308 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-13 02:47:17,308 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 02:47:17,361 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:17,361 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 02:47:17.361031 duration_in_ms=53.329
2017-11-13 02:47:17,362 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 02:47:17.361664
2017-11-13 02:47:17,362 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 02:47:17,362 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 02:47:17,415 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:17,416 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 02:47:17.415572 duration_in_ms=53.907
2017-11-13 02:47:17,416 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 02:47:17.416095
2017-11-13 02:47:17,416 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-13 02:47:17,417 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 02:47:17,469 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:17,469 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 02:47:17.468917 duration_in_ms=52.821
2017-11-13 02:47:17,470 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 02:47:17.469600
2017-11-13 02:47:17,470 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 02:47:17,470 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 02:47:17,519 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:17,520 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 02:47:17.519502 duration_in_ms=49.902
2017-11-13 02:47:17,520 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 02:47:17.520066
2017-11-13 02:47:17,520 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-13 02:47:17,521 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 02:47:17,573 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-11-13 02:47:17,573 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 02:47:17.573041 duration_in_ms=52.975
2017-11-13 02:47:17,574 [salt.state       ][INFO    ][30128] Running state [keystone-credential-keys] at time 02:47:17.574437
2017-11-13 02:47:17,575 [salt.state       ][INFO    ][30128] Executing state glusterfs.started for keystone-credential-keys
2017-11-13 02:47:17,575 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:17,584 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:17,636 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-11-13 02:47:17,643 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 02:47:17,698 [salt.state       ][INFO    ][30128] Volume keystone-credential-keys is already started
2017-11-13 02:47:17,698 [salt.state       ][INFO    ][30128] Completed state [keystone-credential-keys] at time 02:47:17.697960 duration_in_ms=123.523
2017-11-13 02:47:17,700 [salt.minion      ][INFO    ][30128] Returning information for job: 20171113024713788266
2017-11-13 02:47:18,703 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command test.ping with jid 20171113024718690457
2017-11-13 02:47:18,715 [salt.minion      ][INFO    ][30529] Starting a new job with PID 30529
2017-11-13 02:47:18,748 [salt.minion      ][INFO    ][30529] Returning information for job: 20171113024718690457
2017-11-13 02:47:19,378 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command cmd.run with jid 20171113024719364480
2017-11-13 02:47:19,389 [salt.minion      ][INFO    ][30534] Starting a new job with PID 30534
2017-11-13 02:47:19,393 [salt.loaded.int.module.cmdmod][INFO    ][30534] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-13 02:47:19,503 [salt.minion      ][INFO    ][30534] Returning information for job: 20171113024719364480
2017-11-13 03:26:35,093 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command test.ping with jid 20171113032635084069
2017-11-13 03:26:35,111 [salt.minion      ][INFO    ][31225] Starting a new job with PID 31225
2017-11-13 03:26:35,153 [salt.minion      ][INFO    ][31225] Returning information for job: 20171113032635084069
2017-11-13 03:27:08,396 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command state.sls with jid 20171113032708386691
2017-11-13 03:27:08,406 [salt.minion      ][INFO    ][31244] Starting a new job with PID 31244
2017-11-13 03:27:08,945 [salt.state       ][INFO    ][31244] Loading fresh modules for state activity
2017-11-13 03:27:08,973 [salt.fileclient  ][INFO    ][31244] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-13 03:27:08,990 [salt.fileclient  ][INFO    ][31244] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-13 03:27:09,019 [salt.fileclient  ][INFO    ][31244] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 03:27:09,316 [salt.state       ][INFO    ][31244] Running state [lsof] at time 03:27:09.315611
2017-11-13 03:27:09,316 [salt.state       ][INFO    ][31244] Executing state pkg.installed for lsof
2017-11-13 03:27:09,316 [salt.loaded.int.module.cmdmod][INFO    ][31244] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 03:27:09,551 [salt.state       ][INFO    ][31244] Package lsof is already installed
2017-11-13 03:27:09,552 [salt.state       ][INFO    ][31244] Completed state [lsof] at time 03:27:09.551532 duration_in_ms=235.92
2017-11-13 03:27:09,552 [salt.state       ][INFO    ][31244] Running state [keepalived] at time 03:27:09.551824
2017-11-13 03:27:09,552 [salt.state       ][INFO    ][31244] Executing state pkg.installed for keepalived
2017-11-13 03:27:09,556 [salt.state       ][INFO    ][31244] Package keepalived is already installed
2017-11-13 03:27:09,556 [salt.state       ][INFO    ][31244] Completed state [keepalived] at time 03:27:09.555964 duration_in_ms=4.14
2017-11-13 03:27:09,558 [salt.state       ][INFO    ][31244] Running state [/etc/keepalived/keepalived.conf] at time 03:27:09.557478
2017-11-13 03:27:09,558 [salt.state       ][INFO    ][31244] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-13 03:27:09,573 [salt.fileclient  ][INFO    ][31244] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-13 03:27:09,608 [salt.fileclient  ][INFO    ][31244] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 03:27:09,614 [salt.state       ][INFO    ][31244] File /etc/keepalived/keepalived.conf is in the correct state
2017-11-13 03:27:09,614 [salt.state       ][INFO    ][31244] Completed state [/etc/keepalived/keepalived.conf] at time 03:27:09.614344 duration_in_ms=56.865
2017-11-13 03:27:09,615 [salt.state       ][INFO    ][31244] Running state [keepalived] at time 03:27:09.614977
2017-11-13 03:27:09,615 [salt.state       ][INFO    ][31244] Executing state service.running for keepalived
2017-11-13 03:27:09,616 [salt.loaded.int.module.cmdmod][INFO    ][31244] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-13 03:27:09,631 [salt.loaded.int.module.cmdmod][INFO    ][31244] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-13 03:27:09,638 [salt.loaded.int.module.cmdmod][INFO    ][31244] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-13 03:27:09,644 [salt.state       ][INFO    ][31244] The service keepalived is already running
2017-11-13 03:27:09,644 [salt.state       ][INFO    ][31244] Completed state [keepalived] at time 03:27:09.644431 duration_in_ms=29.454
2017-11-13 03:27:09,645 [salt.minion      ][INFO    ][31244] Returning information for job: 20171113032708386691
2017-11-13 03:27:10,532 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command pillar.get with jid 20171113032710522454
2017-11-13 03:27:10,546 [salt.minion      ][INFO    ][31268] Starting a new job with PID 31268
2017-11-13 03:27:10,552 [salt.minion      ][INFO    ][31268] Returning information for job: 20171113032710522454
2017-11-13 03:27:53,716 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command state.sls with jid 20171113032753708469
2017-11-13 03:27:53,731 [salt.minion      ][INFO    ][31278] Starting a new job with PID 31278
2017-11-13 03:27:56,301 [salt.state       ][INFO    ][31278] Loading fresh modules for state activity
2017-11-13 03:27:56,328 [salt.fileclient  ][INFO    ][31278] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-13 03:27:56,352 [salt.fileclient  ][INFO    ][31278] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 03:27:56,656 [salt.state       ][INFO    ][31278] Running state [attr] at time 03:27:56.656311
2017-11-13 03:27:56,657 [salt.state       ][INFO    ][31278] Executing state pkg.installed for attr
2017-11-13 03:27:56,657 [salt.loaded.int.module.cmdmod][INFO    ][31278] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 03:27:56,890 [salt.state       ][INFO    ][31278] Package attr is already installed
2017-11-13 03:27:56,891 [salt.state       ][INFO    ][31278] Completed state [attr] at time 03:27:56.890639 duration_in_ms=234.328
2017-11-13 03:27:56,891 [salt.state       ][INFO    ][31278] Running state [glusterfs-server] at time 03:27:56.890919
2017-11-13 03:27:56,891 [salt.state       ][INFO    ][31278] Executing state pkg.installed for glusterfs-server
2017-11-13 03:27:56,895 [salt.state       ][INFO    ][31278] Package glusterfs-server is already installed
2017-11-13 03:27:56,895 [salt.state       ][INFO    ][31278] Completed state [glusterfs-server] at time 03:27:56.894952 duration_in_ms=4.033
2017-11-13 03:27:56,895 [salt.state       ][INFO    ][31278] Running state [bc] at time 03:27:56.895147
2017-11-13 03:27:56,895 [salt.state       ][INFO    ][31278] Executing state pkg.installed for bc
2017-11-13 03:27:56,899 [salt.state       ][INFO    ][31278] Package bc is already installed
2017-11-13 03:27:56,899 [salt.state       ][INFO    ][31278] Completed state [bc] at time 03:27:56.899016 duration_in_ms=3.869
2017-11-13 03:27:56,900 [salt.state       ][INFO    ][31278] Running state [glusterfs-server] at time 03:27:56.899852
2017-11-13 03:27:56,900 [salt.state       ][INFO    ][31278] Executing state service.running for glusterfs-server
2017-11-13 03:27:56,901 [salt.loaded.int.module.cmdmod][INFO    ][31278] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-13 03:27:56,918 [salt.loaded.int.module.cmdmod][INFO    ][31278] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-13 03:27:56,926 [salt.loaded.int.module.cmdmod][INFO    ][31278] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-13 03:27:56,936 [salt.state       ][INFO    ][31278] The service glusterfs-server is already running
2017-11-13 03:27:56,936 [salt.state       ][INFO    ][31278] Completed state [glusterfs-server] at time 03:27:56.935826 duration_in_ms=35.973
2017-11-13 03:27:56,937 [salt.state       ][INFO    ][31278] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 03:27:56.937378
2017-11-13 03:27:56,938 [salt.state       ][INFO    ][31278] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-13 03:27:56,940 [salt.state       ][INFO    ][31278] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-13 03:27:56,940 [salt.state       ][INFO    ][31278] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 03:27:56.939682 duration_in_ms=2.304
2017-11-13 03:27:56,940 [salt.state       ][INFO    ][31278] Running state [/srv/glusterfs/glance] at time 03:27:56.939885
2017-11-13 03:27:56,940 [salt.state       ][INFO    ][31278] Executing state file.directory for /srv/glusterfs/glance
2017-11-13 03:27:56,941 [salt.state       ][INFO    ][31278] Directory /srv/glusterfs/glance is in the correct state
2017-11-13 03:27:56,941 [salt.state       ][INFO    ][31278] Completed state [/srv/glusterfs/glance] at time 03:27:56.940677 duration_in_ms=0.792
2017-11-13 03:27:56,941 [salt.state       ][INFO    ][31278] Running state [/srv/glusterfs/keystone-keys] at time 03:27:56.940867
2017-11-13 03:27:56,941 [salt.state       ][INFO    ][31278] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-13 03:27:56,942 [salt.state       ][INFO    ][31278] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-13 03:27:56,942 [salt.state       ][INFO    ][31278] Completed state [/srv/glusterfs/keystone-keys] at time 03:27:56.941689 duration_in_ms=0.821
2017-11-13 03:27:56,942 [salt.state       ][INFO    ][31278] Running state [/srv/glusterfs/nova_instances] at time 03:27:56.941877
2017-11-13 03:27:56,942 [salt.state       ][INFO    ][31278] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-13 03:27:56,942 [salt.state       ][INFO    ][31278] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-13 03:27:56,943 [salt.state       ][INFO    ][31278] Completed state [/srv/glusterfs/nova_instances] at time 03:27:56.942596 duration_in_ms=0.718
2017-11-13 03:27:56,943 [salt.state       ][INFO    ][31278] Running state [/srv/glusterfs/keystone-credential-keys] at time 03:27:56.942793
2017-11-13 03:27:56,943 [salt.state       ][INFO    ][31278] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-13 03:27:56,943 [salt.state       ][INFO    ][31278] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-13 03:27:56,944 [salt.state       ][INFO    ][31278] Completed state [/srv/glusterfs/keystone-credential-keys] at time 03:27:56.943566 duration_in_ms=0.772
2017-11-13 03:27:56,944 [salt.minion      ][INFO    ][31278] Returning information for job: 20171113032753708469
2017-11-13 03:27:58,003 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command test.ping with jid 20171113032757991788
2017-11-13 03:27:58,021 [salt.minion      ][INFO    ][31302] Starting a new job with PID 31302
2017-11-13 03:27:58,060 [salt.minion      ][INFO    ][31302] Returning information for job: 20171113032757991788
2017-11-13 03:28:06,377 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command state.sls with jid 20171113032806366660
2017-11-13 03:28:06,392 [salt.minion      ][INFO    ][31308] Starting a new job with PID 31308
2017-11-13 03:28:06,953 [salt.state       ][INFO    ][31308] Loading fresh modules for state activity
2017-11-13 03:28:06,980 [salt.fileclient  ][INFO    ][31308] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-13 03:28:07,020 [salt.fileclient  ][INFO    ][31308] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 03:28:07,102 [salt.fileclient  ][INFO    ][31308] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-13 03:28:07,129 [salt.fileclient  ][INFO    ][31308] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 03:28:07,442 [salt.state       ][INFO    ][31308] Running state [attr] at time 03:28:07.441996
2017-11-13 03:28:07,442 [salt.state       ][INFO    ][31308] Executing state pkg.installed for attr
2017-11-13 03:28:07,443 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 03:28:07,673 [salt.state       ][INFO    ][31308] Package attr is already installed
2017-11-13 03:28:07,674 [salt.state       ][INFO    ][31308] Completed state [attr] at time 03:28:07.673690 duration_in_ms=231.695
2017-11-13 03:28:07,674 [salt.state       ][INFO    ][31308] Running state [glusterfs-server] at time 03:28:07.673964
2017-11-13 03:28:07,674 [salt.state       ][INFO    ][31308] Executing state pkg.installed for glusterfs-server
2017-11-13 03:28:07,678 [salt.state       ][INFO    ][31308] Package glusterfs-server is already installed
2017-11-13 03:28:07,678 [salt.state       ][INFO    ][31308] Completed state [glusterfs-server] at time 03:28:07.677970 duration_in_ms=4.005
2017-11-13 03:28:07,678 [salt.state       ][INFO    ][31308] Running state [bc] at time 03:28:07.678174
2017-11-13 03:28:07,678 [salt.state       ][INFO    ][31308] Executing state pkg.installed for bc
2017-11-13 03:28:07,682 [salt.state       ][INFO    ][31308] Package bc is already installed
2017-11-13 03:28:07,682 [salt.state       ][INFO    ][31308] Completed state [bc] at time 03:28:07.682126 duration_in_ms=3.952
2017-11-13 03:28:07,683 [salt.state       ][INFO    ][31308] Running state [glusterfs-server] at time 03:28:07.683097
2017-11-13 03:28:07,683 [salt.state       ][INFO    ][31308] Executing state service.running for glusterfs-server
2017-11-13 03:28:07,684 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-13 03:28:07,701 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-13 03:28:07,709 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-13 03:28:07,719 [salt.state       ][INFO    ][31308] The service glusterfs-server is already running
2017-11-13 03:28:07,719 [salt.state       ][INFO    ][31308] Completed state [glusterfs-server] at time 03:28:07.719274 duration_in_ms=36.177
2017-11-13 03:28:07,721 [salt.state       ][INFO    ][31308] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 03:28:07.720734
2017-11-13 03:28:07,721 [salt.state       ][INFO    ][31308] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-13 03:28:07,723 [salt.state       ][INFO    ][31308] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-13 03:28:07,723 [salt.state       ][INFO    ][31308] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 03:28:07.723049 duration_in_ms=2.315
2017-11-13 03:28:07,723 [salt.state       ][INFO    ][31308] Running state [/srv/glusterfs/glance] at time 03:28:07.723251
2017-11-13 03:28:07,723 [salt.state       ][INFO    ][31308] Executing state file.directory for /srv/glusterfs/glance
2017-11-13 03:28:07,724 [salt.state       ][INFO    ][31308] Directory /srv/glusterfs/glance is in the correct state
2017-11-13 03:28:07,724 [salt.state       ][INFO    ][31308] Completed state [/srv/glusterfs/glance] at time 03:28:07.724060 duration_in_ms=0.808
2017-11-13 03:28:07,724 [salt.state       ][INFO    ][31308] Running state [/srv/glusterfs/keystone-keys] at time 03:28:07.724248
2017-11-13 03:28:07,724 [salt.state       ][INFO    ][31308] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-13 03:28:07,725 [salt.state       ][INFO    ][31308] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-13 03:28:07,725 [salt.state       ][INFO    ][31308] Completed state [/srv/glusterfs/keystone-keys] at time 03:28:07.725000 duration_in_ms=0.753
2017-11-13 03:28:07,725 [salt.state       ][INFO    ][31308] Running state [/srv/glusterfs/nova_instances] at time 03:28:07.725190
2017-11-13 03:28:07,725 [salt.state       ][INFO    ][31308] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-13 03:28:07,726 [salt.state       ][INFO    ][31308] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-13 03:28:07,726 [salt.state       ][INFO    ][31308] Completed state [/srv/glusterfs/nova_instances] at time 03:28:07.726000 duration_in_ms=0.81
2017-11-13 03:28:07,726 [salt.state       ][INFO    ][31308] Running state [/srv/glusterfs/keystone-credential-keys] at time 03:28:07.726187
2017-11-13 03:28:07,726 [salt.state       ][INFO    ][31308] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-13 03:28:07,727 [salt.state       ][INFO    ][31308] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-13 03:28:07,727 [salt.state       ][INFO    ][31308] Completed state [/srv/glusterfs/keystone-credential-keys] at time 03:28:07.726957 duration_in_ms=0.77
2017-11-13 03:28:07,767 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command ['git', '--version'] in directory '/root'
2017-11-13 03:28:07,844 [salt.state       ][INFO    ][31308] Running state [sleep 5] at time 03:28:07.844137
2017-11-13 03:28:07,844 [salt.state       ][INFO    ][31308] Executing state cmd.wait for sleep 5
2017-11-13 03:28:07,845 [salt.state       ][INFO    ][31308] No changes made for sleep 5
2017-11-13 03:28:07,845 [salt.state       ][INFO    ][31308] Completed state [sleep 5] at time 03:28:07.844875 duration_in_ms=0.738
2017-11-13 03:28:07,845 [salt.state       ][INFO    ][31308] Running state [192.168.10.143] at time 03:28:07.845444
2017-11-13 03:28:07,846 [salt.state       ][INFO    ][31308] Executing state glusterfs.peered for 192.168.10.143
2017-11-13 03:28:07,846 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:07,855 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:07,909 [salt.state       ][INFO    ][31308] Host 192.168.10.143 already peered
2017-11-13 03:28:07,909 [salt.state       ][INFO    ][31308] Completed state [192.168.10.143] at time 03:28:07.909372 duration_in_ms=63.928
2017-11-13 03:28:07,912 [salt.state       ][INFO    ][31308] Running state [192.168.10.142] at time 03:28:07.912228
2017-11-13 03:28:07,912 [salt.state       ][INFO    ][31308] Executing state glusterfs.peered for 192.168.10.142
2017-11-13 03:28:07,913 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:07,922 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:07,977 [salt.state       ][INFO    ][31308] Host 192.168.10.142 already peered
2017-11-13 03:28:07,977 [salt.state       ][INFO    ][31308] Completed state [192.168.10.142] at time 03:28:07.976734 duration_in_ms=64.505
2017-11-13 03:28:07,980 [salt.state       ][INFO    ][31308] Running state [192.168.10.141] at time 03:28:07.979710
2017-11-13 03:28:07,980 [salt.state       ][INFO    ][31308] Executing state glusterfs.peered for 192.168.10.141
2017-11-13 03:28:07,980 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:07,989 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:08,043 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:08,051 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:08,106 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:08,115 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:08,170 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:08,177 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:08,229 [salt.state       ][INFO    ][31308] Peering with localhost is not needed
2017-11-13 03:28:08,230 [salt.state       ][INFO    ][31308] Completed state [192.168.10.141] at time 03:28:08.229452 duration_in_ms=249.741
2017-11-13 03:28:08,231 [salt.state       ][INFO    ][31308] Running state [glance] at time 03:28:08.230547
2017-11-13 03:28:08,231 [salt.state       ][INFO    ][31308] Executing state glusterfs.created for glance
2017-11-13 03:28:08,231 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:08,239 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:08,291 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:08,299 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:08,356 [salt.state       ][INFO    ][31308] Volume glance already exists and is started.
2017-11-13 03:28:08,356 [salt.state       ][INFO    ][31308] Completed state [glance] at time 03:28:08.356183 duration_in_ms=125.635
2017-11-13 03:28:08,360 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 03:28:08.360002
2017-11-13 03:28:08,360 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 03:28:08,361 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 03:28:08,416 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:08,416 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 03:28:08.416007 duration_in_ms=56.005
2017-11-13 03:28:08,417 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 03:28:08.416595
2017-11-13 03:28:08,417 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-13 03:28:08,417 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 03:28:08,470 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:08,470 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 03:28:08.470160 duration_in_ms=53.565
2017-11-13 03:28:08,471 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 03:28:08.470695
2017-11-13 03:28:08,471 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-13 03:28:08,471 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 03:28:08,523 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:08,523 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 03:28:08.523376 duration_in_ms=52.681
2017-11-13 03:28:08,524 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 03:28:08.524000
2017-11-13 03:28:08,524 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 03:28:08,525 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 03:28:08,574 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:08,575 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 03:28:08.574671 duration_in_ms=50.67
2017-11-13 03:28:08,575 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 03:28:08.575306
2017-11-13 03:28:08,576 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-13 03:28:08,576 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 03:28:08,629 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:08,629 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 03:28:08.629122 duration_in_ms=53.816
2017-11-13 03:28:08,630 [salt.state       ][INFO    ][31308] Running state [glance] at time 03:28:08.630433
2017-11-13 03:28:08,631 [salt.state       ][INFO    ][31308] Executing state glusterfs.started for glance
2017-11-13 03:28:08,631 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:08,638 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:08,690 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:08,699 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:08,755 [salt.state       ][INFO    ][31308] Volume glance is already started
2017-11-13 03:28:08,755 [salt.state       ][INFO    ][31308] Completed state [glance] at time 03:28:08.755319 duration_in_ms=124.886
2017-11-13 03:28:08,756 [salt.state       ][INFO    ][31308] Running state [keystone-keys] at time 03:28:08.756389
2017-11-13 03:28:08,757 [salt.state       ][INFO    ][31308] Executing state glusterfs.created for keystone-keys
2017-11-13 03:28:08,757 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:08,765 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:08,817 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:08,825 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:08,881 [salt.state       ][INFO    ][31308] Volume keystone-keys already exists and is started.
2017-11-13 03:28:08,881 [salt.state       ][INFO    ][31308] Completed state [keystone-keys] at time 03:28:08.880748 duration_in_ms=124.359
2017-11-13 03:28:08,885 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 03:28:08.884582
2017-11-13 03:28:08,885 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-13 03:28:08,885 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 03:28:08,939 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:08,939 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 03:28:08.939253 duration_in_ms=54.671
2017-11-13 03:28:08,940 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 03:28:08.939768
2017-11-13 03:28:08,940 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 03:28:08,940 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 03:28:08,995 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:08,996 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 03:28:08.995469 duration_in_ms=55.692
2017-11-13 03:28:08,996 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 03:28:08.996004
2017-11-13 03:28:08,996 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 03:28:08,997 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 03:28:09,050 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:09,050 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 03:28:09.050147 duration_in_ms=54.143
2017-11-13 03:28:09,051 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 03:28:09.050755
2017-11-13 03:28:09,051 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-13 03:28:09,052 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 03:28:09,104 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:09,104 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 03:28:09.104314 duration_in_ms=53.559
2017-11-13 03:28:09,105 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 03:28:09.104893
2017-11-13 03:28:09,105 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-13 03:28:09,106 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 03:28:09,157 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:09,157 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 03:28:09.157117 duration_in_ms=52.224
2017-11-13 03:28:09,159 [salt.state       ][INFO    ][31308] Running state [keystone-keys] at time 03:28:09.158545
2017-11-13 03:28:09,159 [salt.state       ][INFO    ][31308] Executing state glusterfs.started for keystone-keys
2017-11-13 03:28:09,159 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:09,168 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:09,221 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:09,230 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:09,286 [salt.state       ][INFO    ][31308] Volume keystone-keys is already started
2017-11-13 03:28:09,286 [salt.state       ][INFO    ][31308] Completed state [keystone-keys] at time 03:28:09.285954 duration_in_ms=127.409
2017-11-13 03:28:09,287 [salt.state       ][INFO    ][31308] Running state [nova_instances] at time 03:28:09.286935
2017-11-13 03:28:09,287 [salt.state       ][INFO    ][31308] Executing state glusterfs.created for nova_instances
2017-11-13 03:28:09,288 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:09,296 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:09,348 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:09,356 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:09,412 [salt.state       ][INFO    ][31308] Volume nova_instances already exists and is started.
2017-11-13 03:28:09,412 [salt.state       ][INFO    ][31308] Completed state [nova_instances] at time 03:28:09.412295 duration_in_ms=125.36
2017-11-13 03:28:09,416 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 03:28:09.416217
2017-11-13 03:28:09,416 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 03:28:09,417 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 03:28:09,470 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:09,471 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 03:28:09.470472 duration_in_ms=54.255
2017-11-13 03:28:09,471 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 03:28:09.471102
2017-11-13 03:28:09,471 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-11-13 03:28:09,472 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-11-13 03:28:09,525 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:09,525 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 03:28:09.524947 duration_in_ms=53.845
2017-11-13 03:28:09,526 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 03:28:09.525547
2017-11-13 03:28:09,526 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-11-13 03:28:09,526 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-11-13 03:28:09,580 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:09,581 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 03:28:09.580668 duration_in_ms=55.12
2017-11-13 03:28:09,581 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 03:28:09.581200
2017-11-13 03:28:09,581 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-11-13 03:28:09,582 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-11-13 03:28:09,635 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:09,636 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 03:28:09.635637 duration_in_ms=54.436
2017-11-13 03:28:09,636 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 03:28:09.636190
2017-11-13 03:28:09,636 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 03:28:09,637 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 03:28:09,691 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:09,691 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 03:28:09.691290 duration_in_ms=55.099
2017-11-13 03:28:09,693 [salt.state       ][INFO    ][31308] Running state [nova_instances] at time 03:28:09.693044
2017-11-13 03:28:09,693 [salt.state       ][INFO    ][31308] Executing state glusterfs.started for nova_instances
2017-11-13 03:28:09,694 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:09,703 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:09,756 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:09,764 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:09,819 [salt.state       ][INFO    ][31308] Volume nova_instances is already started
2017-11-13 03:28:09,819 [salt.state       ][INFO    ][31308] Completed state [nova_instances] at time 03:28:09.819409 duration_in_ms=126.364
2017-11-13 03:28:09,821 [salt.state       ][INFO    ][31308] Running state [keystone-credential-keys] at time 03:28:09.820687
2017-11-13 03:28:09,821 [salt.state       ][INFO    ][31308] Executing state glusterfs.created for keystone-credential-keys
2017-11-13 03:28:09,821 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:09,830 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:09,883 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:09,890 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:09,947 [salt.state       ][INFO    ][31308] Volume keystone-credential-keys already exists and is started.
2017-11-13 03:28:09,947 [salt.state       ][INFO    ][31308] Completed state [keystone-credential-keys] at time 03:28:09.947089 duration_in_ms=126.401
2017-11-13 03:28:09,951 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 03:28:09.951336
2017-11-13 03:28:09,952 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-13 03:28:09,952 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 03:28:10,005 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:10,005 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 03:28:10.004851 duration_in_ms=53.515
2017-11-13 03:28:10,005 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 03:28:10.005465
2017-11-13 03:28:10,006 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 03:28:10,006 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 03:28:10,059 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:10,059 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 03:28:10.058890 duration_in_ms=53.424
2017-11-13 03:28:10,060 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 03:28:10.059516
2017-11-13 03:28:10,060 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-13 03:28:10,060 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 03:28:10,111 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:10,112 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 03:28:10.111576 duration_in_ms=52.059
2017-11-13 03:28:10,112 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 03:28:10.112213
2017-11-13 03:28:10,112 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 03:28:10,113 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 03:28:10,164 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:10,165 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 03:28:10.164491 duration_in_ms=52.265
2017-11-13 03:28:10,165 [salt.state       ][INFO    ][31308] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 03:28:10.165060
2017-11-13 03:28:10,165 [salt.state       ][INFO    ][31308] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-13 03:28:10,166 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 03:28:10,221 [salt.state       ][INFO    ][31308] unless execution succeeded
2017-11-13 03:28:10,221 [salt.state       ][INFO    ][31308] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 03:28:10.221262 duration_in_ms=56.202
2017-11-13 03:28:10,223 [salt.state       ][INFO    ][31308] Running state [keystone-credential-keys] at time 03:28:10.222600
2017-11-13 03:28:10,223 [salt.state       ][INFO    ][31308] Executing state glusterfs.started for keystone-credential-keys
2017-11-13 03:28:10,223 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:10,230 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:10,283 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --version' in directory '/root'
2017-11-13 03:28:10,291 [salt.loaded.int.module.cmdmod][INFO    ][31308] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 03:28:10,349 [salt.state       ][INFO    ][31308] Volume keystone-credential-keys is already started
2017-11-13 03:28:10,349 [salt.state       ][INFO    ][31308] Completed state [keystone-credential-keys] at time 03:28:10.349100 duration_in_ms=126.5
2017-11-13 03:28:10,352 [salt.minion      ][INFO    ][31308] Returning information for job: 20171113032806366660
2017-11-13 03:28:11,358 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command test.ping with jid 20171113032811348934
2017-11-13 03:28:11,371 [salt.minion      ][INFO    ][31691] Starting a new job with PID 31691
2017-11-13 03:28:11,405 [salt.minion      ][INFO    ][31691] Returning information for job: 20171113032811348934
2017-11-13 03:28:12,060 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command cmd.run with jid 20171113032812047292
2017-11-13 03:28:12,071 [salt.minion      ][INFO    ][31696] Starting a new job with PID 31696
2017-11-13 03:28:12,075 [salt.loaded.int.module.cmdmod][INFO    ][31696] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-13 03:28:12,189 [salt.minion      ][INFO    ][31696] Returning information for job: 20171113032812047292
2017-11-13 03:36:12,331 [salt.utils.schedule][INFO    ][23124] Running scheduled job: __mine_interval
2017-11-13 03:51:51,780 [salt.minion      ][INFO    ][23124] User sudo_ubuntu Executing command cp.push_dir with jid 20171113035151762498
2017-11-13 03:51:51,797 [salt.minion      ][INFO    ][31978] Starting a new job with PID 31978
