2017-11-16 02:04:48,686 [salt.cli.daemons ][WARNING ][2368] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-16 02:21:28,575 [salt.state       ][ERROR   ][3710] No contents found in top file
2017-11-16 02:21:46,018 [salt.transport.ipc][ERROR   ][2368] Exception occurred while handling stream: [Errno 0] Success
2017-11-16 02:23:16,893 [salt.utils.parsers][WARNING ][2368] Minion received a SIGTERM. Exiting.
2017-11-16 02:25:42,576 [salt.cli.daemons ][WARNING ][3191] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-16 02:28:21,496 [salt.transport.ipc][ERROR   ][3191] Exception occurred while handling stream: [Errno 0] Success
2017-11-16 02:28:33,331 [py.warnings      ][WARNING ][15073] /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-16 02:31:49,198 [salt.loaded.int.module.cmdmod][ERROR   ][15073] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-16 02:31:49,199 [salt.loaded.int.module.cmdmod][ERROR   ][15073] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-16 02:31:55,919 [salt.loaded.int.module.cmdmod][ERROR   ][15073] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-16 02:31:55,920 [salt.loaded.int.module.cmdmod][ERROR   ][15073] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-16 02:32:04,472 [salt.loaded.int.module.cmdmod][ERROR   ][15073] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-16 02:32:04,472 [salt.loaded.int.module.cmdmod][ERROR   ][15073] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-16 02:32:13,568 [salt.loaded.int.module.cmdmod][ERROR   ][15073] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-16 02:32:13,568 [salt.loaded.int.module.cmdmod][ERROR   ][15073] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-16 02:32:22,150 [salt.loaded.int.module.cmdmod][ERROR   ][15073] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-16 02:32:22,150 [salt.loaded.int.module.cmdmod][ERROR   ][15073] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-16 02:32:28,128 [salt.loaded.int.module.cmdmod][ERROR   ][15073] 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-16 02:32:30,476 [salt.state       ][ERROR   ][22616] No contents found in top file
2017-11-16 02:32:38,886 [salt.loaded.int.module.cmdmod][INFO    ][23155] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-16 02:32:38,893 [salt.loaded.int.module.cmdmod][INFO    ][23155] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-16 02:32:38,901 [salt.loaded.int.module.cmdmod][INFO    ][23155] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-11-16 02:32:38,907 [salt.utils.parsers][WARNING ][3191] Minion received a SIGTERM. Exiting.
2017-11-16 02:32:39,157 [salt.cli.daemons ][INFO    ][23288] Setting up the Salt Minion "kvm01.baremetal-mcp-ocata-ovs-ha.local"
2017-11-16 02:32:39,686 [salt.minion      ][INFO    ][23288] Creating minion process manager
2017-11-16 02:32:39,686 [salt.cli.daemons ][WARNING ][23288] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-16 02:32:39,686 [salt.cli.daemons ][INFO    ][23288] The Salt Minion is starting up
2017-11-16 02:32:39,686 [salt.minion      ][INFO    ][23288] Minion is starting as user 'root'
2017-11-16 02:32:39,687 [salt.utils.event ][INFO    ][23288] Starting pull socket on /var/run/salt/minion/minion_event_fd678c5357_pull.ipc
2017-11-16 02:32:40,713 [salt.loaded.int.module.cmdmod][INFO    ][23288] Executing command ['date', '+%z'] in directory '/root'
2017-11-16 02:32:40,721 [salt.utils.schedule][INFO    ][23288] Updating job settings for scheduled job: __mine_interval
2017-11-16 02:32:40,724 [salt.minion      ][INFO    ][23288] Added mine.update to scheduler
2017-11-16 02:32:40,930 [salt.minion      ][INFO    ][23288] Minion is ready to receive requests!
2017-11-16 02:32:41,932 [salt.utils.schedule][INFO    ][23288] Running scheduled job: __mine_interval
2017-11-16 02:32:48,088 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command saltutil.find_job with jid 20171116023248087872
2017-11-16 02:32:48,105 [salt.minion      ][INFO    ][23414] Starting a new job with PID 23414
2017-11-16 02:32:48,113 [salt.minion      ][INFO    ][23414] Returning information for job: 20171116023248087872
2017-11-16 02:33:09,129 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command state.sls with jid 20171116023309124553
2017-11-16 02:33:09,145 [salt.minion      ][INFO    ][23422] Starting a new job with PID 23422
2017-11-16 02:33:11,679 [salt.state       ][INFO    ][23422] Loading fresh modules for state activity
2017-11-16 02:33:11,703 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-11-16 02:33:11,750 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-11-16 02:33:11,782 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-16 02:33:11,853 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-11-16 02:33:11,882 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-11-16 02:33:11,941 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-16 02:33:12,038 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-16 02:33:12,057 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-16 02:33:12,099 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-16 02:33:12,131 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-16 02:33:12,146 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-16 02:33:12,161 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-16 02:33:12,168 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-16 02:33:12,190 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-16 02:33:12,206 [py.warnings      ][WARNING ][23422] /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-16 02:33:12,206 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command 'salt-minion --version' in directory '/root'
2017-11-16 02:33:12,484 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-16 02:33:12,524 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-16 02:33:12,536 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-16 02:33:12,548 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-16 02:33:12,604 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-16 02:33:12,650 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-16 02:33:12,710 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-11-16 02:33:12,730 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-16 02:33:12,779 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-16 02:33:12,820 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-16 02:33:12,869 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-16 02:33:12,887 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-16 02:33:12,899 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-16 02:33:12,913 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-16 02:33:12,920 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-16 02:33:12,945 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-16 02:33:12,959 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command 'salt-minion --version' in directory '/root'
2017-11-16 02:33:13,213 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-16 02:33:13,231 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-16 02:33:13,242 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-16 02:33:13,254 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-16 02:33:13,299 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-16 02:33:13,790 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-16 02:33:13,858 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-11-16 02:33:13,941 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-16 02:33:14,310 [salt.state       ][INFO    ][23422] Running state [salt-minion] at time 02:33:14.309970
2017-11-16 02:33:14,310 [salt.state       ][INFO    ][23422] Executing state pkg.installed for salt-minion
2017-11-16 02:33:14,311 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-16 02:33:14,594 [salt.state       ][INFO    ][23422] Package salt-minion is already installed
2017-11-16 02:33:14,594 [salt.state       ][INFO    ][23422] Completed state [salt-minion] at time 02:33:14.594041 duration_in_ms=284.072
2017-11-16 02:33:14,594 [salt.state       ][INFO    ][23422] Running state [salt_minion_dependency_packages] at time 02:33:14.594321
2017-11-16 02:33:14,595 [salt.state       ][INFO    ][23422] Executing state pkg.installed for salt_minion_dependency_packages
2017-11-16 02:33:14,597 [salt.state       ][INFO    ][23422] All specified packages are already installed
2017-11-16 02:33:14,597 [salt.state       ][INFO    ][23422] Completed state [salt_minion_dependency_packages] at time 02:33:14.597329 duration_in_ms=3.009
2017-11-16 02:33:14,599 [salt.state       ][INFO    ][23422] Running state [/etc/salt/minion.d/minion.conf] at time 02:33:14.598822
2017-11-16 02:33:14,599 [salt.state       ][INFO    ][23422] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-11-16 02:33:14,655 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-11-16 02:33:14,701 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-16 02:33:14,747 [salt.fileclient  ][INFO    ][23422] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-16 02:33:14,783 [salt.state       ][INFO    ][23422] File /etc/salt/minion.d/minion.conf is in the correct state
2017-11-16 02:33:14,783 [salt.state       ][INFO    ][23422] Completed state [/etc/salt/minion.d/minion.conf] at time 02:33:14.782802 duration_in_ms=183.979
2017-11-16 02:33:14,783 [salt.state       ][INFO    ][23422] Running state [/etc/salt/minion.d/_virt.conf] at time 02:33:14.783127
2017-11-16 02:33:14,783 [salt.state       ][INFO    ][23422] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-11-16 02:33:14,796 [salt.state       ][INFO    ][23422] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-11-16 02:33:14,796 [salt.state       ][INFO    ][23422] Completed state [/etc/salt/minion.d/_virt.conf] at time 02:33:14.796246 duration_in_ms=13.119
2017-11-16 02:33:14,798 [salt.state       ][INFO    ][23422] Running state [salt-minion] at time 02:33:14.797899
2017-11-16 02:33:14,798 [salt.state       ][INFO    ][23422] Executing state service.running for salt-minion
2017-11-16 02:33:14,799 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-16 02:33:14,814 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-11-16 02:33:14,824 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-16 02:33:14,831 [salt.state       ][INFO    ][23422] The service salt-minion is already running
2017-11-16 02:33:14,832 [salt.state       ][INFO    ][23422] Completed state [salt-minion] at time 02:33:14.831479 duration_in_ms=33.58
2017-11-16 02:33:14,833 [salt.state       ][INFO    ][23422] Running state [/etc/salt/grains.d] at time 02:33:14.832739
2017-11-16 02:33:14,833 [salt.state       ][INFO    ][23422] Executing state file.directory for /etc/salt/grains.d
2017-11-16 02:33:14,833 [salt.state       ][INFO    ][23422] Directory /etc/salt/grains.d is in the correct state
2017-11-16 02:33:14,834 [salt.state       ][INFO    ][23422] Completed state [/etc/salt/grains.d] at time 02:33:14.833575 duration_in_ms=0.836
2017-11-16 02:33:14,834 [salt.state       ][INFO    ][23422] Running state [/etc/salt/grains] at time 02:33:14.833981
2017-11-16 02:33:14,834 [salt.state       ][INFO    ][23422] Executing state file.managed for /etc/salt/grains
2017-11-16 02:33:14,834 [salt.state       ][INFO    ][23422] File /etc/salt/grains exists with proper permissions. No changes made.
2017-11-16 02:33:14,835 [salt.state       ][INFO    ][23422] Completed state [/etc/salt/grains] at time 02:33:14.834611 duration_in_ms=0.63
2017-11-16 02:33:14,835 [salt.state       ][INFO    ][23422] Running state [/etc/salt/grains.d/placeholder] at time 02:33:14.834877
2017-11-16 02:33:14,835 [salt.state       ][INFO    ][23422] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-11-16 02:33:14,835 [salt.state       ][INFO    ][23422] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-11-16 02:33:14,835 [salt.state       ][INFO    ][23422] Completed state [/etc/salt/grains.d/placeholder] at time 02:33:14.835464 duration_in_ms=0.587
2017-11-16 02:33:14,836 [salt.state       ][INFO    ][23422] Running state [/etc/salt/grains.d/sphinx] at time 02:33:14.835726
2017-11-16 02:33:14,836 [salt.state       ][INFO    ][23422] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-11-16 02:33:14,912 [salt.state       ][INFO    ][23422] File changed:
--- 
+++ 
@@ -49,7 +49,6 @@
               - 10.167.4.141
               - 127.0.0.1
               - 192.168.11.252
-              - 192.168.122.1
         system:
           name: System
           param:

2017-11-16 02:33:14,912 [salt.state       ][INFO    ][23422] Completed state [/etc/salt/grains.d/sphinx] at time 02:33:14.912375 duration_in_ms=76.649
2017-11-16 02:33:14,913 [salt.state       ][INFO    ][23422] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:33:14.912987
2017-11-16 02:33:14,913 [salt.state       ][INFO    ][23422] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-16 02:33:14,913 [salt.state       ][INFO    ][23422] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-16 02:33:14,914 [salt.state       ][INFO    ][23422] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:33:14.913478 duration_in_ms=0.492
2017-11-16 02:33:14,914 [salt.state       ][INFO    ][23422] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:33:14.913595
2017-11-16 02:33:14,914 [salt.state       ][INFO    ][23422] 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-16 02:33:14,914 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command 'python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"' in directory '/root'
2017-11-16 02:33:14,990 [salt.state       ][INFO    ][23422] {'pid': 23471, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-16 02:33:15,003 [salt.state       ][INFO    ][23422] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:33:15.003366 duration_in_ms=89.77
2017-11-16 02:33:15,004 [salt.state       ][INFO    ][23422] Running state [/etc/salt/grains.d/dns_records] at time 02:33:15.003931
2017-11-16 02:33:15,004 [salt.state       ][INFO    ][23422] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-11-16 02:33:15,124 [salt.state       ][INFO    ][23422] File /etc/salt/grains.d/dns_records is in the correct state
2017-11-16 02:33:15,124 [salt.state       ][INFO    ][23422] Completed state [/etc/salt/grains.d/dns_records] at time 02:33:15.123895 duration_in_ms=119.962
2017-11-16 02:33:15,125 [salt.state       ][INFO    ][23422] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:33:15.124617
2017-11-16 02:33:15,125 [salt.state       ][INFO    ][23422] 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-16 02:33:15,125 [salt.state       ][INFO    ][23422] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-16 02:33:15,125 [salt.state       ][INFO    ][23422] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:33:15.125153 duration_in_ms=0.536
2017-11-16 02:33:15,125 [salt.state       ][INFO    ][23422] Running state [/etc/salt/grains.d/salt] at time 02:33:15.125410
2017-11-16 02:33:15,126 [salt.state       ][INFO    ][23422] Executing state file.managed for /etc/salt/grains.d/salt
2017-11-16 02:33:15,149 [salt.state       ][INFO    ][23422] File /etc/salt/grains.d/salt is in the correct state
2017-11-16 02:33:15,149 [salt.state       ][INFO    ][23422] Completed state [/etc/salt/grains.d/salt] at time 02:33:15.149344 duration_in_ms=23.934
2017-11-16 02:33:15,150 [salt.state       ][INFO    ][23422] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:33:15.149880
2017-11-16 02:33:15,150 [salt.state       ][INFO    ][23422] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-16 02:33:15,150 [salt.state       ][INFO    ][23422] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-16 02:33:15,150 [salt.state       ][INFO    ][23422] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:33:15.150436 duration_in_ms=0.555
2017-11-16 02:33:15,152 [salt.state       ][INFO    ][23422] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:33:15.152292
2017-11-16 02:33:15,152 [salt.state       ][INFO    ][23422] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-16 02:33:15,153 [salt.state       ][INFO    ][23422] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-16 02:33:15,153 [salt.state       ][INFO    ][23422] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:33:15.152772 duration_in_ms=0.48
2017-11-16 02:33:15,153 [salt.state       ][INFO    ][23422] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:33:15.152892
2017-11-16 02:33:15,153 [salt.state       ][INFO    ][23422] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-16 02:33:15,154 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-11-16 02:33:15,163 [salt.state       ][INFO    ][23422] {'pid': 23473, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-16 02:33:15,163 [salt.state       ][INFO    ][23422] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:33:15.163065 duration_in_ms=10.173
2017-11-16 02:33:15,164 [salt.state       ][INFO    ][23422] Running state [mine.update] at time 02:33:15.163681
2017-11-16 02:33:15,164 [salt.state       ][INFO    ][23422] Executing state module.wait for mine.update
2017-11-16 02:33:15,164 [salt.state       ][INFO    ][23422] No changes made for mine.update
2017-11-16 02:33:15,164 [salt.state       ][INFO    ][23422] Completed state [mine.update] at time 02:33:15.164271 duration_in_ms=0.59
2017-11-16 02:33:15,164 [salt.state       ][INFO    ][23422] Running state [mine.update] at time 02:33:15.164419
2017-11-16 02:33:15,165 [salt.state       ][INFO    ][23422] Executing state module.mod_watch for mine.update
2017-11-16 02:33:15,684 [salt.state       ][INFO    ][23422] {'ret': True}
2017-11-16 02:33:15,685 [salt.state       ][INFO    ][23422] Completed state [mine.update] at time 02:33:15.684767 duration_in_ms=520.347
2017-11-16 02:33:15,685 [salt.state       ][INFO    ][23422] Running state [ca-certificates] at time 02:33:15.685059
2017-11-16 02:33:15,685 [salt.state       ][INFO    ][23422] Executing state pkg.installed for ca-certificates
2017-11-16 02:33:15,689 [salt.state       ][INFO    ][23422] Package ca-certificates is already installed
2017-11-16 02:33:15,689 [salt.state       ][INFO    ][23422] Completed state [ca-certificates] at time 02:33:15.688826 duration_in_ms=3.768
2017-11-16 02:33:15,689 [salt.state       ][INFO    ][23422] Running state [update-ca-certificates] at time 02:33:15.689438
2017-11-16 02:33:15,690 [salt.state       ][INFO    ][23422] Executing state cmd.wait for update-ca-certificates
2017-11-16 02:33:15,690 [salt.state       ][INFO    ][23422] No changes made for update-ca-certificates
2017-11-16 02:33:15,690 [salt.state       ][INFO    ][23422] Completed state [update-ca-certificates] at time 02:33:15.690060 duration_in_ms=0.623
2017-11-16 02:33:15,690 [salt.state       ][INFO    ][23422] Running state [libvirt-dev] at time 02:33:15.690271
2017-11-16 02:33:15,690 [salt.state       ][INFO    ][23422] Executing state pkg.installed for libvirt-dev
2017-11-16 02:33:15,693 [salt.state       ][INFO    ][23422] Package libvirt-dev is already installed
2017-11-16 02:33:15,693 [salt.state       ][INFO    ][23422] Completed state [libvirt-dev] at time 02:33:15.693439 duration_in_ms=3.168
2017-11-16 02:33:15,694 [salt.state       ][INFO    ][23422] Running state [pkg-config] at time 02:33:15.693626
2017-11-16 02:33:15,694 [salt.state       ][INFO    ][23422] Executing state pkg.installed for pkg-config
2017-11-16 02:33:15,697 [salt.state       ][INFO    ][23422] Package pkg-config is already installed
2017-11-16 02:33:15,697 [salt.state       ][INFO    ][23422] Completed state [pkg-config] at time 02:33:15.696760 duration_in_ms=3.134
2017-11-16 02:33:15,697 [salt.state       ][INFO    ][23422] Running state [virtng.init] at time 02:33:15.696947
2017-11-16 02:33:15,697 [salt.state       ][INFO    ][23422] Executing state module.run for virtng.init
2017-11-16 02:33:15,779 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command ['git', '--version'] in directory '/root'
2017-11-16 02:33:15,888 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command 'virsh list --all --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-16 02:33:15,907 [salt.state       ][INFO    ][23422] unless execution succeeded
2017-11-16 02:33:15,907 [salt.state       ][INFO    ][23422] Completed state [virtng.init] at time 02:33:15.907026 duration_in_ms=210.079
2017-11-16 02:33:15,907 [salt.state       ][INFO    ][23422] Running state [virt.set_autostart] at time 02:33:15.907296
2017-11-16 02:33:15,908 [salt.state       ][INFO    ][23422] Executing state module.run for virt.set_autostart
2017-11-16 02:33:15,910 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command 'virsh list --autostart --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-16 02:33:15,926 [salt.state       ][INFO    ][23422] unless execution succeeded
2017-11-16 02:33:15,926 [salt.state       ][INFO    ][23422] Completed state [virt.set_autostart] at time 02:33:15.925733 duration_in_ms=18.437
2017-11-16 02:33:15,926 [salt.state       ][INFO    ][23422] Running state [virtng.init] at time 02:33:15.925990
2017-11-16 02:33:15,926 [salt.state       ][INFO    ][23422] Executing state module.run for virtng.init
2017-11-16 02:33:15,928 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command 'virsh list --all --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-16 02:33:15,943 [salt.state       ][INFO    ][23422] unless execution succeeded
2017-11-16 02:33:15,943 [salt.state       ][INFO    ][23422] Completed state [virtng.init] at time 02:33:15.942965 duration_in_ms=16.975
2017-11-16 02:33:15,943 [salt.state       ][INFO    ][23422] Running state [virt.set_autostart] at time 02:33:15.943218
2017-11-16 02:33:15,943 [salt.state       ][INFO    ][23422] Executing state module.run for virt.set_autostart
2017-11-16 02:33:15,945 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command 'virsh list --autostart --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-16 02:33:15,960 [salt.state       ][INFO    ][23422] unless execution succeeded
2017-11-16 02:33:15,960 [salt.state       ][INFO    ][23422] Completed state [virt.set_autostart] at time 02:33:15.960021 duration_in_ms=16.803
2017-11-16 02:33:15,960 [salt.state       ][INFO    ][23422] Running state [virtng.init] at time 02:33:15.960273
2017-11-16 02:33:15,961 [salt.state       ][INFO    ][23422] Executing state module.run for virtng.init
2017-11-16 02:33:15,963 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command 'virsh list --all --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-16 02:33:15,979 [salt.state       ][INFO    ][23422] unless execution succeeded
2017-11-16 02:33:15,980 [salt.state       ][INFO    ][23422] Completed state [virtng.init] at time 02:33:15.979477 duration_in_ms=19.203
2017-11-16 02:33:15,980 [salt.state       ][INFO    ][23422] Running state [virt.set_autostart] at time 02:33:15.979734
2017-11-16 02:33:15,980 [salt.state       ][INFO    ][23422] Executing state module.run for virt.set_autostart
2017-11-16 02:33:15,982 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command 'virsh list --autostart --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-16 02:33:15,999 [salt.state       ][INFO    ][23422] unless execution succeeded
2017-11-16 02:33:15,999 [salt.state       ][INFO    ][23422] Completed state [virt.set_autostart] at time 02:33:15.998854 duration_in_ms=19.12
2017-11-16 02:33:15,999 [salt.state       ][INFO    ][23422] Running state [virtng.init] at time 02:33:15.999101
2017-11-16 02:33:15,999 [salt.state       ][INFO    ][23422] Executing state module.run for virtng.init
2017-11-16 02:33:16,001 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command 'virsh list --all --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-16 02:33:16,017 [salt.state       ][INFO    ][23422] unless execution succeeded
2017-11-16 02:33:16,111 [salt.state       ][INFO    ][23422] Completed state [virtng.init] at time 02:33:16.111191 duration_in_ms=112.088
2017-11-16 02:33:16,112 [salt.state       ][INFO    ][23422] Running state [virt.set_autostart] at time 02:33:16.111526
2017-11-16 02:33:16,112 [salt.state       ][INFO    ][23422] Executing state module.run for virt.set_autostart
2017-11-16 02:33:16,114 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command 'virsh list --autostart --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-16 02:33:16,132 [salt.state       ][INFO    ][23422] unless execution succeeded
2017-11-16 02:33:16,132 [salt.state       ][INFO    ][23422] Completed state [virt.set_autostart] at time 02:33:16.132117 duration_in_ms=20.591
2017-11-16 02:33:16,132 [salt.state       ][INFO    ][23422] Running state [virtng.init] at time 02:33:16.132376
2017-11-16 02:33:16,133 [salt.state       ][INFO    ][23422] Executing state module.run for virtng.init
2017-11-16 02:33:16,135 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command 'virsh list --all --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-16 02:33:16,152 [salt.state       ][INFO    ][23422] unless execution succeeded
2017-11-16 02:33:16,152 [salt.state       ][INFO    ][23422] Completed state [virtng.init] at time 02:33:16.151876 duration_in_ms=19.499
2017-11-16 02:33:16,152 [salt.state       ][INFO    ][23422] Running state [virt.set_autostart] at time 02:33:16.152124
2017-11-16 02:33:16,152 [salt.state       ][INFO    ][23422] Executing state module.run for virt.set_autostart
2017-11-16 02:33:16,154 [salt.loaded.int.module.cmdmod][INFO    ][23422] Executing command 'virsh list --autostart --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-16 02:33:16,170 [salt.state       ][INFO    ][23422] unless execution succeeded
2017-11-16 02:33:16,170 [salt.state       ][INFO    ][23422] Completed state [virt.set_autostart] at time 02:33:16.170295 duration_in_ms=18.17
2017-11-16 02:33:16,173 [salt.minion      ][INFO    ][23422] Returning information for job: 20171116023309124553
2017-11-16 02:33:17,129 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command pillar.get with jid 20171116023317123180
2017-11-16 02:33:17,143 [salt.minion      ][INFO    ][23526] Starting a new job with PID 23526
2017-11-16 02:33:17,146 [salt.minion      ][INFO    ][23526] Returning information for job: 20171116023317123180
2017-11-16 02:34:01,789 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171116023401786504
2017-11-16 02:34:01,805 [salt.minion      ][INFO    ][23536] Starting a new job with PID 23536
2017-11-16 02:34:04,385 [salt.state       ][INFO    ][23536] Loading fresh modules for state activity
2017-11-16 02:34:04,461 [salt.state       ][ERROR   ][23536] No contents found in top file
2017-11-16 02:34:04,463 [salt.utils.extmods][INFO    ][23536] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-11-16 02:34:04,465 [salt.utils.extmods][INFO    ][23536] Syncing beacons for environment 'base'
2017-11-16 02:34:04,466 [salt.utils.extmods][INFO    ][23536] Loading cache from salt://_beacons, for base)
2017-11-16 02:34:04,466 [salt.fileclient  ][INFO    ][23536] Caching directory '_beacons/' for environment 'base'
2017-11-16 02:34:04,506 [salt.utils.extmods][INFO    ][23536] Syncing modules for environment 'base'
2017-11-16 02:34:04,506 [salt.utils.extmods][INFO    ][23536] Loading cache from salt://_modules, for base)
2017-11-16 02:34:04,506 [salt.fileclient  ][INFO    ][23536] Caching directory '_modules/' for environment 'base'
2017-11-16 02:34:04,764 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-11-16 02:34:04,773 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-11-16 02:34:04,798 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-11-16 02:34:04,817 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-11-16 02:34:04,836 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-11-16 02:34:04,857 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-11-16 02:34:04,892 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-11-16 02:34:04,915 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-11-16 02:34:04,933 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-11-16 02:34:04,964 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-11-16 02:34:04,978 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-11-16 02:34:04,997 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-11-16 02:34:05,008 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-11-16 02:34:05,022 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-11-16 02:34:05,043 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-11-16 02:34:05,066 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-11-16 02:34:05,119 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-11-16 02:34:05,138 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-11-16 02:34:05,165 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-11-16 02:34:05,224 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-11-16 02:34:05,242 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-11-16 02:34:05,277 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-11-16 02:34:05,292 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-11-16 02:34:05,311 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-11-16 02:34:05,325 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-11-16 02:34:05,339 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-11-16 02:34:05,377 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-11-16 02:34:05,390 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-11-16 02:34:05,410 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-11-16 02:34:05,463 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-11-16 02:34:05,476 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-11-16 02:34:05,502 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-11-16 02:34:05,536 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-11-16 02:34:05,539 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-11-16 02:34:05,539 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-11-16 02:34:05,539 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-11-16 02:34:05,540 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-11-16 02:34:05,540 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-11-16 02:34:05,540 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-11-16 02:34:05,541 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-11-16 02:34:05,541 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-11-16 02:34:05,541 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-11-16 02:34:05,541 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-11-16 02:34:05,542 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-11-16 02:34:05,542 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-11-16 02:34:05,542 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-11-16 02:34:05,542 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-11-16 02:34:05,543 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-11-16 02:34:05,543 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-11-16 02:34:05,543 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-11-16 02:34:05,543 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-11-16 02:34:05,544 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-11-16 02:34:05,544 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-11-16 02:34:05,544 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-11-16 02:34:05,544 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-11-16 02:34:05,545 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-11-16 02:34:05,545 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-11-16 02:34:05,545 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-11-16 02:34:05,546 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-11-16 02:34:05,546 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-11-16 02:34:05,546 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-11-16 02:34:05,546 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-11-16 02:34:05,547 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-11-16 02:34:05,547 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-11-16 02:34:05,547 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-11-16 02:34:05,547 [salt.utils.extmods][INFO    ][23536] 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-16 02:34:05,548 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-11-16 02:34:05,548 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-11-16 02:34:05,552 [salt.utils.extmods][INFO    ][23536] Syncing states for environment 'base'
2017-11-16 02:34:05,552 [salt.utils.extmods][INFO    ][23536] Loading cache from salt://_states, for base)
2017-11-16 02:34:05,552 [salt.fileclient  ][INFO    ][23536] Caching directory '_states/' for environment 'base'
2017-11-16 02:34:05,624 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-11-16 02:34:05,642 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-11-16 02:34:05,665 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-11-16 02:34:05,683 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-11-16 02:34:05,700 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-11-16 02:34:05,713 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-11-16 02:34:05,727 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-11-16 02:34:05,744 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-11-16 02:34:05,755 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-11-16 02:34:05,786 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-11-16 02:34:05,808 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-11-16 02:34:05,846 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-11-16 02:34:05,894 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-11-16 02:34:05,906 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-11-16 02:34:05,919 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-11-16 02:34:05,964 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-11-16 02:34:05,994 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-11-16 02:34:06,037 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-11-16 02:34:06,072 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-11-16 02:34:06,086 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-11-16 02:34:06,121 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-11-16 02:34:06,561 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-11-16 02:34:06,789 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-11-16 02:34:06,860 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-11-16 02:34:07,050 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-11-16 02:34:07,057 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-11-16 02:34:07,065 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-11-16 02:34:07,508 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-11-16 02:34:07,514 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-11-16 02:34:07,517 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-11-16 02:34:07,517 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-11-16 02:34:07,518 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-11-16 02:34:07,518 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-11-16 02:34:07,518 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-11-16 02:34:07,518 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-11-16 02:34:07,518 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-11-16 02:34:07,519 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-11-16 02:34:07,519 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-11-16 02:34:07,519 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-11-16 02:34:07,519 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-11-16 02:34:07,519 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-11-16 02:34:07,520 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-11-16 02:34:07,520 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-11-16 02:34:07,520 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-11-16 02:34:07,520 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-11-16 02:34:07,520 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-11-16 02:34:07,521 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-11-16 02:34:07,521 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-11-16 02:34:07,521 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-11-16 02:34:07,521 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-11-16 02:34:07,521 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-11-16 02:34:07,522 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-11-16 02:34:07,522 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-11-16 02:34:07,522 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-11-16 02:34:07,522 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-11-16 02:34:07,523 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-11-16 02:34:07,523 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-11-16 02:34:07,523 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-11-16 02:34:07,523 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-11-16 02:34:07,525 [salt.utils.extmods][INFO    ][23536] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-11-16 02:34:07,528 [salt.utils.extmods][INFO    ][23536] Syncing sdb for environment 'base'
2017-11-16 02:34:07,528 [salt.utils.extmods][INFO    ][23536] Loading cache from salt://_sdb, for base)
2017-11-16 02:34:07,529 [salt.fileclient  ][INFO    ][23536] Caching directory '_sdb/' for environment 'base'
2017-11-16 02:34:07,553 [salt.utils.extmods][INFO    ][23536] Syncing grains for environment 'base'
2017-11-16 02:34:07,553 [salt.utils.extmods][INFO    ][23536] Loading cache from salt://_grains, for base)
2017-11-16 02:34:07,553 [salt.fileclient  ][INFO    ][23536] Caching directory '_grains/' for environment 'base'
2017-11-16 02:34:07,635 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-11-16 02:34:07,667 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-11-16 02:34:07,673 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-11-16 02:34:07,682 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-11-16 02:34:07,921 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-11-16 02:34:07,927 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-11-16 02:34:07,933 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-11-16 02:34:07,939 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-11-16 02:34:07,957 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-11-16 02:34:07,963 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-11-16 02:34:07,968 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-11-16 02:34:07,981 [salt.fileclient  ][INFO    ][23536] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-11-16 02:34:07,984 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-11-16 02:34:07,984 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-11-16 02:34:07,984 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-11-16 02:34:07,984 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-11-16 02:34:07,984 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-11-16 02:34:07,984 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-11-16 02:34:07,985 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-11-16 02:34:07,985 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-11-16 02:34:07,985 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-11-16 02:34:07,985 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-11-16 02:34:07,985 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-11-16 02:34:07,985 [salt.utils.extmods][INFO    ][23536] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-11-16 02:34:07,987 [salt.utils.extmods][INFO    ][23536] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-11-16 02:34:07,990 [salt.utils.extmods][INFO    ][23536] Syncing renderers for environment 'base'
2017-11-16 02:34:07,990 [salt.utils.extmods][INFO    ][23536] Loading cache from salt://_renderers, for base)
2017-11-16 02:34:07,990 [salt.fileclient  ][INFO    ][23536] Caching directory '_renderers/' for environment 'base'
2017-11-16 02:34:08,008 [salt.utils.extmods][INFO    ][23536] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-11-16 02:34:08,011 [salt.utils.extmods][INFO    ][23536] Syncing returners for environment 'base'
2017-11-16 02:34:08,011 [salt.utils.extmods][INFO    ][23536] Loading cache from salt://_returners, for base)
2017-11-16 02:34:08,011 [salt.fileclient  ][INFO    ][23536] Caching directory '_returners/' for environment 'base'
2017-11-16 02:34:08,028 [salt.utils.extmods][INFO    ][23536] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-11-16 02:34:08,030 [salt.utils.extmods][INFO    ][23536] Syncing output for environment 'base'
2017-11-16 02:34:08,030 [salt.utils.extmods][INFO    ][23536] Loading cache from salt://_output, for base)
2017-11-16 02:34:08,031 [salt.fileclient  ][INFO    ][23536] Caching directory '_output/' for environment 'base'
2017-11-16 02:34:08,048 [salt.utils.extmods][INFO    ][23536] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-11-16 02:34:08,050 [salt.utils.extmods][INFO    ][23536] Syncing utils for environment 'base'
2017-11-16 02:34:08,050 [salt.utils.extmods][INFO    ][23536] Loading cache from salt://_utils, for base)
2017-11-16 02:34:08,050 [salt.fileclient  ][INFO    ][23536] Caching directory '_utils/' for environment 'base'
2017-11-16 02:34:08,067 [salt.utils.extmods][INFO    ][23536] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-11-16 02:34:08,069 [salt.utils.extmods][INFO    ][23536] Syncing log_handlers for environment 'base'
2017-11-16 02:34:08,069 [salt.utils.extmods][INFO    ][23536] Loading cache from salt://_log_handlers, for base)
2017-11-16 02:34:08,070 [salt.fileclient  ][INFO    ][23536] Caching directory '_log_handlers/' for environment 'base'
2017-11-16 02:34:08,087 [salt.utils.extmods][INFO    ][23536] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-11-16 02:34:08,089 [salt.utils.extmods][INFO    ][23536] Syncing proxy for environment 'base'
2017-11-16 02:34:08,089 [salt.utils.extmods][INFO    ][23536] Loading cache from salt://_proxy, for base)
2017-11-16 02:34:08,089 [salt.fileclient  ][INFO    ][23536] Caching directory '_proxy/' for environment 'base'
2017-11-16 02:34:08,106 [salt.utils.extmods][INFO    ][23536] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-11-16 02:34:08,109 [salt.utils.extmods][INFO    ][23536] Syncing engines for environment 'base'
2017-11-16 02:34:08,109 [salt.utils.extmods][INFO    ][23536] Loading cache from salt://_engines, for base)
2017-11-16 02:34:08,109 [salt.fileclient  ][INFO    ][23536] Caching directory '_engines/' for environment 'base'
2017-11-16 02:34:08,130 [salt.minion      ][INFO    ][23536] Returning information for job: 20171116023401786504
2017-11-16 02:39:58,907 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command test.ping with jid 20171116023958901302
2017-11-16 02:39:58,922 [salt.minion      ][INFO    ][23723] Starting a new job with PID 23723
2017-11-16 02:39:58,949 [salt.minion      ][INFO    ][23723] Returning information for job: 20171116023958901302
2017-11-16 02:43:47,560 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command state.sls with jid 20171116024347551545
2017-11-16 02:43:47,576 [salt.minion      ][INFO    ][23803] Starting a new job with PID 23803
2017-11-16 02:43:49,273 [salt.state       ][INFO    ][23803] Loading fresh modules for state activity
2017-11-16 02:43:49,362 [salt.fileclient  ][INFO    ][23803] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-16 02:43:49,379 [salt.fileclient  ][INFO    ][23803] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-16 02:43:49,402 [salt.fileclient  ][INFO    ][23803] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-16 02:43:49,692 [salt.state       ][INFO    ][23803] Running state [lsof] at time 02:43:49.692211
2017-11-16 02:43:49,692 [salt.state       ][INFO    ][23803] Executing state pkg.installed for lsof
2017-11-16 02:43:49,693 [salt.loaded.int.module.cmdmod][INFO    ][23803] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-16 02:43:49,959 [salt.state       ][INFO    ][23803] Package lsof is already installed
2017-11-16 02:43:49,960 [salt.state       ][INFO    ][23803] Completed state [lsof] at time 02:43:49.959465 duration_in_ms=267.255
2017-11-16 02:43:49,960 [salt.state       ][INFO    ][23803] Running state [keepalived] at time 02:43:49.959657
2017-11-16 02:43:49,960 [salt.state       ][INFO    ][23803] Executing state pkg.installed for keepalived
2017-11-16 02:43:49,975 [salt.loaded.int.module.cmdmod][INFO    ][23803] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-16 02:43:51,880 [salt.loaded.int.module.cmdmod][INFO    ][23803] 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-16 02:43:57,599 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command saltutil.find_job with jid 20171116024357590226
2017-11-16 02:43:57,614 [salt.minion      ][INFO    ][25075] Starting a new job with PID 25075
2017-11-16 02:43:57,629 [salt.minion      ][INFO    ][25075] Returning information for job: 20171116024357590226
2017-11-16 02:43:59,753 [salt.loaded.int.module.cmdmod][INFO    ][23803] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-16 02:43:59,778 [salt.state       ][INFO    ][23803] 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-16 02:43:59,787 [salt.state       ][INFO    ][23803] Loading fresh modules for state activity
2017-11-16 02:43:59,799 [salt.state       ][INFO    ][23803] Completed state [keepalived] at time 02:43:59.799030 duration_in_ms=9839.373
2017-11-16 02:43:59,801 [salt.state       ][INFO    ][23803] Running state [/etc/keepalived/keepalived.conf] at time 02:43:59.800840
2017-11-16 02:43:59,801 [salt.state       ][INFO    ][23803] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-16 02:43:59,831 [salt.fileclient  ][INFO    ][23803] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-16 02:43:59,864 [salt.fileclient  ][INFO    ][23803] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-16 02:43:59,870 [salt.state       ][INFO    ][23803] File changed:
New file
2017-11-16 02:43:59,870 [salt.state       ][INFO    ][23803] Completed state [/etc/keepalived/keepalived.conf] at time 02:43:59.870375 duration_in_ms=69.534
2017-11-16 02:43:59,930 [salt.state       ][INFO    ][23803] Running state [keepalived] at time 02:43:59.929615
2017-11-16 02:43:59,930 [salt.state       ][INFO    ][23803] Executing state service.running for keepalived
2017-11-16 02:43:59,931 [salt.loaded.int.module.cmdmod][INFO    ][23803] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-16 02:43:59,940 [salt.loaded.int.module.cmdmod][INFO    ][23803] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-16 02:43:59,949 [salt.loaded.int.module.cmdmod][INFO    ][23803] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-16 02:43:59,956 [salt.loaded.int.module.cmdmod][INFO    ][23803] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-16 02:43:59,964 [salt.loaded.int.module.cmdmod][INFO    ][23803] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-11-16 02:44:00,019 [salt.loaded.int.module.cmdmod][INFO    ][23803] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-16 02:44:00,027 [salt.loaded.int.module.cmdmod][INFO    ][23803] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-16 02:44:00,035 [salt.loaded.int.module.cmdmod][INFO    ][23803] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-16 02:44:00,043 [salt.state       ][INFO    ][23803] {'keepalived': True}
2017-11-16 02:44:00,043 [salt.state       ][INFO    ][23803] Completed state [keepalived] at time 02:44:00.042755 duration_in_ms=113.14
2017-11-16 02:44:00,043 [salt.minion      ][INFO    ][23803] Returning information for job: 20171116024347551545
2017-11-16 02:44:01,049 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command pillar.get with jid 20171116024401041139
2017-11-16 02:44:01,066 [salt.minion      ][INFO    ][25359] Starting a new job with PID 25359
2017-11-16 02:44:01,070 [salt.minion      ][INFO    ][25359] Returning information for job: 20171116024401041139
2017-11-16 02:49:21,182 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command state.sls with jid 20171116024921173702
2017-11-16 02:49:21,198 [salt.minion      ][INFO    ][25459] Starting a new job with PID 25459
2017-11-16 02:49:23,677 [salt.state       ][INFO    ][25459] Loading fresh modules for state activity
2017-11-16 02:49:23,700 [salt.fileclient  ][INFO    ][25459] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-16 02:49:23,721 [salt.fileclient  ][INFO    ][25459] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-16 02:49:23,976 [salt.state       ][INFO    ][25459] Running state [attr] at time 02:49:23.976344
2017-11-16 02:49:23,977 [salt.state       ][INFO    ][25459] Executing state pkg.installed for attr
2017-11-16 02:49:23,977 [salt.loaded.int.module.cmdmod][INFO    ][25459] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-16 02:49:24,227 [salt.loaded.int.module.cmdmod][INFO    ][25459] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-16 02:49:26,131 [salt.loaded.int.module.cmdmod][INFO    ][25459] 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-16 02:49:28,633 [salt.loaded.int.module.cmdmod][INFO    ][25459] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-16 02:49:28,655 [salt.state       ][INFO    ][25459] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-11-16 02:49:28,663 [salt.state       ][INFO    ][25459] Loading fresh modules for state activity
2017-11-16 02:49:28,674 [salt.state       ][INFO    ][25459] Completed state [attr] at time 02:49:28.674363 duration_in_ms=4698.02
2017-11-16 02:49:28,679 [salt.state       ][INFO    ][25459] Running state [glusterfs-server] at time 02:49:28.678908
2017-11-16 02:49:28,679 [salt.state       ][INFO    ][25459] Executing state pkg.installed for glusterfs-server
2017-11-16 02:49:28,853 [salt.loaded.int.module.cmdmod][INFO    ][25459] 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-16 02:49:31,273 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command saltutil.find_job with jid 20171116024931264270
2017-11-16 02:49:31,287 [salt.minion      ][INFO    ][26447] Starting a new job with PID 26447
2017-11-16 02:49:31,299 [salt.minion      ][INFO    ][26447] Returning information for job: 20171116024931264270
2017-11-16 02:49:41,469 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command saltutil.find_job with jid 20171116024941459975
2017-11-16 02:49:41,484 [salt.minion      ][INFO    ][29273] Starting a new job with PID 29273
2017-11-16 02:49:41,493 [salt.minion      ][INFO    ][29273] Returning information for job: 20171116024941459975
2017-11-16 02:49:46,045 [salt.loaded.int.module.cmdmod][INFO    ][25459] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-16 02:49:46,070 [salt.state       ][INFO    ][25459] 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-16 02:49:46,076 [salt.state       ][INFO    ][25459] Loading fresh modules for state activity
2017-11-16 02:49:46,088 [salt.state       ][INFO    ][25459] Completed state [glusterfs-server] at time 02:49:46.088350 duration_in_ms=17409.441
2017-11-16 02:49:46,093 [salt.state       ][INFO    ][25459] Running state [bc] at time 02:49:46.092669
2017-11-16 02:49:46,093 [salt.state       ][INFO    ][25459] Executing state pkg.installed for bc
2017-11-16 02:49:46,328 [salt.loaded.int.module.cmdmod][INFO    ][25459] 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-16 02:49:49,189 [salt.loaded.int.module.cmdmod][INFO    ][25459] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-16 02:49:49,217 [salt.state       ][INFO    ][25459] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-11-16 02:49:49,225 [salt.state       ][INFO    ][25459] Loading fresh modules for state activity
2017-11-16 02:49:49,237 [salt.state       ][INFO    ][25459] Completed state [bc] at time 02:49:49.236952 duration_in_ms=3144.282
2017-11-16 02:49:49,295 [salt.state       ][INFO    ][25459] Running state [glusterfs-server] at time 02:49:49.294906
2017-11-16 02:49:49,295 [salt.state       ][INFO    ][25459] Executing state service.running for glusterfs-server
2017-11-16 02:49:49,296 [salt.loaded.int.module.cmdmod][INFO    ][25459] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-16 02:49:49,306 [salt.loaded.int.module.cmdmod][INFO    ][25459] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-16 02:49:49,313 [salt.loaded.int.module.cmdmod][INFO    ][25459] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-16 02:49:49,321 [salt.state       ][INFO    ][25459] The service glusterfs-server is already running
2017-11-16 02:49:49,322 [salt.state       ][INFO    ][25459] Completed state [glusterfs-server] at time 02:49:49.321510 duration_in_ms=26.604
2017-11-16 02:49:49,323 [salt.state       ][INFO    ][25459] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 02:49:49.323278
2017-11-16 02:49:49,323 [salt.state       ][INFO    ][25459] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-16 02:49:49,331 [salt.state       ][INFO    ][25459] File changed:
New file
2017-11-16 02:49:49,332 [salt.state       ][INFO    ][25459] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 02:49:49.331535 duration_in_ms=8.258
2017-11-16 02:49:49,332 [salt.state       ][INFO    ][25459] Running state [/srv/glusterfs/glance] at time 02:49:49.331676
2017-11-16 02:49:49,332 [salt.state       ][INFO    ][25459] Executing state file.directory for /srv/glusterfs/glance
2017-11-16 02:49:49,332 [salt.state       ][INFO    ][25459] {'/srv/glusterfs/glance': 'New Dir'}
2017-11-16 02:49:49,333 [salt.state       ][INFO    ][25459] Completed state [/srv/glusterfs/glance] at time 02:49:49.332489 duration_in_ms=0.814
2017-11-16 02:49:49,333 [salt.state       ][INFO    ][25459] Running state [/srv/glusterfs/keystone-keys] at time 02:49:49.332628
2017-11-16 02:49:49,333 [salt.state       ][INFO    ][25459] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-16 02:49:49,333 [salt.state       ][INFO    ][25459] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-11-16 02:49:49,333 [salt.state       ][INFO    ][25459] Completed state [/srv/glusterfs/keystone-keys] at time 02:49:49.333232 duration_in_ms=0.604
2017-11-16 02:49:49,333 [salt.state       ][INFO    ][25459] Running state [/srv/glusterfs/nova_instances] at time 02:49:49.333369
2017-11-16 02:49:49,334 [salt.state       ][INFO    ][25459] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-16 02:49:49,334 [salt.state       ][INFO    ][25459] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-11-16 02:49:49,334 [salt.state       ][INFO    ][25459] Completed state [/srv/glusterfs/nova_instances] at time 02:49:49.333966 duration_in_ms=0.596
2017-11-16 02:49:49,334 [salt.state       ][INFO    ][25459] Running state [/srv/glusterfs/keystone-credential-keys] at time 02:49:49.334103
2017-11-16 02:49:49,334 [salt.state       ][INFO    ][25459] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-16 02:49:49,335 [salt.state       ][INFO    ][25459] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-11-16 02:49:49,335 [salt.state       ][INFO    ][25459] Completed state [/srv/glusterfs/keystone-credential-keys] at time 02:49:49.334744 duration_in_ms=0.641
2017-11-16 02:49:49,335 [salt.minion      ][INFO    ][25459] Returning information for job: 20171116024921173702
2017-11-16 02:49:51,523 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command test.ping with jid 20171116024951514119
2017-11-16 02:49:51,536 [salt.minion      ][INFO    ][30185] Starting a new job with PID 30185
2017-11-16 02:49:51,562 [salt.minion      ][INFO    ][30185] Returning information for job: 20171116024951514119
2017-11-16 02:50:37,858 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command state.sls with jid 20171116025037850500
2017-11-16 02:50:37,872 [salt.minion      ][INFO    ][30401] Starting a new job with PID 30401
2017-11-16 02:50:38,303 [salt.state       ][INFO    ][30401] Loading fresh modules for state activity
2017-11-16 02:50:38,325 [salt.fileclient  ][INFO    ][30401] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-16 02:50:38,359 [salt.fileclient  ][INFO    ][30401] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-16 02:50:38,421 [salt.fileclient  ][INFO    ][30401] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-16 02:50:38,441 [salt.fileclient  ][INFO    ][30401] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-16 02:50:38,696 [salt.state       ][INFO    ][30401] Running state [attr] at time 02:50:38.695480
2017-11-16 02:50:38,696 [salt.state       ][INFO    ][30401] Executing state pkg.installed for attr
2017-11-16 02:50:38,696 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-16 02:50:38,960 [salt.state       ][INFO    ][30401] Package attr is already installed
2017-11-16 02:50:38,960 [salt.state       ][INFO    ][30401] Completed state [attr] at time 02:50:38.960110 duration_in_ms=264.63
2017-11-16 02:50:38,960 [salt.state       ][INFO    ][30401] Running state [glusterfs-server] at time 02:50:38.960324
2017-11-16 02:50:38,961 [salt.state       ][INFO    ][30401] Executing state pkg.installed for glusterfs-server
2017-11-16 02:50:38,963 [salt.state       ][INFO    ][30401] Package glusterfs-server is already installed
2017-11-16 02:50:38,963 [salt.state       ][INFO    ][30401] Completed state [glusterfs-server] at time 02:50:38.963211 duration_in_ms=2.887
2017-11-16 02:50:38,963 [salt.state       ][INFO    ][30401] Running state [bc] at time 02:50:38.963369
2017-11-16 02:50:38,964 [salt.state       ][INFO    ][30401] Executing state pkg.installed for bc
2017-11-16 02:50:38,966 [salt.state       ][INFO    ][30401] Package bc is already installed
2017-11-16 02:50:38,966 [salt.state       ][INFO    ][30401] Completed state [bc] at time 02:50:38.966185 duration_in_ms=2.817
2017-11-16 02:50:38,967 [salt.state       ][INFO    ][30401] Running state [glusterfs-server] at time 02:50:38.966957
2017-11-16 02:50:38,967 [salt.state       ][INFO    ][30401] Executing state service.running for glusterfs-server
2017-11-16 02:50:38,968 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-16 02:50:38,977 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-11-16 02:50:39,019 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-16 02:50:39,033 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-16 02:50:39,039 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-16 02:50:39,047 [salt.state       ][INFO    ][30401] The service glusterfs-server is already running
2017-11-16 02:50:39,048 [salt.state       ][INFO    ][30401] Completed state [glusterfs-server] at time 02:50:39.047541 duration_in_ms=80.584
2017-11-16 02:50:39,049 [salt.state       ][INFO    ][30401] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 02:50:39.048914
2017-11-16 02:50:39,049 [salt.state       ][INFO    ][30401] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-16 02:50:39,056 [salt.state       ][INFO    ][30401] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-16 02:50:39,056 [salt.state       ][INFO    ][30401] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 02:50:39.056116 duration_in_ms=7.202
2017-11-16 02:50:39,056 [salt.state       ][INFO    ][30401] Running state [/srv/glusterfs/glance] at time 02:50:39.056263
2017-11-16 02:50:39,056 [salt.state       ][INFO    ][30401] Executing state file.directory for /srv/glusterfs/glance
2017-11-16 02:50:39,057 [salt.state       ][INFO    ][30401] Directory /srv/glusterfs/glance is in the correct state
2017-11-16 02:50:39,057 [salt.state       ][INFO    ][30401] Completed state [/srv/glusterfs/glance] at time 02:50:39.056847 duration_in_ms=0.583
2017-11-16 02:50:39,057 [salt.state       ][INFO    ][30401] Running state [/srv/glusterfs/keystone-keys] at time 02:50:39.056985
2017-11-16 02:50:39,057 [salt.state       ][INFO    ][30401] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-16 02:50:39,057 [salt.state       ][INFO    ][30401] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-16 02:50:39,058 [salt.state       ][INFO    ][30401] Completed state [/srv/glusterfs/keystone-keys] at time 02:50:39.057529 duration_in_ms=0.544
2017-11-16 02:50:39,058 [salt.state       ][INFO    ][30401] Running state [/srv/glusterfs/nova_instances] at time 02:50:39.057668
2017-11-16 02:50:39,058 [salt.state       ][INFO    ][30401] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-16 02:50:39,058 [salt.state       ][INFO    ][30401] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-16 02:50:39,058 [salt.state       ][INFO    ][30401] Completed state [/srv/glusterfs/nova_instances] at time 02:50:39.058229 duration_in_ms=0.562
2017-11-16 02:50:39,058 [salt.state       ][INFO    ][30401] Running state [/srv/glusterfs/keystone-credential-keys] at time 02:50:39.058370
2017-11-16 02:50:39,059 [salt.state       ][INFO    ][30401] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-16 02:50:39,059 [salt.state       ][INFO    ][30401] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-16 02:50:39,059 [salt.state       ][INFO    ][30401] Completed state [/srv/glusterfs/keystone-credential-keys] at time 02:50:39.058905 duration_in_ms=0.536
2017-11-16 02:50:39,106 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command ['git', '--version'] in directory '/root'
2017-11-16 02:50:39,205 [salt.state       ][INFO    ][30401] Running state [sleep 5] at time 02:50:39.205405
2017-11-16 02:50:39,206 [salt.state       ][INFO    ][30401] Executing state cmd.wait for sleep 5
2017-11-16 02:50:39,206 [salt.state       ][INFO    ][30401] No changes made for sleep 5
2017-11-16 02:50:39,206 [salt.state       ][INFO    ][30401] Completed state [sleep 5] at time 02:50:39.205974 duration_in_ms=0.57
2017-11-16 02:50:39,206 [salt.state       ][INFO    ][30401] Running state [10.167.4.141] at time 02:50:39.206358
2017-11-16 02:50:39,207 [salt.state       ][INFO    ][30401] Executing state glusterfs.peered for 10.167.4.141
2017-11-16 02:50:39,207 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:39,216 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:39,264 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:39,273 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:39,323 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:39,331 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:39,380 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:39,388 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:39,435 [salt.state       ][INFO    ][30401] Peering with localhost is not needed
2017-11-16 02:50:39,435 [salt.state       ][INFO    ][30401] Completed state [10.167.4.141] at time 02:50:39.435138 duration_in_ms=228.78
2017-11-16 02:50:39,438 [salt.state       ][INFO    ][30401] Running state [10.167.4.142] at time 02:50:39.437484
2017-11-16 02:50:39,438 [salt.state       ][INFO    ][30401] Executing state glusterfs.peered for 10.167.4.142
2017-11-16 02:50:39,438 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:39,447 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:39,495 [salt.state       ][INFO    ][30401] Host 10.167.4.142 already peered
2017-11-16 02:50:39,495 [salt.state       ][INFO    ][30401] Completed state [10.167.4.142] at time 02:50:39.494993 duration_in_ms=57.509
2017-11-16 02:50:39,497 [salt.state       ][INFO    ][30401] Running state [10.167.4.143] at time 02:50:39.497187
2017-11-16 02:50:39,497 [salt.state       ][INFO    ][30401] Executing state glusterfs.peered for 10.167.4.143
2017-11-16 02:50:39,498 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:39,506 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:39,558 [salt.state       ][INFO    ][30401] Host 10.167.4.143 already peered
2017-11-16 02:50:39,558 [salt.state       ][INFO    ][30401] Completed state [10.167.4.143] at time 02:50:39.557835 duration_in_ms=60.648
2017-11-16 02:50:39,559 [salt.state       ][INFO    ][30401] Running state [glance] at time 02:50:39.558722
2017-11-16 02:50:39,559 [salt.state       ][INFO    ][30401] Executing state glusterfs.created for glance
2017-11-16 02:50:39,559 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:39,568 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:39,616 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:39,623 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:39,676 [salt.state       ][INFO    ][30401] Volume glance already exists and is started.
2017-11-16 02:50:39,676 [salt.state       ][INFO    ][30401] Completed state [glance] at time 02:50:39.676056 duration_in_ms=117.334
2017-11-16 02:50:39,679 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 02:50:39.678685
2017-11-16 02:50:39,679 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-16 02:50:39,679 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-16 02:50:39,727 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:39,728 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 02:50:39.727480 duration_in_ms=48.794
2017-11-16 02:50:39,728 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 02:50:39.727952
2017-11-16 02:50:39,728 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-16 02:50:39,729 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-16 02:50:39,777 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:39,777 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 02:50:39.777203 duration_in_ms=49.251
2017-11-16 02:50:39,778 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 02:50:39.777655
2017-11-16 02:50:39,778 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-16 02:50:39,778 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-16 02:50:39,826 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:39,827 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 02:50:39.826597 duration_in_ms=48.942
2017-11-16 02:50:39,827 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 02:50:39.827086
2017-11-16 02:50:39,827 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-16 02:50:39,828 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-16 02:50:39,874 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:39,874 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 02:50:39.873963 duration_in_ms=46.877
2017-11-16 02:50:39,875 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 02:50:39.874493
2017-11-16 02:50:39,875 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-16 02:50:39,875 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-16 02:50:39,927 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:39,928 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 02:50:39.927628 duration_in_ms=53.135
2017-11-16 02:50:39,929 [salt.state       ][INFO    ][30401] Running state [glance] at time 02:50:39.928601
2017-11-16 02:50:39,929 [salt.state       ][INFO    ][30401] Executing state glusterfs.started for glance
2017-11-16 02:50:39,929 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:39,938 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:39,988 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:39,996 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:40,049 [salt.state       ][INFO    ][30401] Volume glance is already started
2017-11-16 02:50:40,049 [salt.state       ][INFO    ][30401] Completed state [glance] at time 02:50:40.049008 duration_in_ms=120.407
2017-11-16 02:50:40,050 [salt.state       ][INFO    ][30401] Running state [keystone-keys] at time 02:50:40.049781
2017-11-16 02:50:40,050 [salt.state       ][INFO    ][30401] Executing state glusterfs.created for keystone-keys
2017-11-16 02:50:40,050 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:40,058 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:40,105 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:40,113 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:40,166 [salt.state       ][INFO    ][30401] Volume keystone-keys already exists and is started.
2017-11-16 02:50:40,166 [salt.state       ][INFO    ][30401] Completed state [keystone-keys] at time 02:50:40.166102 duration_in_ms=116.321
2017-11-16 02:50:40,169 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 02:50:40.168806
2017-11-16 02:50:40,169 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-16 02:50:40,169 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-16 02:50:40,219 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:40,219 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 02:50:40.219035 duration_in_ms=50.228
2017-11-16 02:50:40,219 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 02:50:40.219454
2017-11-16 02:50:40,220 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-16 02:50:40,220 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-16 02:50:40,268 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:40,268 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 02:50:40.267857 duration_in_ms=48.403
2017-11-16 02:50:40,268 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 02:50:40.268342
2017-11-16 02:50:40,269 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-16 02:50:40,269 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-16 02:50:40,319 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:40,319 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 02:50:40.319220 duration_in_ms=50.876
2017-11-16 02:50:40,320 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 02:50:40.319626
2017-11-16 02:50:40,320 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-16 02:50:40,320 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-16 02:50:40,367 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:40,368 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 02:50:40.367669 duration_in_ms=48.043
2017-11-16 02:50:40,368 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 02:50:40.368086
2017-11-16 02:50:40,368 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-16 02:50:40,369 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-16 02:50:40,415 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:40,415 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 02:50:40.415215 duration_in_ms=47.129
2017-11-16 02:50:40,416 [salt.state       ][INFO    ][30401] Running state [keystone-keys] at time 02:50:40.416106
2017-11-16 02:50:40,416 [salt.state       ][INFO    ][30401] Executing state glusterfs.started for keystone-keys
2017-11-16 02:50:40,417 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:40,424 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:40,471 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:40,479 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:40,539 [salt.state       ][INFO    ][30401] Volume keystone-keys is already started
2017-11-16 02:50:40,539 [salt.state       ][INFO    ][30401] Completed state [keystone-keys] at time 02:50:40.539369 duration_in_ms=123.262
2017-11-16 02:50:40,540 [salt.state       ][INFO    ][30401] Running state [nova_instances] at time 02:50:40.540166
2017-11-16 02:50:40,540 [salt.state       ][INFO    ][30401] Executing state glusterfs.created for nova_instances
2017-11-16 02:50:40,541 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:40,549 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:40,597 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:40,605 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:40,662 [salt.state       ][INFO    ][30401] Volume nova_instances already exists and is started.
2017-11-16 02:50:40,662 [salt.state       ][INFO    ][30401] Completed state [nova_instances] at time 02:50:40.661929 duration_in_ms=121.763
2017-11-16 02:50:40,665 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 02:50:40.664930
2017-11-16 02:50:40,665 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-16 02:50:40,666 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-16 02:50:40,716 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:40,717 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 02:50:40.716583 duration_in_ms=51.653
2017-11-16 02:50:40,717 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 02:50:40.717035
2017-11-16 02:50:40,717 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-11-16 02:50:40,718 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-11-16 02:50:40,767 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:40,787 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 02:50:40.786828 duration_in_ms=69.792
2017-11-16 02:50:40,787 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 02:50:40.787438
2017-11-16 02:50:40,788 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-11-16 02:50:40,788 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-11-16 02:50:40,838 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:40,838 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 02:50:40.837814 duration_in_ms=50.375
2017-11-16 02:50:40,838 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 02:50:40.838279
2017-11-16 02:50:40,839 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-11-16 02:50:40,839 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-11-16 02:50:40,886 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:40,887 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 02:50:40.886489 duration_in_ms=48.209
2017-11-16 02:50:40,887 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 02:50:40.886923
2017-11-16 02:50:40,887 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-16 02:50:40,888 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-16 02:50:40,940 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:40,940 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 02:50:40.939755 duration_in_ms=52.832
2017-11-16 02:50:40,941 [salt.state       ][INFO    ][30401] Running state [nova_instances] at time 02:50:40.940667
2017-11-16 02:50:40,941 [salt.state       ][INFO    ][30401] Executing state glusterfs.started for nova_instances
2017-11-16 02:50:40,941 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:40,951 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:41,002 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:41,010 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:41,074 [salt.state       ][INFO    ][30401] Volume nova_instances is already started
2017-11-16 02:50:41,074 [salt.state       ][INFO    ][30401] Completed state [nova_instances] at time 02:50:41.073976 duration_in_ms=133.308
2017-11-16 02:50:41,075 [salt.state       ][INFO    ][30401] Running state [keystone-credential-keys] at time 02:50:41.074880
2017-11-16 02:50:41,075 [salt.state       ][INFO    ][30401] Executing state glusterfs.created for keystone-credential-keys
2017-11-16 02:50:41,076 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:41,084 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:41,132 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:41,140 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:41,193 [salt.state       ][INFO    ][30401] Volume keystone-credential-keys already exists and is started.
2017-11-16 02:50:41,193 [salt.state       ][INFO    ][30401] Completed state [keystone-credential-keys] at time 02:50:41.193403 duration_in_ms=118.523
2017-11-16 02:50:41,200 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 02:50:41.199654
2017-11-16 02:50:41,201 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-16 02:50:41,202 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-16 02:50:41,252 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:41,252 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 02:50:41.251919 duration_in_ms=52.27
2017-11-16 02:50:41,253 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 02:50:41.252468
2017-11-16 02:50:41,253 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-16 02:50:41,253 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-16 02:50:41,304 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:41,304 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 02:50:41.303763 duration_in_ms=51.294
2017-11-16 02:50:41,304 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 02:50:41.304195
2017-11-16 02:50:41,304 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-16 02:50:41,305 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-16 02:50:41,355 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:41,356 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 02:50:41.355584 duration_in_ms=51.389
2017-11-16 02:50:41,356 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 02:50:41.356070
2017-11-16 02:50:41,356 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-16 02:50:41,357 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-16 02:50:41,406 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:41,406 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 02:50:41.405971 duration_in_ms=49.9
2017-11-16 02:50:41,407 [salt.state       ][INFO    ][30401] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 02:50:41.406499
2017-11-16 02:50:41,407 [salt.state       ][INFO    ][30401] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-16 02:50:41,407 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-16 02:50:41,457 [salt.state       ][INFO    ][30401] unless execution succeeded
2017-11-16 02:50:41,457 [salt.state       ][INFO    ][30401] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 02:50:41.456870 duration_in_ms=50.37
2017-11-16 02:50:41,458 [salt.state       ][INFO    ][30401] Running state [keystone-credential-keys] at time 02:50:41.457805
2017-11-16 02:50:41,458 [salt.state       ][INFO    ][30401] Executing state glusterfs.started for keystone-credential-keys
2017-11-16 02:50:41,459 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:41,467 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:41,516 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --version' in directory '/root'
2017-11-16 02:50:41,525 [salt.loaded.int.module.cmdmod][INFO    ][30401] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 02:50:41,581 [salt.state       ][INFO    ][30401] Volume keystone-credential-keys is already started
2017-11-16 02:50:41,581 [salt.state       ][INFO    ][30401] Completed state [keystone-credential-keys] at time 02:50:41.580948 duration_in_ms=123.142
2017-11-16 02:50:41,583 [salt.minion      ][INFO    ][30401] Returning information for job: 20171116025037850500
2017-11-16 02:50:42,784 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command test.ping with jid 20171116025042774134
2017-11-16 02:50:42,798 [salt.minion      ][INFO    ][30800] Starting a new job with PID 30800
2017-11-16 02:50:42,822 [salt.minion      ][INFO    ][30800] Returning information for job: 20171116025042774134
2017-11-16 02:50:43,473 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command cmd.run with jid 20171116025043464350
2017-11-16 02:50:43,488 [salt.minion      ][INFO    ][30805] Starting a new job with PID 30805
2017-11-16 02:50:43,491 [salt.loaded.int.module.cmdmod][INFO    ][30805] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-16 02:50:43,599 [salt.minion      ][INFO    ][30805] Returning information for job: 20171116025043464350
2017-11-16 03:32:41,932 [salt.utils.schedule][INFO    ][23288] Running scheduled job: __mine_interval
2017-11-16 04:01:03,984 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command test.ping with jid 20171116040103975865
2017-11-16 04:01:04,002 [salt.minion      ][INFO    ][31962] Starting a new job with PID 31962
2017-11-16 04:01:04,043 [salt.minion      ][INFO    ][31962] Returning information for job: 20171116040103975865
2017-11-16 04:01:33,880 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command state.sls with jid 20171116040133871758
2017-11-16 04:01:33,896 [salt.minion      ][INFO    ][31980] Starting a new job with PID 31980
2017-11-16 04:01:34,315 [salt.state       ][INFO    ][31980] Loading fresh modules for state activity
2017-11-16 04:01:34,340 [salt.fileclient  ][INFO    ][31980] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-16 04:01:34,359 [salt.fileclient  ][INFO    ][31980] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-16 04:01:34,383 [salt.fileclient  ][INFO    ][31980] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-16 04:01:34,637 [salt.state       ][INFO    ][31980] Running state [lsof] at time 04:01:34.636494
2017-11-16 04:01:34,637 [salt.state       ][INFO    ][31980] Executing state pkg.installed for lsof
2017-11-16 04:01:34,637 [salt.loaded.int.module.cmdmod][INFO    ][31980] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-16 04:01:34,867 [salt.state       ][INFO    ][31980] Package lsof is already installed
2017-11-16 04:01:34,867 [salt.state       ][INFO    ][31980] Completed state [lsof] at time 04:01:34.867279 duration_in_ms=230.785
2017-11-16 04:01:34,868 [salt.state       ][INFO    ][31980] Running state [keepalived] at time 04:01:34.867507
2017-11-16 04:01:34,868 [salt.state       ][INFO    ][31980] Executing state pkg.installed for keepalived
2017-11-16 04:01:34,870 [salt.state       ][INFO    ][31980] Package keepalived is already installed
2017-11-16 04:01:34,870 [salt.state       ][INFO    ][31980] Completed state [keepalived] at time 04:01:34.870448 duration_in_ms=2.94
2017-11-16 04:01:34,872 [salt.state       ][INFO    ][31980] Running state [/etc/keepalived/keepalived.conf] at time 04:01:34.871757
2017-11-16 04:01:34,872 [salt.state       ][INFO    ][31980] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-16 04:01:34,895 [salt.fileclient  ][INFO    ][31980] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-16 04:01:34,926 [salt.fileclient  ][INFO    ][31980] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-16 04:01:34,930 [salt.state       ][INFO    ][31980] File /etc/keepalived/keepalived.conf is in the correct state
2017-11-16 04:01:34,930 [salt.state       ][INFO    ][31980] Completed state [/etc/keepalived/keepalived.conf] at time 04:01:34.930004 duration_in_ms=58.247
2017-11-16 04:01:34,931 [salt.state       ][INFO    ][31980] Running state [keepalived] at time 04:01:34.930538
2017-11-16 04:01:34,931 [salt.state       ][INFO    ][31980] Executing state service.running for keepalived
2017-11-16 04:01:34,931 [salt.loaded.int.module.cmdmod][INFO    ][31980] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-16 04:01:34,948 [salt.loaded.int.module.cmdmod][INFO    ][31980] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-16 04:01:34,954 [salt.loaded.int.module.cmdmod][INFO    ][31980] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-16 04:01:34,959 [salt.state       ][INFO    ][31980] The service keepalived is already running
2017-11-16 04:01:34,959 [salt.state       ][INFO    ][31980] Completed state [keepalived] at time 04:01:34.959427 duration_in_ms=28.889
2017-11-16 04:01:34,960 [salt.minion      ][INFO    ][31980] Returning information for job: 20171116040133871758
2017-11-16 04:01:35,917 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command pillar.get with jid 20171116040135910708
2017-11-16 04:01:35,937 [salt.minion      ][INFO    ][32004] Starting a new job with PID 32004
2017-11-16 04:01:35,942 [salt.minion      ][INFO    ][32004] Returning information for job: 20171116040135910708
2017-11-16 04:02:12,572 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command state.sls with jid 20171116040212564877
2017-11-16 04:02:12,586 [salt.minion      ][INFO    ][32011] Starting a new job with PID 32011
2017-11-16 04:02:13,003 [salt.state       ][INFO    ][32011] Loading fresh modules for state activity
2017-11-16 04:02:13,026 [salt.fileclient  ][INFO    ][32011] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-16 04:02:13,047 [salt.fileclient  ][INFO    ][32011] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-16 04:02:13,323 [salt.state       ][INFO    ][32011] Running state [attr] at time 04:02:13.323103
2017-11-16 04:02:13,323 [salt.state       ][INFO    ][32011] Executing state pkg.installed for attr
2017-11-16 04:02:13,324 [salt.loaded.int.module.cmdmod][INFO    ][32011] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-16 04:02:13,570 [salt.state       ][INFO    ][32011] Package attr is already installed
2017-11-16 04:02:13,570 [salt.state       ][INFO    ][32011] Completed state [attr] at time 04:02:13.570157 duration_in_ms=247.053
2017-11-16 04:02:13,570 [salt.state       ][INFO    ][32011] Running state [glusterfs-server] at time 04:02:13.570385
2017-11-16 04:02:13,571 [salt.state       ][INFO    ][32011] Executing state pkg.installed for glusterfs-server
2017-11-16 04:02:13,573 [salt.state       ][INFO    ][32011] Package glusterfs-server is already installed
2017-11-16 04:02:13,573 [salt.state       ][INFO    ][32011] Completed state [glusterfs-server] at time 04:02:13.573293 duration_in_ms=2.908
2017-11-16 04:02:13,573 [salt.state       ][INFO    ][32011] Running state [bc] at time 04:02:13.573459
2017-11-16 04:02:13,574 [salt.state       ][INFO    ][32011] Executing state pkg.installed for bc
2017-11-16 04:02:13,576 [salt.state       ][INFO    ][32011] Package bc is already installed
2017-11-16 04:02:13,576 [salt.state       ][INFO    ][32011] Completed state [bc] at time 04:02:13.576303 duration_in_ms=2.843
2017-11-16 04:02:13,577 [salt.state       ][INFO    ][32011] Running state [glusterfs-server] at time 04:02:13.576936
2017-11-16 04:02:13,577 [salt.state       ][INFO    ][32011] Executing state service.running for glusterfs-server
2017-11-16 04:02:13,578 [salt.loaded.int.module.cmdmod][INFO    ][32011] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-16 04:02:13,592 [salt.loaded.int.module.cmdmod][INFO    ][32011] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-16 04:02:13,599 [salt.loaded.int.module.cmdmod][INFO    ][32011] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-16 04:02:13,608 [salt.state       ][INFO    ][32011] The service glusterfs-server is already running
2017-11-16 04:02:13,621 [salt.state       ][INFO    ][32011] Completed state [glusterfs-server] at time 04:02:13.620702 duration_in_ms=43.763
2017-11-16 04:02:13,622 [salt.state       ][INFO    ][32011] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 04:02:13.622091
2017-11-16 04:02:13,622 [salt.state       ][INFO    ][32011] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-16 04:02:13,636 [salt.state       ][INFO    ][32011] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-16 04:02:13,636 [salt.state       ][INFO    ][32011] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 04:02:13.636366 duration_in_ms=14.275
2017-11-16 04:02:13,637 [salt.state       ][INFO    ][32011] Running state [/srv/glusterfs/glance] at time 04:02:13.636585
2017-11-16 04:02:13,637 [salt.state       ][INFO    ][32011] Executing state file.directory for /srv/glusterfs/glance
2017-11-16 04:02:13,637 [salt.state       ][INFO    ][32011] Directory /srv/glusterfs/glance is in the correct state
2017-11-16 04:02:13,637 [salt.state       ][INFO    ][32011] Completed state [/srv/glusterfs/glance] at time 04:02:13.637283 duration_in_ms=0.698
2017-11-16 04:02:13,637 [salt.state       ][INFO    ][32011] Running state [/srv/glusterfs/keystone-keys] at time 04:02:13.637474
2017-11-16 04:02:13,638 [salt.state       ][INFO    ][32011] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-16 04:02:13,638 [salt.state       ][INFO    ][32011] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-16 04:02:13,638 [salt.state       ][INFO    ][32011] Completed state [/srv/glusterfs/keystone-keys] at time 04:02:13.638136 duration_in_ms=0.662
2017-11-16 04:02:13,638 [salt.state       ][INFO    ][32011] Running state [/srv/glusterfs/nova_instances] at time 04:02:13.638295
2017-11-16 04:02:13,638 [salt.state       ][INFO    ][32011] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-16 04:02:13,639 [salt.state       ][INFO    ][32011] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-16 04:02:13,639 [salt.state       ][INFO    ][32011] Completed state [/srv/glusterfs/nova_instances] at time 04:02:13.638947 duration_in_ms=0.651
2017-11-16 04:02:13,639 [salt.state       ][INFO    ][32011] Running state [/srv/glusterfs/keystone-credential-keys] at time 04:02:13.639118
2017-11-16 04:02:13,639 [salt.state       ][INFO    ][32011] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-16 04:02:13,640 [salt.state       ][INFO    ][32011] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-16 04:02:13,640 [salt.state       ][INFO    ][32011] Completed state [/srv/glusterfs/keystone-credential-keys] at time 04:02:13.639780 duration_in_ms=0.662
2017-11-16 04:02:13,640 [salt.minion      ][INFO    ][32011] Returning information for job: 20171116040212564877
2017-11-16 04:02:14,834 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command test.ping with jid 20171116040214824486
2017-11-16 04:02:14,848 [salt.minion      ][INFO    ][32034] Starting a new job with PID 32034
2017-11-16 04:02:14,878 [salt.minion      ][INFO    ][32034] Returning information for job: 20171116040214824486
2017-11-16 04:02:23,727 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command state.sls with jid 20171116040223718785
2017-11-16 04:02:23,743 [salt.minion      ][INFO    ][32040] Starting a new job with PID 32040
2017-11-16 04:02:24,151 [salt.state       ][INFO    ][32040] Loading fresh modules for state activity
2017-11-16 04:02:24,171 [salt.fileclient  ][INFO    ][32040] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-16 04:02:24,216 [salt.fileclient  ][INFO    ][32040] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-16 04:02:24,275 [salt.fileclient  ][INFO    ][32040] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-16 04:02:24,293 [salt.fileclient  ][INFO    ][32040] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-16 04:02:24,543 [salt.state       ][INFO    ][32040] Running state [attr] at time 04:02:24.542962
2017-11-16 04:02:24,543 [salt.state       ][INFO    ][32040] Executing state pkg.installed for attr
2017-11-16 04:02:24,544 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-16 04:02:24,783 [salt.state       ][INFO    ][32040] Package attr is already installed
2017-11-16 04:02:24,783 [salt.state       ][INFO    ][32040] Completed state [attr] at time 04:02:24.782870 duration_in_ms=239.909
2017-11-16 04:02:24,783 [salt.state       ][INFO    ][32040] Running state [glusterfs-server] at time 04:02:24.783069
2017-11-16 04:02:24,783 [salt.state       ][INFO    ][32040] Executing state pkg.installed for glusterfs-server
2017-11-16 04:02:24,786 [salt.state       ][INFO    ][32040] Package glusterfs-server is already installed
2017-11-16 04:02:24,786 [salt.state       ][INFO    ][32040] Completed state [glusterfs-server] at time 04:02:24.785959 duration_in_ms=2.89
2017-11-16 04:02:24,786 [salt.state       ][INFO    ][32040] Running state [bc] at time 04:02:24.786098
2017-11-16 04:02:24,786 [salt.state       ][INFO    ][32040] Executing state pkg.installed for bc
2017-11-16 04:02:24,789 [salt.state       ][INFO    ][32040] Package bc is already installed
2017-11-16 04:02:24,789 [salt.state       ][INFO    ][32040] Completed state [bc] at time 04:02:24.788945 duration_in_ms=2.848
2017-11-16 04:02:24,790 [salt.state       ][INFO    ][32040] Running state [glusterfs-server] at time 04:02:24.789717
2017-11-16 04:02:24,790 [salt.state       ][INFO    ][32040] Executing state service.running for glusterfs-server
2017-11-16 04:02:24,790 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-16 04:02:24,806 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-16 04:02:24,813 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-16 04:02:24,821 [salt.state       ][INFO    ][32040] The service glusterfs-server is already running
2017-11-16 04:02:24,822 [salt.state       ][INFO    ][32040] Completed state [glusterfs-server] at time 04:02:24.821547 duration_in_ms=31.83
2017-11-16 04:02:24,823 [salt.state       ][INFO    ][32040] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 04:02:24.823105
2017-11-16 04:02:24,823 [salt.state       ][INFO    ][32040] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-16 04:02:24,833 [salt.state       ][INFO    ][32040] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-16 04:02:24,833 [salt.state       ][INFO    ][32040] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 04:02:24.832964 duration_in_ms=9.859
2017-11-16 04:02:24,833 [salt.state       ][INFO    ][32040] Running state [/srv/glusterfs/glance] at time 04:02:24.833140
2017-11-16 04:02:24,833 [salt.state       ][INFO    ][32040] Executing state file.directory for /srv/glusterfs/glance
2017-11-16 04:02:24,834 [salt.state       ][INFO    ][32040] Directory /srv/glusterfs/glance is in the correct state
2017-11-16 04:02:24,834 [salt.state       ][INFO    ][32040] Completed state [/srv/glusterfs/glance] at time 04:02:24.833841 duration_in_ms=0.701
2017-11-16 04:02:24,834 [salt.state       ][INFO    ][32040] Running state [/srv/glusterfs/keystone-keys] at time 04:02:24.834010
2017-11-16 04:02:24,834 [salt.state       ][INFO    ][32040] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-16 04:02:24,835 [salt.state       ][INFO    ][32040] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-16 04:02:24,835 [salt.state       ][INFO    ][32040] Completed state [/srv/glusterfs/keystone-keys] at time 04:02:24.834769 duration_in_ms=0.76
2017-11-16 04:02:24,835 [salt.state       ][INFO    ][32040] Running state [/srv/glusterfs/nova_instances] at time 04:02:24.834936
2017-11-16 04:02:24,835 [salt.state       ][INFO    ][32040] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-16 04:02:24,835 [salt.state       ][INFO    ][32040] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-16 04:02:24,836 [salt.state       ][INFO    ][32040] Completed state [/srv/glusterfs/nova_instances] at time 04:02:24.835624 duration_in_ms=0.688
2017-11-16 04:02:24,836 [salt.state       ][INFO    ][32040] Running state [/srv/glusterfs/keystone-credential-keys] at time 04:02:24.835790
2017-11-16 04:02:24,836 [salt.state       ][INFO    ][32040] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-16 04:02:24,836 [salt.state       ][INFO    ][32040] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-16 04:02:24,836 [salt.state       ][INFO    ][32040] Completed state [/srv/glusterfs/keystone-credential-keys] at time 04:02:24.836469 duration_in_ms=0.679
2017-11-16 04:02:24,877 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command ['git', '--version'] in directory '/root'
2017-11-16 04:02:24,957 [salt.state       ][INFO    ][32040] Running state [sleep 5] at time 04:02:24.956491
2017-11-16 04:02:24,957 [salt.state       ][INFO    ][32040] Executing state cmd.wait for sleep 5
2017-11-16 04:02:24,957 [salt.state       ][INFO    ][32040] No changes made for sleep 5
2017-11-16 04:02:24,957 [salt.state       ][INFO    ][32040] Completed state [sleep 5] at time 04:02:24.957084 duration_in_ms=0.594
2017-11-16 04:02:24,957 [salt.state       ][INFO    ][32040] Running state [10.167.4.141] at time 04:02:24.957477
2017-11-16 04:02:24,958 [salt.state       ][INFO    ][32040] Executing state glusterfs.peered for 10.167.4.141
2017-11-16 04:02:24,958 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:24,968 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:25,020 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:25,027 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:25,079 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:25,087 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:25,137 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:25,147 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:25,194 [salt.state       ][INFO    ][32040] Peering with localhost is not needed
2017-11-16 04:02:25,194 [salt.state       ][INFO    ][32040] Completed state [10.167.4.141] at time 04:02:25.193817 duration_in_ms=236.34
2017-11-16 04:02:25,196 [salt.state       ][INFO    ][32040] Running state [10.167.4.142] at time 04:02:25.196079
2017-11-16 04:02:25,196 [salt.state       ][INFO    ][32040] Executing state glusterfs.peered for 10.167.4.142
2017-11-16 04:02:25,197 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:25,206 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:25,254 [salt.state       ][INFO    ][32040] Host 10.167.4.142 already peered
2017-11-16 04:02:25,254 [salt.state       ][INFO    ][32040] Completed state [10.167.4.142] at time 04:02:25.254262 duration_in_ms=58.183
2017-11-16 04:02:25,256 [salt.state       ][INFO    ][32040] Running state [10.167.4.143] at time 04:02:25.256407
2017-11-16 04:02:25,257 [salt.state       ][INFO    ][32040] Executing state glusterfs.peered for 10.167.4.143
2017-11-16 04:02:25,257 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:25,266 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:25,312 [salt.state       ][INFO    ][32040] Host 10.167.4.143 already peered
2017-11-16 04:02:25,313 [salt.state       ][INFO    ][32040] Completed state [10.167.4.143] at time 04:02:25.312557 duration_in_ms=56.149
2017-11-16 04:02:25,313 [salt.state       ][INFO    ][32040] Running state [glance] at time 04:02:25.313313
2017-11-16 04:02:25,314 [salt.state       ][INFO    ][32040] Executing state glusterfs.created for glance
2017-11-16 04:02:25,314 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:25,322 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:25,369 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:25,378 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:25,438 [salt.state       ][INFO    ][32040] Volume glance already exists and is started.
2017-11-16 04:02:25,438 [salt.state       ][INFO    ][32040] Completed state [glance] at time 04:02:25.437859 duration_in_ms=124.546
2017-11-16 04:02:25,440 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 04:02:25.440450
2017-11-16 04:02:25,441 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-16 04:02:25,441 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-16 04:02:25,489 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:25,489 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 04:02:25.489004 duration_in_ms=48.552
2017-11-16 04:02:25,490 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 04:02:25.489479
2017-11-16 04:02:25,490 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-16 04:02:25,490 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-16 04:02:25,539 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:25,539 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 04:02:25.539268 duration_in_ms=49.788
2017-11-16 04:02:25,540 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 04:02:25.539687
2017-11-16 04:02:25,540 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-16 04:02:25,540 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-16 04:02:25,587 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:25,587 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 04:02:25.586932 duration_in_ms=47.244
2017-11-16 04:02:25,587 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 04:02:25.587310
2017-11-16 04:02:25,588 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-16 04:02:25,588 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-16 04:02:25,635 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:25,635 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 04:02:25.635427 duration_in_ms=48.117
2017-11-16 04:02:25,636 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 04:02:25.635842
2017-11-16 04:02:25,636 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-16 04:02:25,636 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-16 04:02:25,684 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:25,684 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 04:02:25.683904 duration_in_ms=48.062
2017-11-16 04:02:25,685 [salt.state       ][INFO    ][32040] Running state [glance] at time 04:02:25.684803
2017-11-16 04:02:25,685 [salt.state       ][INFO    ][32040] Executing state glusterfs.started for glance
2017-11-16 04:02:25,685 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:25,693 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:25,739 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:25,747 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:25,796 [salt.state       ][INFO    ][32040] Volume glance is already started
2017-11-16 04:02:25,797 [salt.state       ][INFO    ][32040] Completed state [glance] at time 04:02:25.796545 duration_in_ms=111.741
2017-11-16 04:02:25,797 [salt.state       ][INFO    ][32040] Running state [keystone-keys] at time 04:02:25.797315
2017-11-16 04:02:25,798 [salt.state       ][INFO    ][32040] Executing state glusterfs.created for keystone-keys
2017-11-16 04:02:25,798 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:25,805 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:25,852 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:25,859 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:25,909 [salt.state       ][INFO    ][32040] Volume keystone-keys already exists and is started.
2017-11-16 04:02:25,909 [salt.state       ][INFO    ][32040] Completed state [keystone-keys] at time 04:02:25.908983 duration_in_ms=111.667
2017-11-16 04:02:25,912 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 04:02:25.911741
2017-11-16 04:02:25,912 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-16 04:02:25,912 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-16 04:02:25,961 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:25,961 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 04:02:25.960793 duration_in_ms=49.052
2017-11-16 04:02:25,961 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 04:02:25.961217
2017-11-16 04:02:25,961 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-16 04:02:25,962 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-16 04:02:26,007 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:26,008 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 04:02:26.007553 duration_in_ms=46.335
2017-11-16 04:02:26,008 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 04:02:26.007988
2017-11-16 04:02:26,008 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-16 04:02:26,009 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-16 04:02:26,055 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:26,055 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 04:02:26.055236 duration_in_ms=47.246
2017-11-16 04:02:26,056 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 04:02:26.055631
2017-11-16 04:02:26,056 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-16 04:02:26,056 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-16 04:02:26,104 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:26,104 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 04:02:26.104437 duration_in_ms=48.806
2017-11-16 04:02:26,105 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 04:02:26.104882
2017-11-16 04:02:26,105 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-16 04:02:26,106 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-16 04:02:26,153 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:26,153 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 04:02:26.153081 duration_in_ms=48.199
2017-11-16 04:02:26,154 [salt.state       ][INFO    ][32040] Running state [keystone-keys] at time 04:02:26.153948
2017-11-16 04:02:26,154 [salt.state       ][INFO    ][32040] Executing state glusterfs.started for keystone-keys
2017-11-16 04:02:26,155 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:26,162 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:26,206 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:26,213 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:26,263 [salt.state       ][INFO    ][32040] Volume keystone-keys is already started
2017-11-16 04:02:26,263 [salt.state       ][INFO    ][32040] Completed state [keystone-keys] at time 04:02:26.263292 duration_in_ms=109.343
2017-11-16 04:02:26,264 [salt.state       ][INFO    ][32040] Running state [nova_instances] at time 04:02:26.264042
2017-11-16 04:02:26,264 [salt.state       ][INFO    ][32040] Executing state glusterfs.created for nova_instances
2017-11-16 04:02:26,265 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:26,273 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:26,318 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:26,326 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:26,376 [salt.state       ][INFO    ][32040] Volume nova_instances already exists and is started.
2017-11-16 04:02:26,377 [salt.state       ][INFO    ][32040] Completed state [nova_instances] at time 04:02:26.376502 duration_in_ms=112.46
2017-11-16 04:02:26,379 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 04:02:26.379415
2017-11-16 04:02:26,380 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-16 04:02:26,380 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-16 04:02:26,427 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:26,427 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 04:02:26.427079 duration_in_ms=47.664
2017-11-16 04:02:26,428 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 04:02:26.427490
2017-11-16 04:02:26,428 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-11-16 04:02:26,428 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-11-16 04:02:26,477 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:26,477 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 04:02:26.476737 duration_in_ms=49.246
2017-11-16 04:02:26,477 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 04:02:26.477176
2017-11-16 04:02:26,477 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-11-16 04:02:26,478 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-11-16 04:02:26,528 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:26,528 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 04:02:26.528281 duration_in_ms=51.105
2017-11-16 04:02:26,529 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 04:02:26.528720
2017-11-16 04:02:26,529 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-11-16 04:02:26,529 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-11-16 04:02:26,582 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:26,582 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 04:02:26.581857 duration_in_ms=53.137
2017-11-16 04:02:26,582 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 04:02:26.582339
2017-11-16 04:02:26,583 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-16 04:02:26,583 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-16 04:02:26,631 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:26,631 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 04:02:26.630847 duration_in_ms=48.507
2017-11-16 04:02:26,632 [salt.state       ][INFO    ][32040] Running state [nova_instances] at time 04:02:26.631771
2017-11-16 04:02:26,632 [salt.state       ][INFO    ][32040] Executing state glusterfs.started for nova_instances
2017-11-16 04:02:26,632 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:26,641 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:26,691 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:26,700 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:26,764 [salt.state       ][INFO    ][32040] Volume nova_instances is already started
2017-11-16 04:02:26,764 [salt.state       ][INFO    ][32040] Completed state [nova_instances] at time 04:02:26.764254 duration_in_ms=132.482
2017-11-16 04:02:26,765 [salt.state       ][INFO    ][32040] Running state [keystone-credential-keys] at time 04:02:26.765177
2017-11-16 04:02:26,765 [salt.state       ][INFO    ][32040] Executing state glusterfs.created for keystone-credential-keys
2017-11-16 04:02:26,766 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:26,776 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:26,827 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:26,836 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:26,888 [salt.state       ][INFO    ][32040] Volume keystone-credential-keys already exists and is started.
2017-11-16 04:02:26,889 [salt.state       ][INFO    ][32040] Completed state [keystone-credential-keys] at time 04:02:26.888584 duration_in_ms=123.406
2017-11-16 04:02:26,892 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 04:02:26.891548
2017-11-16 04:02:26,892 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-16 04:02:26,892 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-16 04:02:26,940 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:26,941 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 04:02:26.940489 duration_in_ms=48.941
2017-11-16 04:02:26,941 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 04:02:26.940957
2017-11-16 04:02:26,941 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-16 04:02:26,942 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-16 04:02:26,990 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:26,990 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 04:02:26.990029 duration_in_ms=49.071
2017-11-16 04:02:26,991 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 04:02:26.990502
2017-11-16 04:02:26,991 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-16 04:02:26,991 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-16 04:02:27,038 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:27,039 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 04:02:27.038695 duration_in_ms=48.192
2017-11-16 04:02:27,039 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 04:02:27.039154
2017-11-16 04:02:27,039 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-16 04:02:27,040 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-16 04:02:27,089 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:27,089 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 04:02:27.089439 duration_in_ms=50.284
2017-11-16 04:02:27,090 [salt.state       ][INFO    ][32040] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 04:02:27.090007
2017-11-16 04:02:27,090 [salt.state       ][INFO    ][32040] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-16 04:02:27,091 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-16 04:02:27,139 [salt.state       ][INFO    ][32040] unless execution succeeded
2017-11-16 04:02:27,139 [salt.state       ][INFO    ][32040] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 04:02:27.139445 duration_in_ms=49.438
2017-11-16 04:02:27,140 [salt.state       ][INFO    ][32040] Running state [keystone-credential-keys] at time 04:02:27.140373
2017-11-16 04:02:27,141 [salt.state       ][INFO    ][32040] Executing state glusterfs.started for keystone-credential-keys
2017-11-16 04:02:27,141 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:27,149 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:27,197 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --version' in directory '/root'
2017-11-16 04:02:27,205 [salt.loaded.int.module.cmdmod][INFO    ][32040] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-16 04:02:27,258 [salt.state       ][INFO    ][32040] Volume keystone-credential-keys is already started
2017-11-16 04:02:27,259 [salt.state       ][INFO    ][32040] Completed state [keystone-credential-keys] at time 04:02:27.258570 duration_in_ms=118.197
2017-11-16 04:02:27,260 [salt.minion      ][INFO    ][32040] Returning information for job: 20171116040223718785
2017-11-16 04:02:28,459 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command test.ping with jid 20171116040228450969
2017-11-16 04:02:28,474 [salt.minion      ][INFO    ][32423] Starting a new job with PID 32423
2017-11-16 04:02:28,500 [salt.minion      ][INFO    ][32423] Returning information for job: 20171116040228450969
2017-11-16 04:02:29,150 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command cmd.run with jid 20171116040229141842
2017-11-16 04:02:29,165 [salt.minion      ][INFO    ][32428] Starting a new job with PID 32428
2017-11-16 04:02:29,169 [salt.loaded.int.module.cmdmod][INFO    ][32428] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-16 04:02:29,284 [salt.minion      ][INFO    ][32428] Returning information for job: 20171116040229141842
2017-11-16 04:32:41,931 [salt.utils.schedule][INFO    ][23288] Running scheduled job: __mine_interval
2017-11-16 04:32:44,693 [salt.minion      ][INFO    ][23288] User sudo_ubuntu Executing command cp.push_dir with jid 20171116043244685170
2017-11-16 04:32:44,714 [salt.minion      ][INFO    ][32746] Starting a new job with PID 32746
