2017-11-23 09:11:24,255 [salt.cli.daemons ][WARNING ][2376] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-23 09:11:42,707 [salt.state       ][ERROR   ][3624] No contents found in top file
2017-11-23 09:13:28,680 [salt.utils.parsers][WARNING ][2376] Minion received a SIGTERM. Exiting.
2017-11-23 09:15:41,498 [salt.cli.daemons ][WARNING ][3147] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-23 09:18:08,288 [salt.transport.ipc][ERROR   ][3147] Exception occurred while handling stream: [Errno 0] Success
2017-11-23 09:18:44,173 [py.warnings      ][WARNING ][14747] /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-23 09:24:52,050 [salt.loaded.int.module.cmdmod][ERROR   ][14747] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-23 09:24:52,051 [salt.loaded.int.module.cmdmod][ERROR   ][14747] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-23 09:24:58,528 [salt.loaded.int.module.cmdmod][ERROR   ][14747] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-23 09:24:58,529 [salt.loaded.int.module.cmdmod][ERROR   ][14747] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-23 09:25:06,480 [salt.loaded.int.module.cmdmod][ERROR   ][14747] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-23 09:25:06,480 [salt.loaded.int.module.cmdmod][ERROR   ][14747] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-23 09:25:15,926 [salt.loaded.int.module.cmdmod][ERROR   ][14747] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-23 09:25:15,927 [salt.loaded.int.module.cmdmod][ERROR   ][14747] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-23 09:25:24,579 [salt.loaded.int.module.cmdmod][ERROR   ][14747] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-23 09:25:24,579 [salt.loaded.int.module.cmdmod][ERROR   ][14747] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-23 09:25:30,719 [salt.loaded.int.module.cmdmod][ERROR   ][14747] 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-23 09:25:34,399 [salt.state       ][ERROR   ][22419] No contents found in top file
2017-11-23 09:25:39,387 [salt.loaded.int.module.cmdmod][INFO    ][22766] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-23 09:25:39,394 [salt.loaded.int.module.cmdmod][INFO    ][22766] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-23 09:25:39,404 [salt.loaded.int.module.cmdmod][INFO    ][22766] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-11-23 09:25:39,412 [salt.utils.parsers][WARNING ][3147] Minion received a SIGTERM. Exiting.
2017-11-23 09:25:39,722 [salt.cli.daemons ][INFO    ][22846] Setting up the Salt Minion "kvm03.baremetal-mcp-ocata-ovs-ha.local"
2017-11-23 09:25:40,245 [salt.minion      ][INFO    ][22846] Creating minion process manager
2017-11-23 09:25:40,245 [salt.cli.daemons ][WARNING ][22846] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-23 09:25:40,245 [salt.cli.daemons ][INFO    ][22846] The Salt Minion is starting up
2017-11-23 09:25:40,245 [salt.minion      ][INFO    ][22846] Minion is starting as user 'root'
2017-11-23 09:25:40,246 [salt.utils.event ][INFO    ][22846] Starting pull socket on /var/run/salt/minion/minion_event_98ff1e569f_pull.ipc
2017-11-23 09:25:41,087 [salt.loaded.int.module.cmdmod][INFO    ][22846] Executing command ['date', '+%z'] in directory '/root'
2017-11-23 09:25:41,101 [salt.utils.schedule][INFO    ][22846] Updating job settings for scheduled job: __mine_interval
2017-11-23 09:25:41,103 [salt.minion      ][INFO    ][22846] Added mine.update to scheduler
2017-11-23 09:25:41,159 [salt.minion      ][INFO    ][22846] Minion is ready to receive requests!
2017-11-23 09:25:42,167 [salt.utils.schedule][INFO    ][22846] Running scheduled job: __mine_interval
2017-11-23 09:25:52,982 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command state.sls with jid 20171123092552978168
2017-11-23 09:25:52,998 [salt.minion      ][INFO    ][22948] Starting a new job with PID 22948
2017-11-23 09:25:55,430 [salt.state       ][INFO    ][22948] Loading fresh modules for state activity
2017-11-23 09:25:55,454 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-11-23 09:25:55,472 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-11-23 09:25:55,495 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-23 09:25:55,564 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-11-23 09:25:55,578 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-11-23 09:25:55,604 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-23 09:25:55,767 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-23 09:25:55,784 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-23 09:25:55,843 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-23 09:25:55,861 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-23 09:25:55,875 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-23 09:25:55,890 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-23 09:25:55,897 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-23 09:25:55,919 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-23 09:25:55,934 [py.warnings      ][WARNING ][22948] /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-23 09:25:55,934 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command 'salt-minion --version' in directory '/root'
2017-11-23 09:25:56,185 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-23 09:25:56,204 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-23 09:25:56,216 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-23 09:25:56,230 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-23 09:25:56,345 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-11-23 09:25:56,359 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-11-23 09:25:56,440 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-23 09:25:56,478 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-23 09:25:56,618 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-11-23 09:25:56,689 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-23 09:25:56,747 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-23 09:25:56,813 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-23 09:25:56,856 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-23 09:25:56,874 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-23 09:25:56,888 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-23 09:25:56,904 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-23 09:25:56,910 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-23 09:25:56,931 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-23 09:25:56,946 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command 'salt-minion --version' in directory '/root'
2017-11-23 09:25:57,256 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-23 09:25:57,273 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-23 09:25:57,285 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-23 09:25:57,297 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-23 09:25:57,367 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-11-23 09:25:57,381 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-11-23 09:25:57,404 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-23 09:25:57,439 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-23 09:25:57,509 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-11-23 09:25:57,623 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-23 09:25:57,959 [salt.state       ][INFO    ][22948] Running state [salt-minion] at time 09:25:57.958499
2017-11-23 09:25:57,959 [salt.state       ][INFO    ][22948] Executing state pkg.installed for salt-minion
2017-11-23 09:25:57,959 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-23 09:25:58,221 [salt.state       ][INFO    ][22948] Package salt-minion is already installed
2017-11-23 09:25:58,221 [salt.state       ][INFO    ][22948] Completed state [salt-minion] at time 09:25:58.220830 duration_in_ms=262.331
2017-11-23 09:25:58,221 [salt.state       ][INFO    ][22948] Running state [salt_minion_dependency_packages] at time 09:25:58.221091
2017-11-23 09:25:58,221 [salt.state       ][INFO    ][22948] Executing state pkg.installed for salt_minion_dependency_packages
2017-11-23 09:25:58,224 [salt.state       ][INFO    ][22948] All specified packages are already installed
2017-11-23 09:25:58,224 [salt.state       ][INFO    ][22948] Completed state [salt_minion_dependency_packages] at time 09:25:58.224060 duration_in_ms=2.969
2017-11-23 09:25:58,226 [salt.state       ][INFO    ][22948] Running state [/etc/salt/minion.d/minion.conf] at time 09:25:58.225561
2017-11-23 09:25:58,226 [salt.state       ][INFO    ][22948] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-11-23 09:25:58,246 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-11-23 09:25:58,291 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-23 09:25:58,354 [salt.fileclient  ][INFO    ][22948] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-23 09:25:58,389 [salt.state       ][INFO    ][22948] File /etc/salt/minion.d/minion.conf is in the correct state
2017-11-23 09:25:58,389 [salt.state       ][INFO    ][22948] Completed state [/etc/salt/minion.d/minion.conf] at time 09:25:58.389270 duration_in_ms=163.708
2017-11-23 09:25:58,390 [salt.state       ][INFO    ][22948] Running state [/etc/salt/minion.d/_virt.conf] at time 09:25:58.389671
2017-11-23 09:25:58,390 [salt.state       ][INFO    ][22948] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-11-23 09:25:58,400 [salt.state       ][INFO    ][22948] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-11-23 09:25:58,401 [salt.state       ][INFO    ][22948] Completed state [/etc/salt/minion.d/_virt.conf] at time 09:25:58.400666 duration_in_ms=10.995
2017-11-23 09:25:58,402 [salt.state       ][INFO    ][22948] Running state [salt-minion] at time 09:25:58.402199
2017-11-23 09:25:58,402 [salt.state       ][INFO    ][22948] Executing state service.running for salt-minion
2017-11-23 09:25:58,403 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-23 09:25:58,415 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-11-23 09:25:58,422 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-23 09:25:58,434 [salt.state       ][INFO    ][22948] The service salt-minion is already running
2017-11-23 09:25:58,434 [salt.state       ][INFO    ][22948] Completed state [salt-minion] at time 09:25:58.433851 duration_in_ms=31.651
2017-11-23 09:25:58,435 [salt.state       ][INFO    ][22948] Running state [/etc/salt/grains.d] at time 09:25:58.435155
2017-11-23 09:25:58,435 [salt.state       ][INFO    ][22948] Executing state file.directory for /etc/salt/grains.d
2017-11-23 09:25:58,436 [salt.state       ][INFO    ][22948] Directory /etc/salt/grains.d is in the correct state
2017-11-23 09:25:58,436 [salt.state       ][INFO    ][22948] Completed state [/etc/salt/grains.d] at time 09:25:58.436007 duration_in_ms=0.852
2017-11-23 09:25:58,436 [salt.state       ][INFO    ][22948] Running state [/etc/salt/grains] at time 09:25:58.436400
2017-11-23 09:25:58,437 [salt.state       ][INFO    ][22948] Executing state file.managed for /etc/salt/grains
2017-11-23 09:25:58,437 [salt.state       ][INFO    ][22948] File /etc/salt/grains exists with proper permissions. No changes made.
2017-11-23 09:25:58,437 [salt.state       ][INFO    ][22948] Completed state [/etc/salt/grains] at time 09:25:58.436988 duration_in_ms=0.589
2017-11-23 09:25:58,437 [salt.state       ][INFO    ][22948] Running state [/etc/salt/grains.d/placeholder] at time 09:25:58.437240
2017-11-23 09:25:58,437 [salt.state       ][INFO    ][22948] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-11-23 09:25:58,438 [salt.state       ][INFO    ][22948] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-11-23 09:25:58,438 [salt.state       ][INFO    ][22948] Completed state [/etc/salt/grains.d/placeholder] at time 09:25:58.437778 duration_in_ms=0.537
2017-11-23 09:25:58,438 [salt.state       ][INFO    ][22948] Running state [/etc/salt/grains.d/sphinx] at time 09:25:58.438028
2017-11-23 09:25:58,438 [salt.state       ][INFO    ][22948] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-11-23 09:25:58,557 [salt.state       ][INFO    ][22948] File changed:
--- 
+++ 
@@ -49,7 +49,6 @@
               - 10.167.4.143
               - 127.0.0.1
               - 192.168.11.254
-              - 192.168.122.1
         system:
           name: System
           param:

2017-11-23 09:25:58,558 [salt.state       ][INFO    ][22948] Completed state [/etc/salt/grains.d/sphinx] at time 09:25:58.557748 duration_in_ms=119.719
2017-11-23 09:25:58,559 [salt.state       ][INFO    ][22948] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:25:58.558638
2017-11-23 09:25:58,559 [salt.state       ][INFO    ][22948] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-23 09:25:58,559 [salt.state       ][INFO    ][22948] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-23 09:25:58,559 [salt.state       ][INFO    ][22948] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:25:58.559366 duration_in_ms=0.727
2017-11-23 09:25:58,560 [salt.state       ][INFO    ][22948] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:25:58.559534
2017-11-23 09:25:58,560 [salt.state       ][INFO    ][22948] 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-23 09:25:58,561 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command 'python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"' in directory '/root'
2017-11-23 09:25:58,698 [salt.state       ][INFO    ][22948] {'pid': 22996, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-23 09:25:58,698 [salt.state       ][INFO    ][22948] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:25:58.698267 duration_in_ms=138.732
2017-11-23 09:25:58,699 [salt.state       ][INFO    ][22948] Running state [/etc/salt/grains.d/dns_records] at time 09:25:58.698697
2017-11-23 09:25:58,699 [salt.state       ][INFO    ][22948] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-11-23 09:25:58,848 [salt.state       ][INFO    ][22948] File /etc/salt/grains.d/dns_records is in the correct state
2017-11-23 09:25:58,849 [salt.state       ][INFO    ][22948] Completed state [/etc/salt/grains.d/dns_records] at time 09:25:58.848627 duration_in_ms=149.929
2017-11-23 09:25:58,849 [salt.state       ][INFO    ][22948] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:25:58.849342
2017-11-23 09:25:58,850 [salt.state       ][INFO    ][22948] 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-23 09:25:58,850 [salt.state       ][INFO    ][22948] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-23 09:25:58,850 [salt.state       ][INFO    ][22948] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:25:58.849879 duration_in_ms=0.537
2017-11-23 09:25:58,850 [salt.state       ][INFO    ][22948] Running state [/etc/salt/grains.d/salt] at time 09:25:58.850136
2017-11-23 09:25:58,850 [salt.state       ][INFO    ][22948] Executing state file.managed for /etc/salt/grains.d/salt
2017-11-23 09:25:58,915 [salt.state       ][INFO    ][22948] File /etc/salt/grains.d/salt is in the correct state
2017-11-23 09:25:58,915 [salt.state       ][INFO    ][22948] Completed state [/etc/salt/grains.d/salt] at time 09:25:58.914900 duration_in_ms=64.764
2017-11-23 09:25:58,915 [salt.state       ][INFO    ][22948] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:25:58.915449
2017-11-23 09:25:58,916 [salt.state       ][INFO    ][22948] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-23 09:25:58,916 [salt.state       ][INFO    ][22948] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-23 09:25:58,916 [salt.state       ][INFO    ][22948] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:25:58.915924 duration_in_ms=0.475
2017-11-23 09:25:58,917 [salt.state       ][INFO    ][22948] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:25:58.917063
2017-11-23 09:25:58,917 [salt.state       ][INFO    ][22948] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-23 09:25:58,917 [salt.state       ][INFO    ][22948] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-23 09:25:58,918 [salt.state       ][INFO    ][22948] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:25:58.917532 duration_in_ms=0.469
2017-11-23 09:25:58,918 [salt.state       ][INFO    ][22948] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:25:58.917651
2017-11-23 09:25:58,918 [salt.state       ][INFO    ][22948] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-23 09:25:58,919 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-11-23 09:25:58,928 [salt.state       ][INFO    ][22948] {'pid': 22998, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-23 09:25:58,928 [salt.state       ][INFO    ][22948] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:25:58.928041 duration_in_ms=10.39
2017-11-23 09:25:58,929 [salt.state       ][INFO    ][22948] Running state [mine.update] at time 09:25:58.928656
2017-11-23 09:25:58,929 [salt.state       ][INFO    ][22948] Executing state module.wait for mine.update
2017-11-23 09:25:58,929 [salt.state       ][INFO    ][22948] No changes made for mine.update
2017-11-23 09:25:58,929 [salt.state       ][INFO    ][22948] Completed state [mine.update] at time 09:25:58.929257 duration_in_ms=0.602
2017-11-23 09:25:58,929 [salt.state       ][INFO    ][22948] Running state [mine.update] at time 09:25:58.929406
2017-11-23 09:25:58,930 [salt.state       ][INFO    ][22948] Executing state module.mod_watch for mine.update
2017-11-23 09:25:59,448 [salt.state       ][INFO    ][22948] {'ret': True}
2017-11-23 09:25:59,448 [salt.state       ][INFO    ][22948] Completed state [mine.update] at time 09:25:59.448034 duration_in_ms=518.628
2017-11-23 09:25:59,448 [salt.state       ][INFO    ][22948] Running state [ca-certificates] at time 09:25:59.448308
2017-11-23 09:25:59,449 [salt.state       ][INFO    ][22948] Executing state pkg.installed for ca-certificates
2017-11-23 09:25:59,453 [salt.state       ][INFO    ][22948] Package ca-certificates is already installed
2017-11-23 09:25:59,453 [salt.state       ][INFO    ][22948] Completed state [ca-certificates] at time 09:25:59.452773 duration_in_ms=4.465
2017-11-23 09:25:59,454 [salt.state       ][INFO    ][22948] Running state [update-ca-certificates] at time 09:25:59.453644
2017-11-23 09:25:59,454 [salt.state       ][INFO    ][22948] Executing state cmd.wait for update-ca-certificates
2017-11-23 09:25:59,454 [salt.state       ][INFO    ][22948] No changes made for update-ca-certificates
2017-11-23 09:25:59,454 [salt.state       ][INFO    ][22948] Completed state [update-ca-certificates] at time 09:25:59.454398 duration_in_ms=0.754
2017-11-23 09:25:59,455 [salt.state       ][INFO    ][22948] Running state [libvirt-dev] at time 09:25:59.454631
2017-11-23 09:25:59,455 [salt.state       ][INFO    ][22948] Executing state pkg.installed for libvirt-dev
2017-11-23 09:25:59,458 [salt.state       ][INFO    ][22948] Package libvirt-dev is already installed
2017-11-23 09:25:59,458 [salt.state       ][INFO    ][22948] Completed state [libvirt-dev] at time 09:25:59.457638 duration_in_ms=3.007
2017-11-23 09:25:59,458 [salt.state       ][INFO    ][22948] Running state [pkg-config] at time 09:25:59.457790
2017-11-23 09:25:59,458 [salt.state       ][INFO    ][22948] Executing state pkg.installed for pkg-config
2017-11-23 09:25:59,461 [salt.state       ][INFO    ][22948] Package pkg-config is already installed
2017-11-23 09:25:59,461 [salt.state       ][INFO    ][22948] Completed state [pkg-config] at time 09:25:59.460754 duration_in_ms=2.964
2017-11-23 09:25:59,461 [salt.state       ][INFO    ][22948] Running state [virtng.init] at time 09:25:59.460909
2017-11-23 09:25:59,461 [salt.state       ][INFO    ][22948] Executing state module.run for virtng.init
2017-11-23 09:25:59,613 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command ['git', '--version'] in directory '/root'
2017-11-23 09:25:59,707 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command 'virsh list --all --name| grep -E "^mdb03.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-23 09:25:59,724 [salt.state       ][INFO    ][22948] unless execution succeeded
2017-11-23 09:25:59,724 [salt.state       ][INFO    ][22948] Completed state [virtng.init] at time 09:25:59.724064 duration_in_ms=263.154
2017-11-23 09:25:59,724 [salt.state       ][INFO    ][22948] Running state [virt.set_autostart] at time 09:25:59.724311
2017-11-23 09:25:59,725 [salt.state       ][INFO    ][22948] Executing state module.run for virt.set_autostart
2017-11-23 09:25:59,727 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command 'virsh list --autostart --name| grep -E "^mdb03.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-23 09:25:59,742 [salt.state       ][INFO    ][22948] unless execution succeeded
2017-11-23 09:25:59,742 [salt.state       ][INFO    ][22948] Completed state [virt.set_autostart] at time 09:25:59.742094 duration_in_ms=17.783
2017-11-23 09:25:59,742 [salt.state       ][INFO    ][22948] Running state [virtng.init] at time 09:25:59.742326
2017-11-23 09:25:59,743 [salt.state       ][INFO    ][22948] Executing state module.run for virtng.init
2017-11-23 09:25:59,745 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command 'virsh list --all --name| grep -E "^dbs03.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-23 09:25:59,759 [salt.state       ][INFO    ][22948] unless execution succeeded
2017-11-23 09:25:59,759 [salt.state       ][INFO    ][22948] Completed state [virtng.init] at time 09:25:59.759325 duration_in_ms=16.997
2017-11-23 09:25:59,760 [salt.state       ][INFO    ][22948] Running state [virt.set_autostart] at time 09:25:59.759550
2017-11-23 09:25:59,760 [salt.state       ][INFO    ][22948] Executing state module.run for virt.set_autostart
2017-11-23 09:25:59,762 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command 'virsh list --autostart --name| grep -E "^dbs03.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-23 09:25:59,793 [salt.state       ][INFO    ][22948] unless execution succeeded
2017-11-23 09:25:59,793 [salt.state       ][INFO    ][22948] Completed state [virt.set_autostart] at time 09:25:59.793046 duration_in_ms=33.496
2017-11-23 09:25:59,793 [salt.state       ][INFO    ][22948] Running state [virtng.init] at time 09:25:59.793269
2017-11-23 09:25:59,793 [salt.state       ][INFO    ][22948] Executing state module.run for virtng.init
2017-11-23 09:25:59,795 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command 'virsh list --all --name| grep -E "^prx02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-23 09:25:59,810 [salt.state       ][INFO    ][22948] unless execution succeeded
2017-11-23 09:25:59,810 [salt.state       ][INFO    ][22948] Completed state [virtng.init] at time 09:25:59.809832 duration_in_ms=16.563
2017-11-23 09:25:59,810 [salt.state       ][INFO    ][22948] Running state [virt.set_autostart] at time 09:25:59.810058
2017-11-23 09:25:59,810 [salt.state       ][INFO    ][22948] Executing state module.run for virt.set_autostart
2017-11-23 09:25:59,812 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command 'virsh list --autostart --name| grep -E "^prx02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-23 09:25:59,827 [salt.state       ][INFO    ][22948] unless execution succeeded
2017-11-23 09:25:59,827 [salt.state       ][INFO    ][22948] Completed state [virt.set_autostart] at time 09:25:59.827317 duration_in_ms=17.258
2017-11-23 09:25:59,828 [salt.state       ][INFO    ][22948] Running state [virtng.init] at time 09:25:59.827538
2017-11-23 09:25:59,828 [salt.state       ][INFO    ][22948] Executing state module.run for virtng.init
2017-11-23 09:25:59,830 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command 'virsh list --all --name| grep -E "^msg03.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-23 09:25:59,844 [salt.state       ][INFO    ][22948] unless execution succeeded
2017-11-23 09:25:59,844 [salt.state       ][INFO    ][22948] Completed state [virtng.init] at time 09:25:59.843795 duration_in_ms=16.257
2017-11-23 09:25:59,844 [salt.state       ][INFO    ][22948] Running state [virt.set_autostart] at time 09:25:59.844010
2017-11-23 09:25:59,844 [salt.state       ][INFO    ][22948] Executing state module.run for virt.set_autostart
2017-11-23 09:25:59,846 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command 'virsh list --autostart --name| grep -E "^msg03.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-23 09:25:59,861 [salt.state       ][INFO    ][22948] unless execution succeeded
2017-11-23 09:25:59,861 [salt.state       ][INFO    ][22948] Completed state [virt.set_autostart] at time 09:25:59.861274 duration_in_ms=17.263
2017-11-23 09:25:59,862 [salt.state       ][INFO    ][22948] Running state [virtng.init] at time 09:25:59.861494
2017-11-23 09:25:59,862 [salt.state       ][INFO    ][22948] Executing state module.run for virtng.init
2017-11-23 09:25:59,864 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command 'virsh list --all --name| grep -E "^ctl03.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-23 09:25:59,878 [salt.state       ][INFO    ][22948] unless execution succeeded
2017-11-23 09:25:59,878 [salt.state       ][INFO    ][22948] Completed state [virtng.init] at time 09:25:59.877930 duration_in_ms=16.436
2017-11-23 09:25:59,878 [salt.state       ][INFO    ][22948] Running state [virt.set_autostart] at time 09:25:59.878148
2017-11-23 09:25:59,878 [salt.state       ][INFO    ][22948] Executing state module.run for virt.set_autostart
2017-11-23 09:25:59,880 [salt.loaded.int.module.cmdmod][INFO    ][22948] Executing command 'virsh list --autostart --name| grep -E "^ctl03.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-11-23 09:25:59,894 [salt.state       ][INFO    ][22948] unless execution succeeded
2017-11-23 09:25:59,964 [salt.state       ][INFO    ][22948] Completed state [virt.set_autostart] at time 09:25:59.963975 duration_in_ms=85.825
2017-11-23 09:25:59,966 [salt.minion      ][INFO    ][22948] Returning information for job: 20171123092552978168
2017-11-23 09:26:58,643 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171123092658634981
2017-11-23 09:26:58,660 [salt.minion      ][INFO    ][23059] Starting a new job with PID 23059
2017-11-23 09:27:01,961 [salt.state       ][INFO    ][23059] Loading fresh modules for state activity
2017-11-23 09:27:02,179 [salt.state       ][ERROR   ][23059] No contents found in top file
2017-11-23 09:27:02,180 [salt.utils.extmods][INFO    ][23059] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-11-23 09:27:02,183 [salt.utils.extmods][INFO    ][23059] Syncing beacons for environment 'base'
2017-11-23 09:27:02,183 [salt.utils.extmods][INFO    ][23059] Loading cache from salt://_beacons, for base)
2017-11-23 09:27:02,183 [salt.fileclient  ][INFO    ][23059] Caching directory '_beacons/' for environment 'base'
2017-11-23 09:27:02,203 [salt.utils.extmods][INFO    ][23059] Syncing modules for environment 'base'
2017-11-23 09:27:02,204 [salt.utils.extmods][INFO    ][23059] Loading cache from salt://_modules, for base)
2017-11-23 09:27:02,204 [salt.fileclient  ][INFO    ][23059] Caching directory '_modules/' for environment 'base'
2017-11-23 09:27:02,557 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-11-23 09:27:02,602 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-11-23 09:27:02,618 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-11-23 09:27:02,639 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-11-23 09:27:02,657 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-11-23 09:27:02,677 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-11-23 09:27:02,733 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-11-23 09:27:02,742 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-11-23 09:27:02,751 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-11-23 09:27:02,762 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-11-23 09:27:02,782 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-11-23 09:27:02,789 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-11-23 09:27:02,831 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-11-23 09:27:02,843 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-11-23 09:27:02,873 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-11-23 09:27:02,940 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-11-23 09:27:02,949 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-11-23 09:27:02,962 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-11-23 09:27:02,970 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-11-23 09:27:02,987 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-11-23 09:27:03,034 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-11-23 09:27:03,071 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-11-23 09:27:03,121 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-11-23 09:27:03,133 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-11-23 09:27:03,191 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-11-23 09:27:03,207 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-11-23 09:27:03,222 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-11-23 09:27:03,295 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-11-23 09:27:03,319 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-11-23 09:27:03,326 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-11-23 09:27:03,349 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-11-23 09:27:03,355 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-11-23 09:27:03,375 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-11-23 09:27:03,378 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-11-23 09:27:03,378 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-11-23 09:27:03,379 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-11-23 09:27:03,379 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-11-23 09:27:03,379 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-11-23 09:27:03,379 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-11-23 09:27:03,379 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-11-23 09:27:03,380 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-11-23 09:27:03,380 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-11-23 09:27:03,380 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-11-23 09:27:03,380 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-11-23 09:27:03,381 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-11-23 09:27:03,381 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-11-23 09:27:03,381 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-11-23 09:27:03,381 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-11-23 09:27:03,381 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-11-23 09:27:03,382 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-11-23 09:27:03,382 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-11-23 09:27:03,382 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-11-23 09:27:03,382 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-11-23 09:27:03,382 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-11-23 09:27:03,383 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-11-23 09:27:03,383 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-11-23 09:27:03,383 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-11-23 09:27:03,384 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-11-23 09:27:03,384 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-11-23 09:27:03,384 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-11-23 09:27:03,384 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-11-23 09:27:03,384 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-11-23 09:27:03,385 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-11-23 09:27:03,385 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-11-23 09:27:03,385 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-11-23 09:27:03,385 [salt.utils.extmods][INFO    ][23059] 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-23 09:27:03,386 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-11-23 09:27:03,386 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-11-23 09:27:03,391 [salt.utils.extmods][INFO    ][23059] Syncing states for environment 'base'
2017-11-23 09:27:03,391 [salt.utils.extmods][INFO    ][23059] Loading cache from salt://_states, for base)
2017-11-23 09:27:03,391 [salt.fileclient  ][INFO    ][23059] Caching directory '_states/' for environment 'base'
2017-11-23 09:27:03,421 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-11-23 09:27:03,450 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-11-23 09:27:03,460 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-11-23 09:27:03,502 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-11-23 09:27:03,520 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-11-23 09:27:03,535 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-11-23 09:27:03,545 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-11-23 09:27:03,555 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-11-23 09:27:03,581 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-11-23 09:27:03,594 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-11-23 09:27:03,625 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-11-23 09:27:03,633 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/jenkins_globalenvprop.py'
2017-11-23 09:27:03,648 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-11-23 09:27:03,659 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-11-23 09:27:03,696 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-11-23 09:27:03,708 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-11-23 09:27:03,716 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-11-23 09:27:03,759 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-11-23 09:27:03,813 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-11-23 09:27:03,823 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-11-23 09:27:03,877 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-11-23 09:27:03,904 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-11-23 09:27:03,938 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-11-23 09:27:03,954 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-11-23 09:27:03,980 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-11-23 09:27:04,006 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-11-23 09:27:04,030 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-11-23 09:27:04,049 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-11-23 09:27:04,357 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-11-23 09:27:04,368 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-11-23 09:27:04,374 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-11-23 09:27:04,375 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-11-23 09:27:04,375 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-11-23 09:27:04,375 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-11-23 09:27:04,376 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-11-23 09:27:04,376 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-11-23 09:27:04,376 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-11-23 09:27:04,377 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-11-23 09:27:04,377 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-11-23 09:27:04,377 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-11-23 09:27:04,378 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-11-23 09:27:04,378 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/jenkins_globalenvprop.py' to '/var/cache/salt/minion/extmods/states/jenkins_globalenvprop.py'
2017-11-23 09:27:04,378 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-11-23 09:27:04,378 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-11-23 09:27:04,379 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-11-23 09:27:04,379 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-11-23 09:27:04,379 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-11-23 09:27:04,380 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-11-23 09:27:04,380 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-11-23 09:27:04,380 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-11-23 09:27:04,380 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-11-23 09:27:04,380 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-11-23 09:27:04,381 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-11-23 09:27:04,381 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-11-23 09:27:04,381 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-11-23 09:27:04,381 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-11-23 09:27:04,381 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-11-23 09:27:04,382 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-11-23 09:27:04,382 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-11-23 09:27:04,382 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-11-23 09:27:04,382 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-11-23 09:27:04,384 [salt.utils.extmods][INFO    ][23059] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-11-23 09:27:04,387 [salt.utils.extmods][INFO    ][23059] Syncing sdb for environment 'base'
2017-11-23 09:27:04,387 [salt.utils.extmods][INFO    ][23059] Loading cache from salt://_sdb, for base)
2017-11-23 09:27:04,387 [salt.fileclient  ][INFO    ][23059] Caching directory '_sdb/' for environment 'base'
2017-11-23 09:27:04,442 [salt.utils.extmods][INFO    ][23059] Syncing grains for environment 'base'
2017-11-23 09:27:04,442 [salt.utils.extmods][INFO    ][23059] Loading cache from salt://_grains, for base)
2017-11-23 09:27:04,442 [salt.fileclient  ][INFO    ][23059] Caching directory '_grains/' for environment 'base'
2017-11-23 09:27:04,700 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-11-23 09:27:04,880 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-11-23 09:27:04,886 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-11-23 09:27:05,091 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-11-23 09:27:05,104 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-11-23 09:27:05,110 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-11-23 09:27:05,123 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-11-23 09:27:05,133 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-11-23 09:27:05,139 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-11-23 09:27:05,145 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-11-23 09:27:05,151 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-11-23 09:27:05,400 [salt.fileclient  ][INFO    ][23059] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-11-23 09:27:05,403 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-11-23 09:27:05,403 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-11-23 09:27:05,404 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-11-23 09:27:05,404 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-11-23 09:27:05,404 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-11-23 09:27:05,404 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-11-23 09:27:05,405 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-11-23 09:27:05,405 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-11-23 09:27:05,405 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-11-23 09:27:05,405 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-11-23 09:27:05,405 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-11-23 09:27:05,406 [salt.utils.extmods][INFO    ][23059] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-11-23 09:27:05,407 [salt.utils.extmods][INFO    ][23059] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-11-23 09:27:05,409 [salt.utils.extmods][INFO    ][23059] Syncing renderers for environment 'base'
2017-11-23 09:27:05,409 [salt.utils.extmods][INFO    ][23059] Loading cache from salt://_renderers, for base)
2017-11-23 09:27:05,409 [salt.fileclient  ][INFO    ][23059] Caching directory '_renderers/' for environment 'base'
2017-11-23 09:27:05,432 [salt.utils.extmods][INFO    ][23059] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-11-23 09:27:05,434 [salt.utils.extmods][INFO    ][23059] Syncing returners for environment 'base'
2017-11-23 09:27:05,434 [salt.utils.extmods][INFO    ][23059] Loading cache from salt://_returners, for base)
2017-11-23 09:27:05,434 [salt.fileclient  ][INFO    ][23059] Caching directory '_returners/' for environment 'base'
2017-11-23 09:27:05,651 [salt.utils.extmods][INFO    ][23059] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-11-23 09:27:05,654 [salt.utils.extmods][INFO    ][23059] Syncing output for environment 'base'
2017-11-23 09:27:05,654 [salt.utils.extmods][INFO    ][23059] Loading cache from salt://_output, for base)
2017-11-23 09:27:05,654 [salt.fileclient  ][INFO    ][23059] Caching directory '_output/' for environment 'base'
2017-11-23 09:27:05,673 [salt.utils.extmods][INFO    ][23059] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-11-23 09:27:05,676 [salt.utils.extmods][INFO    ][23059] Syncing utils for environment 'base'
2017-11-23 09:27:05,676 [salt.utils.extmods][INFO    ][23059] Loading cache from salt://_utils, for base)
2017-11-23 09:27:05,676 [salt.fileclient  ][INFO    ][23059] Caching directory '_utils/' for environment 'base'
2017-11-23 09:27:05,697 [salt.utils.extmods][INFO    ][23059] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-11-23 09:27:05,700 [salt.utils.extmods][INFO    ][23059] Syncing log_handlers for environment 'base'
2017-11-23 09:27:05,700 [salt.utils.extmods][INFO    ][23059] Loading cache from salt://_log_handlers, for base)
2017-11-23 09:27:05,700 [salt.fileclient  ][INFO    ][23059] Caching directory '_log_handlers/' for environment 'base'
2017-11-23 09:27:05,718 [salt.utils.extmods][INFO    ][23059] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-11-23 09:27:05,720 [salt.utils.extmods][INFO    ][23059] Syncing proxy for environment 'base'
2017-11-23 09:27:05,721 [salt.utils.extmods][INFO    ][23059] Loading cache from salt://_proxy, for base)
2017-11-23 09:27:05,721 [salt.fileclient  ][INFO    ][23059] Caching directory '_proxy/' for environment 'base'
2017-11-23 09:27:05,751 [salt.utils.extmods][INFO    ][23059] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-11-23 09:27:05,754 [salt.utils.extmods][INFO    ][23059] Syncing engines for environment 'base'
2017-11-23 09:27:05,754 [salt.utils.extmods][INFO    ][23059] Loading cache from salt://_engines, for base)
2017-11-23 09:27:05,754 [salt.fileclient  ][INFO    ][23059] Caching directory '_engines/' for environment 'base'
2017-11-23 09:27:05,779 [salt.minion      ][INFO    ][23059] Returning information for job: 20171123092658634981
2017-11-23 09:32:23,865 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command test.ping with jid 20171123093223858021
2017-11-23 09:32:23,880 [salt.minion      ][INFO    ][23212] Starting a new job with PID 23212
2017-11-23 09:32:23,907 [salt.minion      ][INFO    ][23212] Returning information for job: 20171123093223858021
2017-11-23 09:35:46,993 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command state.sls with jid 20171123093546981762
2017-11-23 09:35:47,009 [salt.minion      ][INFO    ][23260] Starting a new job with PID 23260
2017-11-23 09:35:47,436 [salt.state       ][INFO    ][23260] Loading fresh modules for state activity
2017-11-23 09:35:47,508 [salt.fileclient  ][INFO    ][23260] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-23 09:35:47,524 [salt.fileclient  ][INFO    ][23260] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-23 09:35:47,547 [salt.fileclient  ][INFO    ][23260] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-23 09:35:47,831 [salt.state       ][INFO    ][23260] Running state [lsof] at time 09:35:47.831108
2017-11-23 09:35:47,831 [salt.state       ][INFO    ][23260] Executing state pkg.installed for lsof
2017-11-23 09:35:47,832 [salt.loaded.int.module.cmdmod][INFO    ][23260] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-23 09:35:48,092 [salt.state       ][INFO    ][23260] Package lsof is already installed
2017-11-23 09:35:48,093 [salt.state       ][INFO    ][23260] Completed state [lsof] at time 09:35:48.092692 duration_in_ms=261.585
2017-11-23 09:35:48,093 [salt.state       ][INFO    ][23260] Running state [keepalived] at time 09:35:48.092885
2017-11-23 09:35:48,093 [salt.state       ][INFO    ][23260] Executing state pkg.installed for keepalived
2017-11-23 09:35:48,107 [salt.loaded.int.module.cmdmod][INFO    ][23260] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-23 09:35:50,135 [salt.loaded.int.module.cmdmod][INFO    ][23260] 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-23 09:35:57,037 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command saltutil.find_job with jid 20171123093557025650
2017-11-23 09:35:57,052 [salt.minion      ][INFO    ][24695] Starting a new job with PID 24695
2017-11-23 09:35:57,061 [salt.minion      ][INFO    ][24695] Returning information for job: 20171123093557025650
2017-11-23 09:35:57,981 [salt.loaded.int.module.cmdmod][INFO    ][23260] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-23 09:35:58,006 [salt.state       ][INFO    ][23260] 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-23 09:35:58,015 [salt.state       ][INFO    ][23260] Loading fresh modules for state activity
2017-11-23 09:35:58,028 [salt.state       ][INFO    ][23260] Completed state [keepalived] at time 09:35:58.027473 duration_in_ms=9934.588
2017-11-23 09:35:58,029 [salt.state       ][INFO    ][23260] Running state [/etc/keepalived/keepalived.conf] at time 09:35:58.029385
2017-11-23 09:35:58,030 [salt.state       ][INFO    ][23260] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-23 09:35:58,056 [salt.fileclient  ][INFO    ][23260] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-23 09:35:58,088 [salt.fileclient  ][INFO    ][23260] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-23 09:35:58,095 [salt.state       ][INFO    ][23260] File changed:
New file
2017-11-23 09:35:58,096 [salt.state       ][INFO    ][23260] Completed state [/etc/keepalived/keepalived.conf] at time 09:35:58.095533 duration_in_ms=66.148
2017-11-23 09:35:58,158 [salt.state       ][INFO    ][23260] Running state [keepalived] at time 09:35:58.157755
2017-11-23 09:35:58,158 [salt.state       ][INFO    ][23260] Executing state service.running for keepalived
2017-11-23 09:35:58,159 [salt.loaded.int.module.cmdmod][INFO    ][23260] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-23 09:35:58,168 [salt.loaded.int.module.cmdmod][INFO    ][23260] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-23 09:35:58,175 [salt.loaded.int.module.cmdmod][INFO    ][23260] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-23 09:35:58,182 [salt.loaded.int.module.cmdmod][INFO    ][23260] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-23 09:35:58,188 [salt.loaded.int.module.cmdmod][INFO    ][23260] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-11-23 09:35:58,250 [salt.loaded.int.module.cmdmod][INFO    ][23260] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-23 09:35:58,257 [salt.loaded.int.module.cmdmod][INFO    ][23260] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-23 09:35:58,264 [salt.loaded.int.module.cmdmod][INFO    ][23260] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-23 09:35:58,271 [salt.state       ][INFO    ][23260] {'keepalived': True}
2017-11-23 09:35:58,271 [salt.state       ][INFO    ][23260] Completed state [keepalived] at time 09:35:58.270922 duration_in_ms=113.168
2017-11-23 09:35:58,272 [salt.minion      ][INFO    ][23260] Returning information for job: 20171123093546981762
2017-11-23 09:36:25,421 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command pillar.get with jid 20171123093625411583
2017-11-23 09:36:25,440 [salt.minion      ][INFO    ][24818] Starting a new job with PID 24818
2017-11-23 09:36:25,448 [salt.minion      ][INFO    ][24818] Returning information for job: 20171123093625411583
2017-11-23 09:42:28,411 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command state.sls with jid 20171123094228398648
2017-11-23 09:42:28,427 [salt.minion      ][INFO    ][24919] Starting a new job with PID 24919
2017-11-23 09:42:30,993 [salt.state       ][INFO    ][24919] Loading fresh modules for state activity
2017-11-23 09:42:31,017 [salt.fileclient  ][INFO    ][24919] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-23 09:42:31,037 [salt.fileclient  ][INFO    ][24919] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-23 09:42:31,315 [salt.state       ][INFO    ][24919] Running state [attr] at time 09:42:31.314517
2017-11-23 09:42:31,315 [salt.state       ][INFO    ][24919] Executing state pkg.installed for attr
2017-11-23 09:42:31,315 [salt.loaded.int.module.cmdmod][INFO    ][24919] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-23 09:42:31,586 [salt.loaded.int.module.cmdmod][INFO    ][24919] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-23 09:42:33,848 [salt.loaded.int.module.cmdmod][INFO    ][24919] 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-23 09:42:36,861 [salt.loaded.int.module.cmdmod][INFO    ][24919] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-23 09:42:36,883 [salt.state       ][INFO    ][24919] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-11-23 09:42:36,891 [salt.state       ][INFO    ][24919] Loading fresh modules for state activity
2017-11-23 09:42:36,903 [salt.state       ][INFO    ][24919] Completed state [attr] at time 09:42:36.902932 duration_in_ms=5588.415
2017-11-23 09:42:36,908 [salt.state       ][INFO    ][24919] Running state [glusterfs-server] at time 09:42:36.907621
2017-11-23 09:42:36,908 [salt.state       ][INFO    ][24919] Executing state pkg.installed for glusterfs-server
2017-11-23 09:42:37,083 [salt.loaded.int.module.cmdmod][INFO    ][24919] 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-23 09:42:38,503 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command saltutil.find_job with jid 20171123094238490678
2017-11-23 09:42:38,518 [salt.minion      ][INFO    ][25881] Starting a new job with PID 25881
2017-11-23 09:42:38,528 [salt.minion      ][INFO    ][25881] Returning information for job: 20171123094238490678
2017-11-23 09:42:48,698 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command saltutil.find_job with jid 20171123094248684841
2017-11-23 09:42:48,712 [salt.minion      ][INFO    ][28628] Starting a new job with PID 28628
2017-11-23 09:42:48,733 [salt.minion      ][INFO    ][28628] Returning information for job: 20171123094248684841
2017-11-23 09:42:54,400 [salt.loaded.int.module.cmdmod][INFO    ][24919] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-23 09:42:54,424 [salt.state       ][INFO    ][24919] 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-23 09:42:54,431 [salt.state       ][INFO    ][24919] Loading fresh modules for state activity
2017-11-23 09:42:54,444 [salt.state       ][INFO    ][24919] Completed state [glusterfs-server] at time 09:42:54.443923 duration_in_ms=17536.301
2017-11-23 09:42:54,450 [salt.state       ][INFO    ][24919] Running state [bc] at time 09:42:54.450248
2017-11-23 09:42:54,450 [salt.state       ][INFO    ][24919] Executing state pkg.installed for bc
2017-11-23 09:42:54,713 [salt.loaded.int.module.cmdmod][INFO    ][24919] 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-23 09:42:57,516 [salt.loaded.int.module.cmdmod][INFO    ][24919] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-23 09:42:57,539 [salt.state       ][INFO    ][24919] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-11-23 09:42:57,546 [salt.state       ][INFO    ][24919] Loading fresh modules for state activity
2017-11-23 09:42:57,560 [salt.state       ][INFO    ][24919] Completed state [bc] at time 09:42:57.559949 duration_in_ms=3109.701
2017-11-23 09:42:57,618 [salt.state       ][INFO    ][24919] Running state [glusterfs-server] at time 09:42:57.618071
2017-11-23 09:42:57,618 [salt.state       ][INFO    ][24919] Executing state service.running for glusterfs-server
2017-11-23 09:42:57,620 [salt.loaded.int.module.cmdmod][INFO    ][24919] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-23 09:42:57,629 [salt.loaded.int.module.cmdmod][INFO    ][24919] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-23 09:42:57,636 [salt.loaded.int.module.cmdmod][INFO    ][24919] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-23 09:42:57,645 [salt.state       ][INFO    ][24919] The service glusterfs-server is already running
2017-11-23 09:42:57,645 [salt.state       ][INFO    ][24919] Completed state [glusterfs-server] at time 09:42:57.645286 duration_in_ms=27.215
2017-11-23 09:42:57,647 [salt.state       ][INFO    ][24919] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:42:57.646707
2017-11-23 09:42:57,647 [salt.state       ][INFO    ][24919] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-23 09:42:57,662 [salt.state       ][INFO    ][24919] File changed:
New file
2017-11-23 09:42:57,662 [salt.state       ][INFO    ][24919] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:42:57.662073 duration_in_ms=15.366
2017-11-23 09:42:57,662 [salt.state       ][INFO    ][24919] Running state [/srv/glusterfs/glance] at time 09:42:57.662243
2017-11-23 09:42:57,662 [salt.state       ][INFO    ][24919] Executing state file.directory for /srv/glusterfs/glance
2017-11-23 09:42:57,663 [salt.state       ][INFO    ][24919] {'/srv/glusterfs/glance': 'New Dir'}
2017-11-23 09:42:57,663 [salt.state       ][INFO    ][24919] Completed state [/srv/glusterfs/glance] at time 09:42:57.663149 duration_in_ms=0.905
2017-11-23 09:42:57,663 [salt.state       ][INFO    ][24919] Running state [/srv/glusterfs/keystone-keys] at time 09:42:57.663288
2017-11-23 09:42:57,663 [salt.state       ][INFO    ][24919] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-23 09:42:57,664 [salt.state       ][INFO    ][24919] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-11-23 09:42:57,664 [salt.state       ][INFO    ][24919] Completed state [/srv/glusterfs/keystone-keys] at time 09:42:57.663918 duration_in_ms=0.629
2017-11-23 09:42:57,664 [salt.state       ][INFO    ][24919] Running state [/srv/glusterfs/nova_instances] at time 09:42:57.664058
2017-11-23 09:42:57,664 [salt.state       ][INFO    ][24919] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-23 09:42:57,665 [salt.state       ][INFO    ][24919] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-11-23 09:42:57,665 [salt.state       ][INFO    ][24919] Completed state [/srv/glusterfs/nova_instances] at time 09:42:57.664701 duration_in_ms=0.644
2017-11-23 09:42:57,665 [salt.state       ][INFO    ][24919] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:42:57.664843
2017-11-23 09:42:57,665 [salt.state       ][INFO    ][24919] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-23 09:42:57,665 [salt.state       ][INFO    ][24919] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-11-23 09:42:57,666 [salt.state       ][INFO    ][24919] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:42:57.665477 duration_in_ms=0.634
2017-11-23 09:42:57,666 [salt.minion      ][INFO    ][24919] Returning information for job: 20171123094228398648
2017-11-23 09:42:58,791 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command test.ping with jid 20171123094258779030
2017-11-23 09:42:58,805 [salt.minion      ][INFO    ][29634] Starting a new job with PID 29634
2017-11-23 09:42:58,833 [salt.minion      ][INFO    ][29634] Returning information for job: 20171123094258779030
2017-11-23 09:43:39,582 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command state.sls with jid 20171123094339570267
2017-11-23 09:43:39,599 [salt.minion      ][INFO    ][29846] Starting a new job with PID 29846
2017-11-23 09:43:41,204 [salt.state       ][INFO    ][29846] Loading fresh modules for state activity
2017-11-23 09:43:41,227 [salt.fileclient  ][INFO    ][29846] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-23 09:43:41,263 [salt.fileclient  ][INFO    ][29846] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-23 09:43:41,326 [salt.fileclient  ][INFO    ][29846] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-23 09:43:41,346 [salt.fileclient  ][INFO    ][29846] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-23 09:43:41,624 [salt.state       ][INFO    ][29846] Running state [attr] at time 09:43:41.624443
2017-11-23 09:43:41,625 [salt.state       ][INFO    ][29846] Executing state pkg.installed for attr
2017-11-23 09:43:41,625 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-23 09:43:41,892 [salt.state       ][INFO    ][29846] Package attr is already installed
2017-11-23 09:43:41,893 [salt.state       ][INFO    ][29846] Completed state [attr] at time 09:43:41.892473 duration_in_ms=268.029
2017-11-23 09:43:41,893 [salt.state       ][INFO    ][29846] Running state [glusterfs-server] at time 09:43:41.892696
2017-11-23 09:43:41,893 [salt.state       ][INFO    ][29846] Executing state pkg.installed for glusterfs-server
2017-11-23 09:43:41,895 [salt.state       ][INFO    ][29846] Package glusterfs-server is already installed
2017-11-23 09:43:41,896 [salt.state       ][INFO    ][29846] Completed state [glusterfs-server] at time 09:43:41.895571 duration_in_ms=2.874
2017-11-23 09:43:41,896 [salt.state       ][INFO    ][29846] Running state [bc] at time 09:43:41.895712
2017-11-23 09:43:41,896 [salt.state       ][INFO    ][29846] Executing state pkg.installed for bc
2017-11-23 09:43:41,898 [salt.state       ][INFO    ][29846] Package bc is already installed
2017-11-23 09:43:41,899 [salt.state       ][INFO    ][29846] Completed state [bc] at time 09:43:41.898474 duration_in_ms=2.762
2017-11-23 09:43:41,899 [salt.state       ][INFO    ][29846] Running state [glusterfs-server] at time 09:43:41.899273
2017-11-23 09:43:41,899 [salt.state       ][INFO    ][29846] Executing state service.running for glusterfs-server
2017-11-23 09:43:41,900 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-23 09:43:41,909 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-11-23 09:43:41,952 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-23 09:43:41,966 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-23 09:43:41,972 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-23 09:43:41,980 [salt.state       ][INFO    ][29846] The service glusterfs-server is already running
2017-11-23 09:43:41,981 [salt.state       ][INFO    ][29846] Completed state [glusterfs-server] at time 09:43:41.980526 duration_in_ms=81.252
2017-11-23 09:43:41,982 [salt.state       ][INFO    ][29846] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:43:41.982081
2017-11-23 09:43:41,982 [salt.state       ][INFO    ][29846] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-23 09:43:41,990 [salt.state       ][INFO    ][29846] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-23 09:43:41,990 [salt.state       ][INFO    ][29846] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:43:41.989812 duration_in_ms=7.731
2017-11-23 09:43:41,990 [salt.state       ][INFO    ][29846] Running state [/srv/glusterfs/glance] at time 09:43:41.990015
2017-11-23 09:43:41,990 [salt.state       ][INFO    ][29846] Executing state file.directory for /srv/glusterfs/glance
2017-11-23 09:43:41,991 [salt.state       ][INFO    ][29846] Directory /srv/glusterfs/glance is in the correct state
2017-11-23 09:43:41,991 [salt.state       ][INFO    ][29846] Completed state [/srv/glusterfs/glance] at time 09:43:41.990776 duration_in_ms=0.76
2017-11-23 09:43:41,991 [salt.state       ][INFO    ][29846] Running state [/srv/glusterfs/keystone-keys] at time 09:43:41.990968
2017-11-23 09:43:41,991 [salt.state       ][INFO    ][29846] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-23 09:43:41,992 [salt.state       ][INFO    ][29846] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-23 09:43:41,992 [salt.state       ][INFO    ][29846] Completed state [/srv/glusterfs/keystone-keys] at time 09:43:41.991672 duration_in_ms=0.704
2017-11-23 09:43:41,992 [salt.state       ][INFO    ][29846] Running state [/srv/glusterfs/nova_instances] at time 09:43:41.991840
2017-11-23 09:43:41,992 [salt.state       ][INFO    ][29846] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-23 09:43:41,992 [salt.state       ][INFO    ][29846] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-23 09:43:41,993 [salt.state       ][INFO    ][29846] Completed state [/srv/glusterfs/nova_instances] at time 09:43:41.992527 duration_in_ms=0.687
2017-11-23 09:43:41,993 [salt.state       ][INFO    ][29846] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:43:41.992698
2017-11-23 09:43:41,993 [salt.state       ][INFO    ][29846] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-23 09:43:41,993 [salt.state       ][INFO    ][29846] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-23 09:43:41,993 [salt.state       ][INFO    ][29846] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:43:41.993384 duration_in_ms=0.685
2017-11-23 09:43:42,048 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command ['git', '--version'] in directory '/root'
2017-11-23 09:43:42,129 [salt.state       ][INFO    ][29846] Running state [sleep 5] at time 09:43:42.129434
2017-11-23 09:43:42,130 [salt.state       ][INFO    ][29846] Executing state cmd.wait for sleep 5
2017-11-23 09:43:42,130 [salt.state       ][INFO    ][29846] No changes made for sleep 5
2017-11-23 09:43:42,130 [salt.state       ][INFO    ][29846] Completed state [sleep 5] at time 09:43:42.129997 duration_in_ms=0.565
2017-11-23 09:43:42,130 [salt.state       ][INFO    ][29846] Running state [10.167.4.141] at time 09:43:42.130387
2017-11-23 09:43:42,131 [salt.state       ][INFO    ][29846] Executing state glusterfs.peered for 10.167.4.141
2017-11-23 09:43:42,131 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:42,139 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:42,188 [salt.state       ][INFO    ][29846] Host 10.167.4.141 already peered
2017-11-23 09:43:42,189 [salt.state       ][INFO    ][29846] Completed state [10.167.4.141] at time 09:43:42.188548 duration_in_ms=58.161
2017-11-23 09:43:42,191 [salt.state       ][INFO    ][29846] Running state [10.167.4.142] at time 09:43:42.190621
2017-11-23 09:43:42,191 [salt.state       ][INFO    ][29846] Executing state glusterfs.peered for 10.167.4.142
2017-11-23 09:43:42,191 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:42,200 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:42,247 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:42,255 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:42,307 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:42,315 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:42,376 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:42,384 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:42,433 [salt.state       ][INFO    ][29846] {'new': {'10.167.4.141': [], 'kvm02.baremetal-mcp-ocata-ovs-ha.local': ['10.167.4.142']}, 'old': {'10.167.4.141': [], 'kvm02.baremetal-mcp-ocata-ovs-ha.local': []}}
2017-11-23 09:43:42,433 [salt.state       ][INFO    ][29846] Completed state [10.167.4.142] at time 09:43:42.433023 duration_in_ms=242.402
2017-11-23 09:43:42,435 [salt.state       ][INFO    ][29846] Running state [10.167.4.143] at time 09:43:42.435186
2017-11-23 09:43:42,435 [salt.state       ][INFO    ][29846] Executing state glusterfs.peered for 10.167.4.143
2017-11-23 09:43:42,436 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:42,445 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:42,493 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:42,500 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:42,549 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:42,556 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:42,605 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:42,612 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:42,660 [salt.state       ][INFO    ][29846] Peering with localhost is not needed
2017-11-23 09:43:42,660 [salt.state       ][INFO    ][29846] Completed state [10.167.4.143] at time 09:43:42.659886 duration_in_ms=224.7
2017-11-23 09:43:42,661 [salt.state       ][INFO    ][29846] Running state [glance] at time 09:43:42.660643
2017-11-23 09:43:42,661 [salt.state       ][INFO    ][29846] Executing state glusterfs.created for glance
2017-11-23 09:43:42,661 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:42,670 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:42,720 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:42,728 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:42,780 [salt.state       ][INFO    ][29846] Volume glance already exists and is started.
2017-11-23 09:43:42,781 [salt.state       ][INFO    ][29846] Completed state [glance] at time 09:43:42.780680 duration_in_ms=120.035
2017-11-23 09:43:42,783 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:43:42.783330
2017-11-23 09:43:42,784 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-23 09:43:42,784 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-23 09:43:42,832 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:42,872 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:43:42.872245 duration_in_ms=88.912
2017-11-23 09:43:42,873 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:43:42.872815
2017-11-23 09:43:42,873 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-23 09:43:42,874 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-23 09:43:42,927 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:42,927 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:43:42.926931 duration_in_ms=54.116
2017-11-23 09:43:42,927 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:43:42.927383
2017-11-23 09:43:42,928 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-23 09:43:42,928 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-23 09:43:42,977 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:42,978 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:43:42.977713 duration_in_ms=50.33
2017-11-23 09:43:42,978 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:43:42.978192
2017-11-23 09:43:42,978 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-23 09:43:42,979 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-23 09:43:43,030 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:43,031 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:43:43.030628 duration_in_ms=52.435
2017-11-23 09:43:43,031 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:43:43.031106
2017-11-23 09:43:43,031 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-23 09:43:43,032 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-23 09:43:43,082 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:43,082 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:43:43.081855 duration_in_ms=50.749
2017-11-23 09:43:43,083 [salt.state       ][INFO    ][29846] Running state [glance] at time 09:43:43.082827
2017-11-23 09:43:43,083 [salt.state       ][INFO    ][29846] Executing state glusterfs.started for glance
2017-11-23 09:43:43,084 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:43,092 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:43,143 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:43,152 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:43,205 [salt.state       ][INFO    ][29846] Volume glance is already started
2017-11-23 09:43:43,206 [salt.state       ][INFO    ][29846] Completed state [glance] at time 09:43:43.205704 duration_in_ms=122.876
2017-11-23 09:43:43,207 [salt.state       ][INFO    ][29846] Running state [keystone-keys] at time 09:43:43.206551
2017-11-23 09:43:43,207 [salt.state       ][INFO    ][29846] Executing state glusterfs.created for keystone-keys
2017-11-23 09:43:43,207 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:43,215 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:43,264 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:43,273 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:43,327 [salt.state       ][INFO    ][29846] Volume keystone-keys already exists and is started.
2017-11-23 09:43:43,327 [salt.state       ][INFO    ][29846] Completed state [keystone-keys] at time 09:43:43.327375 duration_in_ms=120.824
2017-11-23 09:43:43,330 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:43:43.330131
2017-11-23 09:43:43,330 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-23 09:43:43,331 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-23 09:43:43,379 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:43,380 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:43:43.379625 duration_in_ms=49.493
2017-11-23 09:43:43,380 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:43:43.380081
2017-11-23 09:43:43,380 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-23 09:43:43,381 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-23 09:43:43,428 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:43,428 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:43:43.428032 duration_in_ms=47.951
2017-11-23 09:43:43,429 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:43:43.428487
2017-11-23 09:43:43,429 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-23 09:43:43,429 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-23 09:43:43,479 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:43,479 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:43:43.479259 duration_in_ms=50.771
2017-11-23 09:43:43,480 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:43:43.479715
2017-11-23 09:43:43,480 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-23 09:43:43,480 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-23 09:43:43,532 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:43,532 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:43:43.532029 duration_in_ms=52.313
2017-11-23 09:43:43,533 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:43:43.532537
2017-11-23 09:43:43,533 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-23 09:43:43,533 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-23 09:43:43,582 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:43,583 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:43:43.582462 duration_in_ms=49.925
2017-11-23 09:43:43,583 [salt.state       ][INFO    ][29846] Running state [keystone-keys] at time 09:43:43.583418
2017-11-23 09:43:43,584 [salt.state       ][INFO    ][29846] Executing state glusterfs.started for keystone-keys
2017-11-23 09:43:43,584 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:43,592 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:43,641 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:43,650 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:43,702 [salt.state       ][INFO    ][29846] Volume keystone-keys is already started
2017-11-23 09:43:43,702 [salt.state       ][INFO    ][29846] Completed state [keystone-keys] at time 09:43:43.702191 duration_in_ms=118.773
2017-11-23 09:43:43,703 [salt.state       ][INFO    ][29846] Running state [nova_instances] at time 09:43:43.703260
2017-11-23 09:43:43,704 [salt.state       ][INFO    ][29846] Executing state glusterfs.created for nova_instances
2017-11-23 09:43:43,704 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:43,712 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:43,760 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:43,768 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:43,822 [salt.state       ][INFO    ][29846] Volume nova_instances already exists and is started.
2017-11-23 09:43:43,822 [salt.state       ][INFO    ][29846] Completed state [nova_instances] at time 09:43:43.822142 duration_in_ms=118.881
2017-11-23 09:43:43,828 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:43:43.828285
2017-11-23 09:43:43,829 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-23 09:43:43,830 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-23 09:43:43,879 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:43,880 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:43:43.879446 duration_in_ms=51.163
2017-11-23 09:43:43,880 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 09:43:43.880031
2017-11-23 09:43:43,880 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-11-23 09:43:43,881 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-11-23 09:43:43,932 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:43,932 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 09:43:43.931985 duration_in_ms=51.953
2017-11-23 09:43:43,932 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 09:43:43.932438
2017-11-23 09:43:43,933 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-11-23 09:43:43,933 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-11-23 09:43:43,981 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:43,981 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 09:43:43.981365 duration_in_ms=48.926
2017-11-23 09:43:43,982 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 09:43:43.981869
2017-11-23 09:43:43,982 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-11-23 09:43:43,983 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-11-23 09:43:44,034 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:44,035 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 09:43:44.034560 duration_in_ms=52.691
2017-11-23 09:43:44,035 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:43:44.035046
2017-11-23 09:43:44,035 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-23 09:43:44,036 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-23 09:43:44,083 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:44,083 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:43:44.082803 duration_in_ms=47.756
2017-11-23 09:43:44,084 [salt.state       ][INFO    ][29846] Running state [nova_instances] at time 09:43:44.083783
2017-11-23 09:43:44,084 [salt.state       ][INFO    ][29846] Executing state glusterfs.started for nova_instances
2017-11-23 09:43:44,085 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:44,092 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:44,140 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:44,149 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:44,202 [salt.state       ][INFO    ][29846] Volume nova_instances is already started
2017-11-23 09:43:44,202 [salt.state       ][INFO    ][29846] Completed state [nova_instances] at time 09:43:44.202124 duration_in_ms=118.34
2017-11-23 09:43:44,203 [salt.state       ][INFO    ][29846] Running state [keystone-credential-keys] at time 09:43:44.203209
2017-11-23 09:43:44,204 [salt.state       ][INFO    ][29846] Executing state glusterfs.created for keystone-credential-keys
2017-11-23 09:43:44,204 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:44,213 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:44,265 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:44,274 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:44,328 [salt.state       ][INFO    ][29846] Volume keystone-credential-keys already exists and is started.
2017-11-23 09:43:44,328 [salt.state       ][INFO    ][29846] Completed state [keystone-credential-keys] at time 09:43:44.328051 duration_in_ms=124.841
2017-11-23 09:43:44,331 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:43:44.330985
2017-11-23 09:43:44,331 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-23 09:43:44,332 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-23 09:43:44,380 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:44,381 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:43:44.380506 duration_in_ms=49.521
2017-11-23 09:43:44,381 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:43:44.381001
2017-11-23 09:43:44,381 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-23 09:43:44,382 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-23 09:43:44,433 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:44,433 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:43:44.432933 duration_in_ms=51.93
2017-11-23 09:43:44,433 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:43:44.433359
2017-11-23 09:43:44,434 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-23 09:43:44,434 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-23 09:43:44,484 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:44,484 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:43:44.484198 duration_in_ms=50.839
2017-11-23 09:43:44,485 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:43:44.484719
2017-11-23 09:43:44,485 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-23 09:43:44,486 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-23 09:43:44,532 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:44,532 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:43:44.532333 duration_in_ms=47.614
2017-11-23 09:43:44,533 [salt.state       ][INFO    ][29846] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:43:44.533236
2017-11-23 09:43:44,534 [salt.state       ][INFO    ][29846] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-23 09:43:44,534 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-23 09:43:44,583 [salt.state       ][INFO    ][29846] unless execution succeeded
2017-11-23 09:43:44,583 [salt.state       ][INFO    ][29846] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:43:44.583436 duration_in_ms=50.201
2017-11-23 09:43:44,584 [salt.state       ][INFO    ][29846] Running state [keystone-credential-keys] at time 09:43:44.584392
2017-11-23 09:43:44,585 [salt.state       ][INFO    ][29846] Executing state glusterfs.started for keystone-credential-keys
2017-11-23 09:43:44,585 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:44,593 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:44,643 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --version' in directory '/root'
2017-11-23 09:43:44,651 [salt.loaded.int.module.cmdmod][INFO    ][29846] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 09:43:44,701 [salt.state       ][INFO    ][29846] Volume keystone-credential-keys is already started
2017-11-23 09:43:44,701 [salt.state       ][INFO    ][29846] Completed state [keystone-credential-keys] at time 09:43:44.701114 duration_in_ms=116.722
2017-11-23 09:43:44,703 [salt.minion      ][INFO    ][29846] Returning information for job: 20171123094339570267
2017-11-23 09:43:49,675 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command test.ping with jid 20171123094349662003
2017-11-23 09:43:49,690 [salt.minion      ][INFO    ][30272] Starting a new job with PID 30272
2017-11-23 09:43:49,716 [salt.minion      ][INFO    ][30272] Returning information for job: 20171123094349662003
2017-11-23 09:43:50,104 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command cmd.run with jid 20171123094350091189
2017-11-23 09:43:50,118 [salt.minion      ][INFO    ][30277] Starting a new job with PID 30277
2017-11-23 09:43:50,122 [salt.loaded.int.module.cmdmod][INFO    ][30277] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-23 09:43:50,231 [salt.minion      ][INFO    ][30277] Returning information for job: 20171123094350091189
2017-11-23 09:58:37,117 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command test.ping with jid 20171123095837106580
2017-11-23 09:58:37,135 [salt.minion      ][INFO    ][30534] Starting a new job with PID 30534
2017-11-23 09:58:37,193 [salt.minion      ][INFO    ][30534] Returning information for job: 20171123095837106580
2017-11-23 09:59:02,003 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command state.sls with jid 20171123095901992430
2017-11-23 09:59:02,019 [salt.minion      ][INFO    ][30547] Starting a new job with PID 30547
2017-11-23 09:59:02,469 [salt.state       ][INFO    ][30547] Loading fresh modules for state activity
2017-11-23 09:59:02,495 [salt.fileclient  ][INFO    ][30547] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-23 09:59:02,513 [salt.fileclient  ][INFO    ][30547] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-23 09:59:02,537 [salt.fileclient  ][INFO    ][30547] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-23 09:59:02,800 [salt.state       ][INFO    ][30547] Running state [lsof] at time 09:59:02.800304
2017-11-23 09:59:02,801 [salt.state       ][INFO    ][30547] Executing state pkg.installed for lsof
2017-11-23 09:59:02,801 [salt.loaded.int.module.cmdmod][INFO    ][30547] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-23 09:59:03,058 [salt.state       ][INFO    ][30547] Package lsof is already installed
2017-11-23 09:59:03,058 [salt.state       ][INFO    ][30547] Completed state [lsof] at time 09:59:03.058005 duration_in_ms=257.702
2017-11-23 09:59:03,058 [salt.state       ][INFO    ][30547] Running state [keepalived] at time 09:59:03.058215
2017-11-23 09:59:03,058 [salt.state       ][INFO    ][30547] Executing state pkg.installed for keepalived
2017-11-23 09:59:03,061 [salt.state       ][INFO    ][30547] Package keepalived is already installed
2017-11-23 09:59:03,061 [salt.state       ][INFO    ][30547] Completed state [keepalived] at time 09:59:03.061070 duration_in_ms=2.854
2017-11-23 09:59:03,062 [salt.state       ][INFO    ][30547] Running state [/etc/keepalived/keepalived.conf] at time 09:59:03.062453
2017-11-23 09:59:03,063 [salt.state       ][INFO    ][30547] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-23 09:59:03,086 [salt.fileclient  ][INFO    ][30547] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-23 09:59:03,115 [salt.fileclient  ][INFO    ][30547] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-23 09:59:03,120 [salt.state       ][INFO    ][30547] File /etc/keepalived/keepalived.conf is in the correct state
2017-11-23 09:59:03,120 [salt.state       ][INFO    ][30547] Completed state [/etc/keepalived/keepalived.conf] at time 09:59:03.119659 duration_in_ms=57.206
2017-11-23 09:59:03,120 [salt.state       ][INFO    ][30547] Running state [keepalived] at time 09:59:03.120134
2017-11-23 09:59:03,120 [salt.state       ][INFO    ][30547] Executing state service.running for keepalived
2017-11-23 09:59:03,121 [salt.loaded.int.module.cmdmod][INFO    ][30547] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-23 09:59:03,137 [salt.loaded.int.module.cmdmod][INFO    ][30547] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-23 09:59:03,144 [salt.loaded.int.module.cmdmod][INFO    ][30547] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-23 09:59:03,151 [salt.state       ][INFO    ][30547] The service keepalived is already running
2017-11-23 09:59:03,152 [salt.state       ][INFO    ][30547] Completed state [keepalived] at time 09:59:03.151604 duration_in_ms=31.469
2017-11-23 09:59:03,153 [salt.minion      ][INFO    ][30547] Returning information for job: 20171123095901992430
2017-11-23 09:59:17,639 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command test.ping with jid 20171123095917632223
2017-11-23 09:59:17,664 [salt.minion      ][INFO    ][30576] Starting a new job with PID 30576
2017-11-23 09:59:17,703 [salt.minion      ][INFO    ][30576] Returning information for job: 20171123095917632223
2017-11-23 09:59:50,852 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command state.sls with jid 20171123095950842520
2017-11-23 09:59:50,867 [salt.minion      ][INFO    ][30599] Starting a new job with PID 30599
2017-11-23 09:59:51,292 [salt.state       ][INFO    ][30599] Loading fresh modules for state activity
2017-11-23 09:59:51,314 [salt.fileclient  ][INFO    ][30599] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-23 09:59:51,331 [salt.fileclient  ][INFO    ][30599] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-23 09:59:51,352 [salt.fileclient  ][INFO    ][30599] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-23 09:59:51,613 [salt.state       ][INFO    ][30599] Running state [lsof] at time 09:59:51.612605
2017-11-23 09:59:51,613 [salt.state       ][INFO    ][30599] Executing state pkg.installed for lsof
2017-11-23 09:59:51,613 [salt.loaded.int.module.cmdmod][INFO    ][30599] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-23 09:59:51,871 [salt.state       ][INFO    ][30599] Package lsof is already installed
2017-11-23 09:59:51,871 [salt.state       ][INFO    ][30599] Completed state [lsof] at time 09:59:51.871418 duration_in_ms=258.814
2017-11-23 09:59:51,872 [salt.state       ][INFO    ][30599] Running state [keepalived] at time 09:59:51.871617
2017-11-23 09:59:51,872 [salt.state       ][INFO    ][30599] Executing state pkg.installed for keepalived
2017-11-23 09:59:51,874 [salt.state       ][INFO    ][30599] Package keepalived is already installed
2017-11-23 09:59:51,874 [salt.state       ][INFO    ][30599] Completed state [keepalived] at time 09:59:51.874453 duration_in_ms=2.835
2017-11-23 09:59:51,876 [salt.state       ][INFO    ][30599] Running state [/etc/keepalived/keepalived.conf] at time 09:59:51.875879
2017-11-23 09:59:51,876 [salt.state       ][INFO    ][30599] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-23 09:59:51,898 [salt.fileclient  ][INFO    ][30599] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-23 09:59:51,930 [salt.fileclient  ][INFO    ][30599] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-23 09:59:51,934 [salt.state       ][INFO    ][30599] File /etc/keepalived/keepalived.conf is in the correct state
2017-11-23 09:59:51,934 [salt.state       ][INFO    ][30599] Completed state [/etc/keepalived/keepalived.conf] at time 09:59:51.934251 duration_in_ms=58.372
2017-11-23 09:59:51,935 [salt.state       ][INFO    ][30599] Running state [keepalived] at time 09:59:51.934748
2017-11-23 09:59:51,935 [salt.state       ][INFO    ][30599] Executing state service.running for keepalived
2017-11-23 09:59:51,935 [salt.loaded.int.module.cmdmod][INFO    ][30599] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-23 09:59:51,952 [salt.loaded.int.module.cmdmod][INFO    ][30599] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-23 09:59:51,959 [salt.loaded.int.module.cmdmod][INFO    ][30599] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-23 09:59:51,966 [salt.state       ][INFO    ][30599] The service keepalived is already running
2017-11-23 09:59:51,966 [salt.state       ][INFO    ][30599] Completed state [keepalived] at time 09:59:51.966219 duration_in_ms=31.47
2017-11-23 09:59:51,967 [salt.minion      ][INFO    ][30599] Returning information for job: 20171123095950842520
2017-11-23 09:59:57,229 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command pillar.get with jid 20171123095957223946
2017-11-23 09:59:57,248 [salt.minion      ][INFO    ][30624] Starting a new job with PID 30624
2017-11-23 09:59:57,254 [salt.minion      ][INFO    ][30624] Returning information for job: 20171123095957223946
2017-11-23 10:00:33,247 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command state.sls with jid 20171123100033238071
2017-11-23 10:00:33,263 [salt.minion      ][INFO    ][30632] Starting a new job with PID 30632
2017-11-23 10:00:33,685 [salt.state       ][INFO    ][30632] Loading fresh modules for state activity
2017-11-23 10:00:33,705 [salt.fileclient  ][INFO    ][30632] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-23 10:00:33,724 [salt.fileclient  ][INFO    ][30632] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-23 10:00:33,999 [salt.state       ][INFO    ][30632] Running state [attr] at time 10:00:33.999152
2017-11-23 10:00:33,999 [salt.state       ][INFO    ][30632] Executing state pkg.installed for attr
2017-11-23 10:00:33,1000 [salt.loaded.int.module.cmdmod][INFO    ][30632] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-23 10:00:34,237 [salt.state       ][INFO    ][30632] Package attr is already installed
2017-11-23 10:00:34,237 [salt.state       ][INFO    ][30632] Completed state [attr] at time 10:00:34.237144 duration_in_ms=237.992
2017-11-23 10:00:34,237 [salt.state       ][INFO    ][30632] Running state [glusterfs-server] at time 10:00:34.237354
2017-11-23 10:00:34,238 [salt.state       ][INFO    ][30632] Executing state pkg.installed for glusterfs-server
2017-11-23 10:00:34,240 [salt.state       ][INFO    ][30632] Package glusterfs-server is already installed
2017-11-23 10:00:34,240 [salt.state       ][INFO    ][30632] Completed state [glusterfs-server] at time 10:00:34.240226 duration_in_ms=2.872
2017-11-23 10:00:34,240 [salt.state       ][INFO    ][30632] Running state [bc] at time 10:00:34.240369
2017-11-23 10:00:34,241 [salt.state       ][INFO    ][30632] Executing state pkg.installed for bc
2017-11-23 10:00:34,243 [salt.state       ][INFO    ][30632] Package bc is already installed
2017-11-23 10:00:34,243 [salt.state       ][INFO    ][30632] Completed state [bc] at time 10:00:34.243144 duration_in_ms=2.775
2017-11-23 10:00:34,244 [salt.state       ][INFO    ][30632] Running state [glusterfs-server] at time 10:00:34.243783
2017-11-23 10:00:34,244 [salt.state       ][INFO    ][30632] Executing state service.running for glusterfs-server
2017-11-23 10:00:34,244 [salt.loaded.int.module.cmdmod][INFO    ][30632] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-23 10:00:34,261 [salt.loaded.int.module.cmdmod][INFO    ][30632] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-23 10:00:34,269 [salt.loaded.int.module.cmdmod][INFO    ][30632] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-23 10:00:34,280 [salt.state       ][INFO    ][30632] The service glusterfs-server is already running
2017-11-23 10:00:34,280 [salt.state       ][INFO    ][30632] Completed state [glusterfs-server] at time 10:00:34.280073 duration_in_ms=36.289
2017-11-23 10:00:34,281 [salt.state       ][INFO    ][30632] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:00:34.281378
2017-11-23 10:00:34,282 [salt.state       ][INFO    ][30632] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-23 10:00:34,290 [salt.state       ][INFO    ][30632] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-23 10:00:34,291 [salt.state       ][INFO    ][30632] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:00:34.290551 duration_in_ms=9.173
2017-11-23 10:00:34,291 [salt.state       ][INFO    ][30632] Running state [/srv/glusterfs/glance] at time 10:00:34.290700
2017-11-23 10:00:34,291 [salt.state       ][INFO    ][30632] Executing state file.directory for /srv/glusterfs/glance
2017-11-23 10:00:34,291 [salt.state       ][INFO    ][30632] Directory /srv/glusterfs/glance is in the correct state
2017-11-23 10:00:34,291 [salt.state       ][INFO    ][30632] Completed state [/srv/glusterfs/glance] at time 10:00:34.291311 duration_in_ms=0.61
2017-11-23 10:00:34,291 [salt.state       ][INFO    ][30632] Running state [/srv/glusterfs/keystone-keys] at time 10:00:34.291449
2017-11-23 10:00:34,292 [salt.state       ][INFO    ][30632] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-23 10:00:34,292 [salt.state       ][INFO    ][30632] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-23 10:00:34,292 [salt.state       ][INFO    ][30632] Completed state [/srv/glusterfs/keystone-keys] at time 10:00:34.292005 duration_in_ms=0.555
2017-11-23 10:00:34,292 [salt.state       ][INFO    ][30632] Running state [/srv/glusterfs/nova_instances] at time 10:00:34.292139
2017-11-23 10:00:34,292 [salt.state       ][INFO    ][30632] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-23 10:00:34,293 [salt.state       ][INFO    ][30632] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-23 10:00:34,293 [salt.state       ][INFO    ][30632] Completed state [/srv/glusterfs/nova_instances] at time 10:00:34.292714 duration_in_ms=0.575
2017-11-23 10:00:34,293 [salt.state       ][INFO    ][30632] Running state [/srv/glusterfs/keystone-credential-keys] at time 10:00:34.292853
2017-11-23 10:00:34,293 [salt.state       ][INFO    ][30632] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-23 10:00:34,293 [salt.state       ][INFO    ][30632] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-23 10:00:34,293 [salt.state       ][INFO    ][30632] Completed state [/srv/glusterfs/keystone-credential-keys] at time 10:00:34.293406 duration_in_ms=0.553
2017-11-23 10:00:34,294 [salt.minion      ][INFO    ][30632] Returning information for job: 20171123100033238071
2017-11-23 10:00:35,576 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command test.ping with jid 20171123100035566083
2017-11-23 10:00:35,590 [salt.minion      ][INFO    ][30655] Starting a new job with PID 30655
2017-11-23 10:00:35,619 [salt.minion      ][INFO    ][30655] Returning information for job: 20171123100035566083
2017-11-23 10:00:40,801 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command state.sls with jid 20171123100040786985
2017-11-23 10:00:40,816 [salt.minion      ][INFO    ][30664] Starting a new job with PID 30664
2017-11-23 10:00:41,260 [salt.state       ][INFO    ][30664] Loading fresh modules for state activity
2017-11-23 10:00:41,283 [salt.fileclient  ][INFO    ][30664] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-23 10:00:41,318 [salt.fileclient  ][INFO    ][30664] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-23 10:00:41,382 [salt.fileclient  ][INFO    ][30664] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-23 10:00:41,401 [salt.fileclient  ][INFO    ][30664] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-23 10:00:41,660 [salt.state       ][INFO    ][30664] Running state [attr] at time 10:00:41.660412
2017-11-23 10:00:41,661 [salt.state       ][INFO    ][30664] Executing state pkg.installed for attr
2017-11-23 10:00:41,661 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-23 10:00:41,923 [salt.state       ][INFO    ][30664] Package attr is already installed
2017-11-23 10:00:41,923 [salt.state       ][INFO    ][30664] Completed state [attr] at time 10:00:41.922984 duration_in_ms=262.572
2017-11-23 10:00:41,923 [salt.state       ][INFO    ][30664] Running state [glusterfs-server] at time 10:00:41.923182
2017-11-23 10:00:41,923 [salt.state       ][INFO    ][30664] Executing state pkg.installed for glusterfs-server
2017-11-23 10:00:41,926 [salt.state       ][INFO    ][30664] Package glusterfs-server is already installed
2017-11-23 10:00:41,926 [salt.state       ][INFO    ][30664] Completed state [glusterfs-server] at time 10:00:41.926042 duration_in_ms=2.86
2017-11-23 10:00:41,926 [salt.state       ][INFO    ][30664] Running state [bc] at time 10:00:41.926183
2017-11-23 10:00:41,926 [salt.state       ][INFO    ][30664] Executing state pkg.installed for bc
2017-11-23 10:00:41,929 [salt.state       ][INFO    ][30664] Package bc is already installed
2017-11-23 10:00:41,929 [salt.state       ][INFO    ][30664] Completed state [bc] at time 10:00:41.929020 duration_in_ms=2.837
2017-11-23 10:00:41,930 [salt.state       ][INFO    ][30664] Running state [glusterfs-server] at time 10:00:41.929800
2017-11-23 10:00:41,930 [salt.state       ][INFO    ][30664] Executing state service.running for glusterfs-server
2017-11-23 10:00:41,930 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-23 10:00:41,946 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-23 10:00:41,954 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-23 10:00:41,963 [salt.state       ][INFO    ][30664] The service glusterfs-server is already running
2017-11-23 10:00:41,963 [salt.state       ][INFO    ][30664] Completed state [glusterfs-server] at time 10:00:41.963291 duration_in_ms=33.49
2017-11-23 10:00:41,965 [salt.state       ][INFO    ][30664] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:00:41.964871
2017-11-23 10:00:41,965 [salt.state       ][INFO    ][30664] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-23 10:00:41,977 [salt.state       ][INFO    ][30664] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-23 10:00:41,977 [salt.state       ][INFO    ][30664] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:00:41.977405 duration_in_ms=12.534
2017-11-23 10:00:41,978 [salt.state       ][INFO    ][30664] Running state [/srv/glusterfs/glance] at time 10:00:41.977599
2017-11-23 10:00:41,978 [salt.state       ][INFO    ][30664] Executing state file.directory for /srv/glusterfs/glance
2017-11-23 10:00:41,978 [salt.state       ][INFO    ][30664] Directory /srv/glusterfs/glance is in the correct state
2017-11-23 10:00:41,978 [salt.state       ][INFO    ][30664] Completed state [/srv/glusterfs/glance] at time 10:00:41.978330 duration_in_ms=0.731
2017-11-23 10:00:41,979 [salt.state       ][INFO    ][30664] Running state [/srv/glusterfs/keystone-keys] at time 10:00:41.978515
2017-11-23 10:00:41,979 [salt.state       ][INFO    ][30664] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-23 10:00:41,979 [salt.state       ][INFO    ][30664] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-23 10:00:41,979 [salt.state       ][INFO    ][30664] Completed state [/srv/glusterfs/keystone-keys] at time 10:00:41.979300 duration_in_ms=0.784
2017-11-23 10:00:41,980 [salt.state       ][INFO    ][30664] Running state [/srv/glusterfs/nova_instances] at time 10:00:41.979478
2017-11-23 10:00:41,980 [salt.state       ][INFO    ][30664] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-23 10:00:41,980 [salt.state       ][INFO    ][30664] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-23 10:00:41,980 [salt.state       ][INFO    ][30664] Completed state [/srv/glusterfs/nova_instances] at time 10:00:41.980201 duration_in_ms=0.723
2017-11-23 10:00:41,980 [salt.state       ][INFO    ][30664] Running state [/srv/glusterfs/keystone-credential-keys] at time 10:00:41.980385
2017-11-23 10:00:41,981 [salt.state       ][INFO    ][30664] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-23 10:00:41,981 [salt.state       ][INFO    ][30664] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-23 10:00:41,981 [salt.state       ][INFO    ][30664] Completed state [/srv/glusterfs/keystone-credential-keys] at time 10:00:41.981097 duration_in_ms=0.712
2017-11-23 10:00:42,027 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command ['git', '--version'] in directory '/root'
2017-11-23 10:00:42,107 [salt.state       ][INFO    ][30664] Running state [sleep 5] at time 10:00:42.106876
2017-11-23 10:00:42,107 [salt.state       ][INFO    ][30664] Executing state cmd.wait for sleep 5
2017-11-23 10:00:42,107 [salt.state       ][INFO    ][30664] No changes made for sleep 5
2017-11-23 10:00:42,108 [salt.state       ][INFO    ][30664] Completed state [sleep 5] at time 10:00:42.107480 duration_in_ms=0.604
2017-11-23 10:00:42,108 [salt.state       ][INFO    ][30664] Running state [10.167.4.141] at time 10:00:42.107858
2017-11-23 10:00:42,108 [salt.state       ][INFO    ][30664] Executing state glusterfs.peered for 10.167.4.141
2017-11-23 10:00:42,108 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:42,118 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:42,167 [salt.state       ][INFO    ][30664] Host 10.167.4.141 already peered
2017-11-23 10:00:42,167 [salt.state       ][INFO    ][30664] Completed state [10.167.4.141] at time 10:00:42.166949 duration_in_ms=59.09
2017-11-23 10:00:42,169 [salt.state       ][INFO    ][30664] Running state [10.167.4.142] at time 10:00:42.169276
2017-11-23 10:00:42,170 [salt.state       ][INFO    ][30664] Executing state glusterfs.peered for 10.167.4.142
2017-11-23 10:00:42,170 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:42,179 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:42,228 [salt.state       ][INFO    ][30664] Host 10.167.4.142 already peered
2017-11-23 10:00:42,228 [salt.state       ][INFO    ][30664] Completed state [10.167.4.142] at time 10:00:42.228290 duration_in_ms=59.014
2017-11-23 10:00:42,231 [salt.state       ][INFO    ][30664] Running state [10.167.4.143] at time 10:00:42.230599
2017-11-23 10:00:42,231 [salt.state       ][INFO    ][30664] Executing state glusterfs.peered for 10.167.4.143
2017-11-23 10:00:42,231 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:42,240 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:42,287 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:42,296 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:42,350 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:42,360 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:42,411 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:42,419 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:42,467 [salt.state       ][INFO    ][30664] Peering with localhost is not needed
2017-11-23 10:00:42,467 [salt.state       ][INFO    ][30664] Completed state [10.167.4.143] at time 10:00:42.466914 duration_in_ms=236.315
2017-11-23 10:00:42,468 [salt.state       ][INFO    ][30664] Running state [glance] at time 10:00:42.467666
2017-11-23 10:00:42,468 [salt.state       ][INFO    ][30664] Executing state glusterfs.created for glance
2017-11-23 10:00:42,468 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:42,477 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:42,528 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:42,537 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:42,590 [salt.state       ][INFO    ][30664] Volume glance already exists and is started.
2017-11-23 10:00:42,591 [salt.state       ][INFO    ][30664] Completed state [glance] at time 10:00:42.590481 duration_in_ms=122.813
2017-11-23 10:00:42,593 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 10:00:42.593210
2017-11-23 10:00:42,593 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-23 10:00:42,594 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-23 10:00:42,645 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:42,646 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 10:00:42.645564 duration_in_ms=52.354
2017-11-23 10:00:42,646 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 10:00:42.646162
2017-11-23 10:00:42,646 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-23 10:00:42,647 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-23 10:00:42,696 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:42,696 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 10:00:42.696165 duration_in_ms=50.002
2017-11-23 10:00:42,697 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 10:00:42.696595
2017-11-23 10:00:42,697 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-23 10:00:42,697 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-23 10:00:42,744 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:42,744 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 10:00:42.744329 duration_in_ms=47.734
2017-11-23 10:00:42,745 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 10:00:42.744770
2017-11-23 10:00:42,745 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-23 10:00:42,745 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-23 10:00:42,794 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:42,795 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 10:00:42.794594 duration_in_ms=49.824
2017-11-23 10:00:42,795 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 10:00:42.795076
2017-11-23 10:00:42,795 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-23 10:00:42,796 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-23 10:00:42,843 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:42,844 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 10:00:42.843663 duration_in_ms=48.587
2017-11-23 10:00:42,845 [salt.state       ][INFO    ][30664] Running state [glance] at time 10:00:42.844623
2017-11-23 10:00:42,845 [salt.state       ][INFO    ][30664] Executing state glusterfs.started for glance
2017-11-23 10:00:42,845 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:42,853 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:42,901 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:42,909 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:42,960 [salt.state       ][INFO    ][30664] Volume glance is already started
2017-11-23 10:00:42,960 [salt.state       ][INFO    ][30664] Completed state [glance] at time 10:00:42.960186 duration_in_ms=115.563
2017-11-23 10:00:42,961 [salt.state       ][INFO    ][30664] Running state [keystone-keys] at time 10:00:42.960960
2017-11-23 10:00:42,961 [salt.state       ][INFO    ][30664] Executing state glusterfs.created for keystone-keys
2017-11-23 10:00:42,962 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:42,970 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:43,019 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:43,028 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:43,081 [salt.state       ][INFO    ][30664] Volume keystone-keys already exists and is started.
2017-11-23 10:00:43,082 [salt.state       ][INFO    ][30664] Completed state [keystone-keys] at time 10:00:43.081454 duration_in_ms=120.494
2017-11-23 10:00:43,084 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 10:00:43.084346
2017-11-23 10:00:43,085 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-23 10:00:43,085 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-23 10:00:43,138 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:43,138 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 10:00:43.138298 duration_in_ms=53.952
2017-11-23 10:00:43,139 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:00:43.138768
2017-11-23 10:00:43,139 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-23 10:00:43,139 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-23 10:00:43,188 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:43,188 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:00:43.187837 duration_in_ms=49.068
2017-11-23 10:00:43,188 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:00:43.188286
2017-11-23 10:00:43,188 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-23 10:00:43,189 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-23 10:00:43,243 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:43,243 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:00:43.243018 duration_in_ms=54.72
2017-11-23 10:00:43,244 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 10:00:43.243501
2017-11-23 10:00:43,244 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-23 10:00:43,244 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-23 10:00:43,292 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:43,293 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 10:00:43.292640 duration_in_ms=49.139
2017-11-23 10:00:43,293 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 10:00:43.293057
2017-11-23 10:00:43,293 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-23 10:00:43,294 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-23 10:00:43,344 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:43,344 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 10:00:43.343923 duration_in_ms=50.865
2017-11-23 10:00:43,345 [salt.state       ][INFO    ][30664] Running state [keystone-keys] at time 10:00:43.344907
2017-11-23 10:00:43,345 [salt.state       ][INFO    ][30664] Executing state glusterfs.started for keystone-keys
2017-11-23 10:00:43,346 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:43,355 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:43,404 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:43,412 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:43,462 [salt.state       ][INFO    ][30664] Volume keystone-keys is already started
2017-11-23 10:00:43,463 [salt.state       ][INFO    ][30664] Completed state [keystone-keys] at time 10:00:43.462474 duration_in_ms=117.567
2017-11-23 10:00:43,464 [salt.state       ][INFO    ][30664] Running state [nova_instances] at time 10:00:43.463507
2017-11-23 10:00:43,464 [salt.state       ][INFO    ][30664] Executing state glusterfs.created for nova_instances
2017-11-23 10:00:43,464 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:43,473 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:43,521 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:43,530 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:43,582 [salt.state       ][INFO    ][30664] Volume nova_instances already exists and is started.
2017-11-23 10:00:43,582 [salt.state       ][INFO    ][30664] Completed state [nova_instances] at time 10:00:43.582250 duration_in_ms=118.743
2017-11-23 10:00:43,585 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 10:00:43.585055
2017-11-23 10:00:43,585 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-23 10:00:43,586 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-23 10:00:43,634 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:43,634 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 10:00:43.633703 duration_in_ms=48.648
2017-11-23 10:00:43,634 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 10:00:43.634130
2017-11-23 10:00:43,634 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-11-23 10:00:43,635 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-11-23 10:00:43,682 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:43,683 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 10:00:43.682547 duration_in_ms=48.417
2017-11-23 10:00:43,683 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 10:00:43.683004
2017-11-23 10:00:43,683 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-11-23 10:00:43,684 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-11-23 10:00:43,731 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:43,732 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 10:00:43.731648 duration_in_ms=48.643
2017-11-23 10:00:43,732 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 10:00:43.732109
2017-11-23 10:00:43,732 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-11-23 10:00:43,733 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-11-23 10:00:43,779 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:43,779 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 10:00:43.779000 duration_in_ms=46.891
2017-11-23 10:00:43,779 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 10:00:43.779448
2017-11-23 10:00:43,780 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-23 10:00:43,780 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-23 10:00:43,828 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:43,828 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 10:00:43.827805 duration_in_ms=48.355
2017-11-23 10:00:43,829 [salt.state       ][INFO    ][30664] Running state [nova_instances] at time 10:00:43.828699
2017-11-23 10:00:43,829 [salt.state       ][INFO    ][30664] Executing state glusterfs.started for nova_instances
2017-11-23 10:00:43,829 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:43,838 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:43,888 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:43,895 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:43,945 [salt.state       ][INFO    ][30664] Volume nova_instances is already started
2017-11-23 10:00:43,945 [salt.state       ][INFO    ][30664] Completed state [nova_instances] at time 10:00:43.945174 duration_in_ms=116.474
2017-11-23 10:00:43,946 [salt.state       ][INFO    ][30664] Running state [keystone-credential-keys] at time 10:00:43.946072
2017-11-23 10:00:43,946 [salt.state       ][INFO    ][30664] Executing state glusterfs.created for keystone-credential-keys
2017-11-23 10:00:43,947 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:43,955 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:44,002 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:44,010 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:44,063 [salt.state       ][INFO    ][30664] Volume keystone-credential-keys already exists and is started.
2017-11-23 10:00:44,063 [salt.state       ][INFO    ][30664] Completed state [keystone-credential-keys] at time 10:00:44.063172 duration_in_ms=117.099
2017-11-23 10:00:44,066 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 10:00:44.066233
2017-11-23 10:00:44,067 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-23 10:00:44,067 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-23 10:00:44,117 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:44,117 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 10:00:44.117065 duration_in_ms=50.832
2017-11-23 10:00:44,118 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:00:44.117477
2017-11-23 10:00:44,118 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-23 10:00:44,118 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-23 10:00:44,168 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:44,168 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:00:44.168255 duration_in_ms=50.777
2017-11-23 10:00:44,169 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 10:00:44.169306
2017-11-23 10:00:44,170 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-23 10:00:44,171 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-23 10:00:44,222 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:44,222 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 10:00:44.221960 duration_in_ms=52.652
2017-11-23 10:00:44,222 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:00:44.222403
2017-11-23 10:00:44,223 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-23 10:00:44,223 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-23 10:00:44,270 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:44,270 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:00:44.269914 duration_in_ms=47.511
2017-11-23 10:00:44,270 [salt.state       ][INFO    ][30664] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 10:00:44.270378
2017-11-23 10:00:44,271 [salt.state       ][INFO    ][30664] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-23 10:00:44,271 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-23 10:00:44,319 [salt.state       ][INFO    ][30664] unless execution succeeded
2017-11-23 10:00:44,319 [salt.state       ][INFO    ][30664] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 10:00:44.319008 duration_in_ms=48.629
2017-11-23 10:00:44,320 [salt.state       ][INFO    ][30664] Running state [keystone-credential-keys] at time 10:00:44.319910
2017-11-23 10:00:44,320 [salt.state       ][INFO    ][30664] Executing state glusterfs.started for keystone-credential-keys
2017-11-23 10:00:44,321 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:44,328 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:44,375 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:00:44,383 [salt.loaded.int.module.cmdmod][INFO    ][30664] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:00:44,434 [salt.state       ][INFO    ][30664] Volume keystone-credential-keys is already started
2017-11-23 10:00:44,434 [salt.state       ][INFO    ][30664] Completed state [keystone-credential-keys] at time 10:00:44.434005 duration_in_ms=114.093
2017-11-23 10:00:44,436 [salt.minion      ][INFO    ][30664] Returning information for job: 20171123100040786985
2017-11-23 10:00:49,274 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command test.ping with jid 20171123100049269026
2017-11-23 10:00:49,289 [salt.minion      ][INFO    ][31048] Starting a new job with PID 31048
2017-11-23 10:00:49,319 [salt.minion      ][INFO    ][31048] Returning information for job: 20171123100049269026
2017-11-23 10:00:49,702 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command cmd.run with jid 20171123100049693369
2017-11-23 10:00:49,717 [salt.minion      ][INFO    ][31053] Starting a new job with PID 31053
2017-11-23 10:00:49,724 [salt.loaded.int.module.cmdmod][INFO    ][31053] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-23 10:00:49,830 [salt.minion      ][INFO    ][31053] Returning information for job: 20171123100049693369
2017-11-23 10:25:42,167 [salt.utils.schedule][INFO    ][22846] Running scheduled job: __mine_interval
2017-11-23 10:55:27,914 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command test.ping with jid 20171123105527904487
2017-11-23 10:55:27,934 [salt.minion      ][INFO    ][31707] Starting a new job with PID 31707
2017-11-23 10:55:27,986 [salt.minion      ][INFO    ][31707] Returning information for job: 20171123105527904487
2017-11-23 10:55:51,463 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command state.sls with jid 20171123105551448429
2017-11-23 10:55:51,479 [salt.minion      ][INFO    ][31715] Starting a new job with PID 31715
2017-11-23 10:55:51,975 [salt.state       ][INFO    ][31715] Loading fresh modules for state activity
2017-11-23 10:55:52,002 [salt.fileclient  ][INFO    ][31715] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-23 10:55:52,018 [salt.fileclient  ][INFO    ][31715] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-23 10:55:52,043 [salt.fileclient  ][INFO    ][31715] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-23 10:55:52,303 [salt.state       ][INFO    ][31715] Running state [lsof] at time 10:55:52.303275
2017-11-23 10:55:52,304 [salt.state       ][INFO    ][31715] Executing state pkg.installed for lsof
2017-11-23 10:55:52,304 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-23 10:55:52,565 [salt.state       ][INFO    ][31715] Package lsof is already installed
2017-11-23 10:55:52,566 [salt.state       ][INFO    ][31715] Completed state [lsof] at time 10:55:52.565615 duration_in_ms=262.341
2017-11-23 10:55:52,566 [salt.state       ][INFO    ][31715] Running state [keepalived] at time 10:55:52.565810
2017-11-23 10:55:52,566 [salt.state       ][INFO    ][31715] Executing state pkg.installed for keepalived
2017-11-23 10:55:52,569 [salt.state       ][INFO    ][31715] Package keepalived is already installed
2017-11-23 10:55:52,569 [salt.state       ][INFO    ][31715] Completed state [keepalived] at time 10:55:52.568683 duration_in_ms=2.872
2017-11-23 10:55:52,570 [salt.state       ][INFO    ][31715] Running state [/etc/keepalived/keepalived.conf] at time 10:55:52.570019
2017-11-23 10:55:52,570 [salt.state       ][INFO    ][31715] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-23 10:55:52,591 [salt.fileclient  ][INFO    ][31715] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-23 10:55:52,625 [salt.fileclient  ][INFO    ][31715] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-23 10:55:52,630 [salt.state       ][INFO    ][31715] File /etc/keepalived/keepalived.conf is in the correct state
2017-11-23 10:55:52,630 [salt.state       ][INFO    ][31715] Completed state [/etc/keepalived/keepalived.conf] at time 10:55:52.629744 duration_in_ms=59.725
2017-11-23 10:55:52,630 [salt.state       ][INFO    ][31715] Running state [keepalived] at time 10:55:52.630386
2017-11-23 10:55:52,631 [salt.state       ][INFO    ][31715] Executing state service.running for keepalived
2017-11-23 10:55:52,631 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-23 10:55:52,647 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-23 10:55:52,654 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-23 10:55:52,662 [salt.state       ][INFO    ][31715] The service keepalived is already running
2017-11-23 10:55:52,662 [salt.state       ][INFO    ][31715] Completed state [keepalived] at time 10:55:52.661982 duration_in_ms=31.596
2017-11-23 10:55:52,663 [salt.minion      ][INFO    ][31715] Returning information for job: 20171123105551448429
2017-11-23 10:55:59,605 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command pillar.get with jid 20171123105559591073
2017-11-23 10:55:59,627 [salt.minion      ][INFO    ][31739] Starting a new job with PID 31739
2017-11-23 10:55:59,635 [salt.minion      ][INFO    ][31739] Returning information for job: 20171123105559591073
2017-11-23 10:56:35,358 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command state.sls with jid 20171123105635343942
2017-11-23 10:56:35,372 [salt.minion      ][INFO    ][31746] Starting a new job with PID 31746
2017-11-23 10:56:38,067 [salt.state       ][INFO    ][31746] Loading fresh modules for state activity
2017-11-23 10:56:38,090 [salt.fileclient  ][INFO    ][31746] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-23 10:56:38,111 [salt.fileclient  ][INFO    ][31746] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-23 10:56:38,375 [salt.state       ][INFO    ][31746] Running state [attr] at time 10:56:38.374810
2017-11-23 10:56:38,375 [salt.state       ][INFO    ][31746] Executing state pkg.installed for attr
2017-11-23 10:56:38,375 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-23 10:56:38,634 [salt.state       ][INFO    ][31746] Package attr is already installed
2017-11-23 10:56:38,634 [salt.state       ][INFO    ][31746] Completed state [attr] at time 10:56:38.634017 duration_in_ms=259.208
2017-11-23 10:56:38,634 [salt.state       ][INFO    ][31746] Running state [glusterfs-server] at time 10:56:38.634211
2017-11-23 10:56:38,634 [salt.state       ][INFO    ][31746] Executing state pkg.installed for glusterfs-server
2017-11-23 10:56:38,637 [salt.state       ][INFO    ][31746] Package glusterfs-server is already installed
2017-11-23 10:56:38,637 [salt.state       ][INFO    ][31746] Completed state [glusterfs-server] at time 10:56:38.637073 duration_in_ms=2.862
2017-11-23 10:56:38,637 [salt.state       ][INFO    ][31746] Running state [bc] at time 10:56:38.637215
2017-11-23 10:56:38,637 [salt.state       ][INFO    ][31746] Executing state pkg.installed for bc
2017-11-23 10:56:38,640 [salt.state       ][INFO    ][31746] Package bc is already installed
2017-11-23 10:56:38,640 [salt.state       ][INFO    ][31746] Completed state [bc] at time 10:56:38.640031 duration_in_ms=2.817
2017-11-23 10:56:38,641 [salt.state       ][INFO    ][31746] Running state [glusterfs-server] at time 10:56:38.640668
2017-11-23 10:56:38,641 [salt.state       ][INFO    ][31746] Executing state service.running for glusterfs-server
2017-11-23 10:56:38,641 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-23 10:56:38,657 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-23 10:56:38,664 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-23 10:56:38,674 [salt.state       ][INFO    ][31746] The service glusterfs-server is already running
2017-11-23 10:56:38,674 [salt.state       ][INFO    ][31746] Completed state [glusterfs-server] at time 10:56:38.674022 duration_in_ms=33.353
2017-11-23 10:56:38,675 [salt.state       ][INFO    ][31746] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:56:38.675370
2017-11-23 10:56:38,676 [salt.state       ][INFO    ][31746] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-23 10:56:38,684 [salt.state       ][INFO    ][31746] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-23 10:56:38,684 [salt.state       ][INFO    ][31746] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:56:38.683697 duration_in_ms=8.328
2017-11-23 10:56:38,684 [salt.state       ][INFO    ][31746] Running state [/srv/glusterfs/glance] at time 10:56:38.683845
2017-11-23 10:56:38,684 [salt.state       ][INFO    ][31746] Executing state file.directory for /srv/glusterfs/glance
2017-11-23 10:56:38,684 [salt.state       ][INFO    ][31746] Directory /srv/glusterfs/glance is in the correct state
2017-11-23 10:56:38,685 [salt.state       ][INFO    ][31746] Completed state [/srv/glusterfs/glance] at time 10:56:38.684493 duration_in_ms=0.647
2017-11-23 10:56:38,685 [salt.state       ][INFO    ][31746] Running state [/srv/glusterfs/keystone-keys] at time 10:56:38.684634
2017-11-23 10:56:38,685 [salt.state       ][INFO    ][31746] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-23 10:56:38,685 [salt.state       ][INFO    ][31746] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-23 10:56:38,685 [salt.state       ][INFO    ][31746] Completed state [/srv/glusterfs/keystone-keys] at time 10:56:38.685221 duration_in_ms=0.587
2017-11-23 10:56:38,685 [salt.state       ][INFO    ][31746] Running state [/srv/glusterfs/nova_instances] at time 10:56:38.685360
2017-11-23 10:56:38,686 [salt.state       ][INFO    ][31746] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-23 10:56:38,686 [salt.state       ][INFO    ][31746] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-23 10:56:38,686 [salt.state       ][INFO    ][31746] Completed state [/srv/glusterfs/nova_instances] at time 10:56:38.685971 duration_in_ms=0.611
2017-11-23 10:56:38,686 [salt.state       ][INFO    ][31746] Running state [/srv/glusterfs/keystone-credential-keys] at time 10:56:38.686111
2017-11-23 10:56:38,686 [salt.state       ][INFO    ][31746] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-23 10:56:38,687 [salt.state       ][INFO    ][31746] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-23 10:56:38,687 [salt.state       ][INFO    ][31746] Completed state [/srv/glusterfs/keystone-credential-keys] at time 10:56:38.686714 duration_in_ms=0.603
2017-11-23 10:56:38,687 [salt.minion      ][INFO    ][31746] Returning information for job: 20171123105635343942
2017-11-23 10:56:39,865 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command test.ping with jid 20171123105639851897
2017-11-23 10:56:39,880 [salt.minion      ][INFO    ][31769] Starting a new job with PID 31769
2017-11-23 10:56:39,907 [salt.minion      ][INFO    ][31769] Returning information for job: 20171123105639851897
2017-11-23 10:56:43,879 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command state.sls with jid 20171123105643864051
2017-11-23 10:56:43,895 [salt.minion      ][INFO    ][31774] Starting a new job with PID 31774
2017-11-23 10:56:44,327 [salt.state       ][INFO    ][31774] Loading fresh modules for state activity
2017-11-23 10:56:44,350 [salt.fileclient  ][INFO    ][31774] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-23 10:56:44,385 [salt.fileclient  ][INFO    ][31774] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-23 10:56:44,462 [salt.fileclient  ][INFO    ][31774] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-23 10:56:44,482 [salt.fileclient  ][INFO    ][31774] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-23 10:56:44,751 [salt.state       ][INFO    ][31774] Running state [attr] at time 10:56:44.751027
2017-11-23 10:56:44,751 [salt.state       ][INFO    ][31774] Executing state pkg.installed for attr
2017-11-23 10:56:44,752 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-23 10:56:44,999 [salt.state       ][INFO    ][31774] Package attr is already installed
2017-11-23 10:56:44,999 [salt.state       ][INFO    ][31774] Completed state [attr] at time 10:56:44.998920 duration_in_ms=247.894
2017-11-23 10:56:44,999 [salt.state       ][INFO    ][31774] Running state [glusterfs-server] at time 10:56:44.999160
2017-11-23 10:56:44,999 [salt.state       ][INFO    ][31774] Executing state pkg.installed for glusterfs-server
2017-11-23 10:56:45,002 [salt.state       ][INFO    ][31774] Package glusterfs-server is already installed
2017-11-23 10:56:45,002 [salt.state       ][INFO    ][31774] Completed state [glusterfs-server] at time 10:56:45.002053 duration_in_ms=2.893
2017-11-23 10:56:45,002 [salt.state       ][INFO    ][31774] Running state [bc] at time 10:56:45.002195
2017-11-23 10:56:45,002 [salt.state       ][INFO    ][31774] Executing state pkg.installed for bc
2017-11-23 10:56:45,005 [salt.state       ][INFO    ][31774] Package bc is already installed
2017-11-23 10:56:45,005 [salt.state       ][INFO    ][31774] Completed state [bc] at time 10:56:45.004983 duration_in_ms=2.788
2017-11-23 10:56:45,006 [salt.state       ][INFO    ][31774] Running state [glusterfs-server] at time 10:56:45.005758
2017-11-23 10:56:45,006 [salt.state       ][INFO    ][31774] Executing state service.running for glusterfs-server
2017-11-23 10:56:45,006 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-23 10:56:45,023 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-23 10:56:45,031 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-23 10:56:45,040 [salt.state       ][INFO    ][31774] The service glusterfs-server is already running
2017-11-23 10:56:45,040 [salt.state       ][INFO    ][31774] Completed state [glusterfs-server] at time 10:56:45.040337 duration_in_ms=34.578
2017-11-23 10:56:45,042 [salt.state       ][INFO    ][31774] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:56:45.041717
2017-11-23 10:56:45,042 [salt.state       ][INFO    ][31774] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-23 10:56:45,053 [salt.state       ][INFO    ][31774] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-23 10:56:45,054 [salt.state       ][INFO    ][31774] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:56:45.053616 duration_in_ms=11.9
2017-11-23 10:56:45,054 [salt.state       ][INFO    ][31774] Running state [/srv/glusterfs/glance] at time 10:56:45.053780
2017-11-23 10:56:45,054 [salt.state       ][INFO    ][31774] Executing state file.directory for /srv/glusterfs/glance
2017-11-23 10:56:45,054 [salt.state       ][INFO    ][31774] Directory /srv/glusterfs/glance is in the correct state
2017-11-23 10:56:45,054 [salt.state       ][INFO    ][31774] Completed state [/srv/glusterfs/glance] at time 10:56:45.054432 duration_in_ms=0.652
2017-11-23 10:56:45,055 [salt.state       ][INFO    ][31774] Running state [/srv/glusterfs/keystone-keys] at time 10:56:45.054580
2017-11-23 10:56:45,055 [salt.state       ][INFO    ][31774] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-23 10:56:45,055 [salt.state       ][INFO    ][31774] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-23 10:56:45,055 [salt.state       ][INFO    ][31774] Completed state [/srv/glusterfs/keystone-keys] at time 10:56:45.055229 duration_in_ms=0.649
2017-11-23 10:56:45,055 [salt.state       ][INFO    ][31774] Running state [/srv/glusterfs/nova_instances] at time 10:56:45.055376
2017-11-23 10:56:45,056 [salt.state       ][INFO    ][31774] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-23 10:56:45,056 [salt.state       ][INFO    ][31774] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-23 10:56:45,056 [salt.state       ][INFO    ][31774] Completed state [/srv/glusterfs/nova_instances] at time 10:56:45.055973 duration_in_ms=0.596
2017-11-23 10:56:45,056 [salt.state       ][INFO    ][31774] Running state [/srv/glusterfs/keystone-credential-keys] at time 10:56:45.056121
2017-11-23 10:56:45,056 [salt.state       ][INFO    ][31774] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-23 10:56:45,057 [salt.state       ][INFO    ][31774] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-23 10:56:45,057 [salt.state       ][INFO    ][31774] Completed state [/srv/glusterfs/keystone-credential-keys] at time 10:56:45.056744 duration_in_ms=0.624
2017-11-23 10:56:45,098 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command ['git', '--version'] in directory '/root'
2017-11-23 10:56:45,184 [salt.state       ][INFO    ][31774] Running state [sleep 5] at time 10:56:45.183634
2017-11-23 10:56:45,184 [salt.state       ][INFO    ][31774] Executing state cmd.wait for sleep 5
2017-11-23 10:56:45,184 [salt.state       ][INFO    ][31774] No changes made for sleep 5
2017-11-23 10:56:45,184 [salt.state       ][INFO    ][31774] Completed state [sleep 5] at time 10:56:45.184247 duration_in_ms=0.613
2017-11-23 10:56:45,185 [salt.state       ][INFO    ][31774] Running state [10.167.4.141] at time 10:56:45.184640
2017-11-23 10:56:45,185 [salt.state       ][INFO    ][31774] Executing state glusterfs.peered for 10.167.4.141
2017-11-23 10:56:45,185 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:45,195 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:45,246 [salt.state       ][INFO    ][31774] Host 10.167.4.141 already peered
2017-11-23 10:56:45,247 [salt.state       ][INFO    ][31774] Completed state [10.167.4.141] at time 10:56:45.246665 duration_in_ms=62.024
2017-11-23 10:56:45,249 [salt.state       ][INFO    ][31774] Running state [10.167.4.142] at time 10:56:45.249011
2017-11-23 10:56:45,249 [salt.state       ][INFO    ][31774] Executing state glusterfs.peered for 10.167.4.142
2017-11-23 10:56:45,250 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:45,258 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:45,305 [salt.state       ][INFO    ][31774] Host 10.167.4.142 already peered
2017-11-23 10:56:45,306 [salt.state       ][INFO    ][31774] Completed state [10.167.4.142] at time 10:56:45.305645 duration_in_ms=56.634
2017-11-23 10:56:45,308 [salt.state       ][INFO    ][31774] Running state [10.167.4.143] at time 10:56:45.307984
2017-11-23 10:56:45,308 [salt.state       ][INFO    ][31774] Executing state glusterfs.peered for 10.167.4.143
2017-11-23 10:56:45,309 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:45,317 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:45,367 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:45,376 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:45,425 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:45,435 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:45,484 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:45,493 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:45,543 [salt.state       ][INFO    ][31774] Peering with localhost is not needed
2017-11-23 10:56:45,543 [salt.state       ][INFO    ][31774] Completed state [10.167.4.143] at time 10:56:45.543378 duration_in_ms=235.394
2017-11-23 10:56:45,544 [salt.state       ][INFO    ][31774] Running state [glance] at time 10:56:45.544207
2017-11-23 10:56:45,544 [salt.state       ][INFO    ][31774] Executing state glusterfs.created for glance
2017-11-23 10:56:45,545 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:45,553 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:45,600 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:45,608 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:45,659 [salt.state       ][INFO    ][31774] Volume glance already exists and is started.
2017-11-23 10:56:45,659 [salt.state       ][INFO    ][31774] Completed state [glance] at time 10:56:45.659230 duration_in_ms=115.023
2017-11-23 10:56:45,662 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 10:56:45.661829
2017-11-23 10:56:45,662 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-23 10:56:45,662 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-23 10:56:45,710 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:45,710 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 10:56:45.709840 duration_in_ms=48.01
2017-11-23 10:56:45,710 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 10:56:45.710292
2017-11-23 10:56:45,711 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-23 10:56:45,711 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-23 10:56:45,760 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:45,760 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 10:56:45.759706 duration_in_ms=49.414
2017-11-23 10:56:45,760 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 10:56:45.760106
2017-11-23 10:56:45,760 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-23 10:56:45,761 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-23 10:56:45,810 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:45,810 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 10:56:45.809959 duration_in_ms=49.853
2017-11-23 10:56:45,810 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 10:56:45.810401
2017-11-23 10:56:45,811 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-23 10:56:45,811 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-23 10:56:45,858 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:45,859 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 10:56:45.858523 duration_in_ms=48.121
2017-11-23 10:56:45,859 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 10:56:45.858972
2017-11-23 10:56:45,859 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-23 10:56:45,860 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-23 10:56:45,906 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:45,906 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 10:56:45.905788 duration_in_ms=46.815
2017-11-23 10:56:45,907 [salt.state       ][INFO    ][31774] Running state [glance] at time 10:56:45.906757
2017-11-23 10:56:45,907 [salt.state       ][INFO    ][31774] Executing state glusterfs.started for glance
2017-11-23 10:56:45,907 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:45,915 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:45,962 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:45,971 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:46,023 [salt.state       ][INFO    ][31774] Volume glance is already started
2017-11-23 10:56:46,023 [salt.state       ][INFO    ][31774] Completed state [glance] at time 10:56:46.023042 duration_in_ms=116.284
2017-11-23 10:56:46,024 [salt.state       ][INFO    ][31774] Running state [keystone-keys] at time 10:56:46.023924
2017-11-23 10:56:46,024 [salt.state       ][INFO    ][31774] Executing state glusterfs.created for keystone-keys
2017-11-23 10:56:46,025 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:46,034 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:46,085 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:46,095 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:46,148 [salt.state       ][INFO    ][31774] Volume keystone-keys already exists and is started.
2017-11-23 10:56:46,148 [salt.state       ][INFO    ][31774] Completed state [keystone-keys] at time 10:56:46.148363 duration_in_ms=124.439
2017-11-23 10:56:46,151 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 10:56:46.151176
2017-11-23 10:56:46,151 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-23 10:56:46,152 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-23 10:56:46,199 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:46,200 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 10:56:46.199529 duration_in_ms=48.353
2017-11-23 10:56:46,200 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:56:46.199957
2017-11-23 10:56:46,200 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-23 10:56:46,201 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-23 10:56:46,249 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:46,250 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:56:46.249696 duration_in_ms=49.738
2017-11-23 10:56:46,250 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:56:46.250170
2017-11-23 10:56:46,250 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-23 10:56:46,251 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-23 10:56:46,299 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:46,299 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:56:46.299137 duration_in_ms=48.966
2017-11-23 10:56:46,300 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 10:56:46.299583
2017-11-23 10:56:46,300 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-23 10:56:46,300 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-23 10:56:46,350 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:46,350 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 10:56:46.349783 duration_in_ms=50.2
2017-11-23 10:56:46,350 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 10:56:46.350184
2017-11-23 10:56:46,350 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-23 10:56:46,351 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-23 10:56:46,399 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:46,399 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 10:56:46.398996 duration_in_ms=48.811
2017-11-23 10:56:46,400 [salt.state       ][INFO    ][31774] Running state [keystone-keys] at time 10:56:46.399919
2017-11-23 10:56:46,400 [salt.state       ][INFO    ][31774] Executing state glusterfs.started for keystone-keys
2017-11-23 10:56:46,401 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:46,408 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:46,457 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:46,465 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:46,526 [salt.state       ][INFO    ][31774] Volume keystone-keys is already started
2017-11-23 10:56:46,526 [salt.state       ][INFO    ][31774] Completed state [keystone-keys] at time 10:56:46.526379 duration_in_ms=126.46
2017-11-23 10:56:46,527 [salt.state       ][INFO    ][31774] Running state [nova_instances] at time 10:56:46.527403
2017-11-23 10:56:46,528 [salt.state       ][INFO    ][31774] Executing state glusterfs.created for nova_instances
2017-11-23 10:56:46,528 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:46,536 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:46,584 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:46,593 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:46,645 [salt.state       ][INFO    ][31774] Volume nova_instances already exists and is started.
2017-11-23 10:56:46,645 [salt.state       ][INFO    ][31774] Completed state [nova_instances] at time 10:56:46.645418 duration_in_ms=118.014
2017-11-23 10:56:46,648 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 10:56:46.648153
2017-11-23 10:56:46,648 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-23 10:56:46,649 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-23 10:56:46,696 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:46,696 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 10:56:46.696103 duration_in_ms=47.949
2017-11-23 10:56:46,697 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 10:56:46.696556
2017-11-23 10:56:46,697 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-11-23 10:56:46,697 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-11-23 10:56:46,744 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:46,744 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 10:56:46.743693 duration_in_ms=47.135
2017-11-23 10:56:46,744 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 10:56:46.744137
2017-11-23 10:56:46,744 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-11-23 10:56:46,745 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-11-23 10:56:46,793 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:46,793 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 10:56:46.793251 duration_in_ms=49.113
2017-11-23 10:56:46,794 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 10:56:46.793733
2017-11-23 10:56:46,794 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-11-23 10:56:46,794 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-11-23 10:56:46,842 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:46,842 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 10:56:46.841971 duration_in_ms=48.237
2017-11-23 10:56:46,842 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 10:56:46.842440
2017-11-23 10:56:46,843 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-23 10:56:46,843 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-23 10:56:46,891 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:46,891 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 10:56:46.890793 duration_in_ms=48.353
2017-11-23 10:56:46,892 [salt.state       ][INFO    ][31774] Running state [nova_instances] at time 10:56:46.891713
2017-11-23 10:56:46,892 [salt.state       ][INFO    ][31774] Executing state glusterfs.started for nova_instances
2017-11-23 10:56:46,892 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:46,899 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:46,946 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:46,954 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:47,005 [salt.state       ][INFO    ][31774] Volume nova_instances is already started
2017-11-23 10:56:47,005 [salt.state       ][INFO    ][31774] Completed state [nova_instances] at time 10:56:47.005102 duration_in_ms=113.388
2017-11-23 10:56:47,006 [salt.state       ][INFO    ][31774] Running state [keystone-credential-keys] at time 10:56:47.006000
2017-11-23 10:56:47,006 [salt.state       ][INFO    ][31774] Executing state glusterfs.created for keystone-credential-keys
2017-11-23 10:56:47,007 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:47,013 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:47,060 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:47,070 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:47,120 [salt.state       ][INFO    ][31774] Volume keystone-credential-keys already exists and is started.
2017-11-23 10:56:47,120 [salt.state       ][INFO    ][31774] Completed state [keystone-credential-keys] at time 10:56:47.120174 duration_in_ms=114.174
2017-11-23 10:56:47,123 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 10:56:47.123119
2017-11-23 10:56:47,123 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-23 10:56:47,124 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-23 10:56:47,172 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:47,172 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 10:56:47.172264 duration_in_ms=49.144
2017-11-23 10:56:47,173 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:56:47.172671
2017-11-23 10:56:47,173 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-23 10:56:47,173 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-23 10:56:47,224 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:47,225 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:56:47.225014 duration_in_ms=52.342
2017-11-23 10:56:47,226 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 10:56:47.226122
2017-11-23 10:56:47,227 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-23 10:56:47,228 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-23 10:56:47,278 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:47,279 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 10:56:47.278644 duration_in_ms=52.521
2017-11-23 10:56:47,279 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:56:47.279246
2017-11-23 10:56:47,280 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-23 10:56:47,280 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-23 10:56:47,329 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:47,329 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:56:47.328797 duration_in_ms=49.551
2017-11-23 10:56:47,329 [salt.state       ][INFO    ][31774] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 10:56:47.329279
2017-11-23 10:56:47,330 [salt.state       ][INFO    ][31774] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-23 10:56:47,330 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-23 10:56:47,377 [salt.state       ][INFO    ][31774] unless execution succeeded
2017-11-23 10:56:47,377 [salt.state       ][INFO    ][31774] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 10:56:47.376814 duration_in_ms=47.534
2017-11-23 10:56:47,378 [salt.state       ][INFO    ][31774] Running state [keystone-credential-keys] at time 10:56:47.377874
2017-11-23 10:56:47,378 [salt.state       ][INFO    ][31774] Executing state glusterfs.started for keystone-credential-keys
2017-11-23 10:56:47,379 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:47,387 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:47,437 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --version' in directory '/root'
2017-11-23 10:56:47,447 [salt.loaded.int.module.cmdmod][INFO    ][31774] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-23 10:56:47,498 [salt.state       ][INFO    ][31774] Volume keystone-credential-keys is already started
2017-11-23 10:56:47,499 [salt.state       ][INFO    ][31774] Completed state [keystone-credential-keys] at time 10:56:47.498533 duration_in_ms=120.66
2017-11-23 10:56:47,501 [salt.minion      ][INFO    ][31774] Returning information for job: 20171123105643864051
2017-11-23 10:56:52,392 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command test.ping with jid 20171123105652377661
2017-11-23 10:56:52,407 [salt.minion      ][INFO    ][32156] Starting a new job with PID 32156
2017-11-23 10:56:52,435 [salt.minion      ][INFO    ][32156] Returning information for job: 20171123105652377661
2017-11-23 10:56:52,825 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command cmd.run with jid 20171123105652810474
2017-11-23 10:56:52,840 [salt.minion      ][INFO    ][32161] Starting a new job with PID 32161
2017-11-23 10:56:52,844 [salt.loaded.int.module.cmdmod][INFO    ][32161] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-23 10:56:52,957 [salt.minion      ][INFO    ][32161] Returning information for job: 20171123105652810474
2017-11-23 11:25:42,168 [salt.utils.schedule][INFO    ][22846] Running scheduled job: __mine_interval
2017-11-23 11:26:16,085 [salt.minion      ][INFO    ][22846] User sudo_ubuntu Executing command cp.push_dir with jid 20171123112616069652
2017-11-23 11:26:16,109 [salt.minion      ][INFO    ][32385] Starting a new job with PID 32385
