2017-11-13 08:36:27,635 [salt.cli.daemons ][WARNING ][2213] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-13 08:36:51,036 [salt.state       ][ERROR   ][3652] No contents found in top file
2017-11-13 08:38:47,107 [salt.utils.parsers][WARNING ][2213] Minion received a SIGTERM. Exiting.
2017-11-13 08:43:16,373 [salt.cli.daemons ][WARNING ][3260] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-13 08:45:30,689 [py.warnings      ][WARNING ][16409] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2017-11-13 08:45:54,370 [salt.loaded.int.module.cmdmod][ERROR   ][16409] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-13 08:45:54,370 [salt.loaded.int.module.cmdmod][ERROR   ][16409] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-13 08:45:58,812 [salt.loaded.int.module.cmdmod][ERROR   ][16409] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-13 08:45:58,813 [salt.loaded.int.module.cmdmod][ERROR   ][16409] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-13 08:46:03,228 [salt.loaded.int.module.cmdmod][ERROR   ][16409] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-13 08:46:03,229 [salt.loaded.int.module.cmdmod][ERROR   ][16409] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-13 08:46:07,732 [salt.loaded.int.module.cmdmod][ERROR   ][16409] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-13 08:46:07,733 [salt.loaded.int.module.cmdmod][ERROR   ][16409] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-13 08:46:12,226 [salt.loaded.int.module.cmdmod][ERROR   ][16409] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-13 08:46:12,226 [salt.loaded.int.module.cmdmod][ERROR   ][16409] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-13 08:46:16,652 [salt.loaded.int.module.cmdmod][ERROR   ][16409] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-13 08:46:16,653 [salt.loaded.int.module.cmdmod][ERROR   ][16409] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-13 08:46:20,591 [salt.loaded.int.module.cmdmod][ERROR   ][16409] Command 'while true; do salt-call saltutil.running|grep fun: && continue; salt-call --local service.restart salt-minion; break; done' failed with return code: None
2017-11-13 08:46:24,165 [salt.state       ][ERROR   ][24247] No contents found in top file
2017-11-13 08:46:27,937 [salt.loaded.int.module.cmdmod][INFO    ][24545] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-13 08:46:27,944 [salt.loaded.int.module.cmdmod][INFO    ][24545] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-13 08:46:27,956 [salt.loaded.int.module.cmdmod][INFO    ][24545] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-11-13 08:46:27,965 [salt.utils.parsers][WARNING ][3260] Minion received a SIGTERM. Exiting.
2017-11-13 08:46:28,275 [salt.cli.daemons ][INFO    ][24652] Setting up the Salt Minion "kvm03.baremetal-mcp-ocata-odl-ha.local"
2017-11-13 08:46:28,920 [salt.minion      ][INFO    ][24652] Creating minion process manager
2017-11-13 08:46:28,920 [salt.cli.daemons ][WARNING ][24652] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-13 08:46:28,920 [salt.cli.daemons ][INFO    ][24652] The Salt Minion is starting up
2017-11-13 08:46:28,920 [salt.minion      ][INFO    ][24652] Minion is starting as user 'root'
2017-11-13 08:46:28,921 [salt.utils.event ][INFO    ][24652] Starting pull socket on /var/run/salt/minion/minion_event_2d6d7b82d1_pull.ipc
2017-11-13 08:46:30,045 [salt.loaded.int.module.cmdmod][INFO    ][24652] Executing command ['date', '+%z'] in directory '/root'
2017-11-13 08:46:30,055 [salt.utils.schedule][INFO    ][24652] Updating job settings for scheduled job: __mine_interval
2017-11-13 08:46:30,058 [salt.minion      ][INFO    ][24652] Added mine.update to scheduler
2017-11-13 08:46:30,076 [salt.minion      ][INFO    ][24652] Minion is ready to receive requests!
2017-11-13 08:46:31,078 [salt.utils.schedule][INFO    ][24652] Running scheduled job: __mine_interval
2017-11-13 08:46:42,630 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command state.sls with jid 20171113084642623782
2017-11-13 08:46:42,640 [salt.minion      ][INFO    ][24778] Starting a new job with PID 24778
2017-11-13 08:46:45,573 [salt.state       ][INFO    ][24778] Loading fresh modules for state activity
2017-11-13 08:46:45,602 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-11-13 08:46:45,624 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-11-13 08:46:45,652 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-13 08:46:45,772 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-11-13 08:46:45,793 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-11-13 08:46:45,825 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-13 08:46:45,888 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-13 08:46:45,907 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-13 08:46:45,966 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-13 08:46:45,984 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-13 08:46:45,996 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 08:46:46,009 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-13 08:46:46,018 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-13 08:46:46,038 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-13 08:46:46,057 [py.warnings      ][WARNING ][24778] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2017-11-13 08:46:46,057 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'salt-minion --version' in directory '/root'
2017-11-13 08:46:46,323 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-13 08:46:46,341 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-13 08:46:46,353 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-13 08:46:46,598 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-13 08:46:46,648 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-13 08:46:46,681 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-13 08:46:46,746 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-11-13 08:46:46,767 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-13 08:46:46,828 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-13 08:46:46,846 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-13 08:46:46,899 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-13 08:46:46,918 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-13 08:46:46,929 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 08:46:46,943 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-13 08:46:46,952 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-13 08:46:46,971 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-13 08:46:46,991 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'salt-minion --version' in directory '/root'
2017-11-13 08:46:47,245 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-13 08:46:47,267 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-13 08:46:47,279 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-13 08:46:47,290 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-13 08:46:47,345 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-13 08:46:47,382 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-13 08:46:47,460 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-11-13 08:46:47,531 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-13 08:46:47,892 [salt.state       ][INFO    ][24778] Running state [salt-minion] at time 08:46:47.891527
2017-11-13 08:46:47,892 [salt.state       ][INFO    ][24778] Executing state pkg.installed for salt-minion
2017-11-13 08:46:47,892 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 08:46:48,161 [salt.state       ][INFO    ][24778] Package salt-minion is already installed
2017-11-13 08:46:48,161 [salt.state       ][INFO    ][24778] Completed state [salt-minion] at time 08:46:48.161055 duration_in_ms=269.528
2017-11-13 08:46:48,161 [salt.state       ][INFO    ][24778] Running state [salt_minion_dependency_packages] at time 08:46:48.161334
2017-11-13 08:46:48,162 [salt.state       ][INFO    ][24778] Executing state pkg.installed for salt_minion_dependency_packages
2017-11-13 08:46:48,166 [salt.state       ][INFO    ][24778] All specified packages are already installed
2017-11-13 08:46:48,166 [salt.state       ][INFO    ][24778] Completed state [salt_minion_dependency_packages] at time 08:46:48.165747 duration_in_ms=4.413
2017-11-13 08:46:48,167 [salt.state       ][INFO    ][24778] Running state [/etc/salt/minion.d/minion.conf] at time 08:46:48.167454
2017-11-13 08:46:48,168 [salt.state       ][INFO    ][24778] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-11-13 08:46:48,192 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-11-13 08:46:48,249 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-13 08:46:48,304 [salt.fileclient  ][INFO    ][24778] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-13 08:46:48,355 [salt.state       ][INFO    ][24778] File /etc/salt/minion.d/minion.conf is in the correct state
2017-11-13 08:46:48,355 [salt.state       ][INFO    ][24778] Completed state [/etc/salt/minion.d/minion.conf] at time 08:46:48.354823 duration_in_ms=187.369
2017-11-13 08:46:48,355 [salt.state       ][INFO    ][24778] Running state [/etc/salt/minion.d/_virt.conf] at time 08:46:48.355314
2017-11-13 08:46:48,356 [salt.state       ][INFO    ][24778] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-11-13 08:46:48,356 [salt.state       ][INFO    ][24778] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-11-13 08:46:48,357 [salt.state       ][INFO    ][24778] Completed state [/etc/salt/minion.d/_virt.conf] at time 08:46:48.356527 duration_in_ms=1.213
2017-11-13 08:46:48,358 [salt.state       ][INFO    ][24778] Running state [salt-minion] at time 08:46:48.358199
2017-11-13 08:46:48,358 [salt.state       ][INFO    ][24778] Executing state service.running for salt-minion
2017-11-13 08:46:48,359 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-13 08:46:48,372 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-11-13 08:46:48,377 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-13 08:46:48,383 [salt.state       ][INFO    ][24778] The service salt-minion is already running
2017-11-13 08:46:48,383 [salt.state       ][INFO    ][24778] Completed state [salt-minion] at time 08:46:48.383056 duration_in_ms=24.857
2017-11-13 08:46:48,385 [salt.state       ][INFO    ][24778] Running state [/etc/salt/grains.d] at time 08:46:48.384652
2017-11-13 08:46:48,385 [salt.state       ][INFO    ][24778] Executing state file.directory for /etc/salt/grains.d
2017-11-13 08:46:48,386 [salt.state       ][INFO    ][24778] Directory /etc/salt/grains.d is in the correct state
2017-11-13 08:46:48,386 [salt.state       ][INFO    ][24778] Completed state [/etc/salt/grains.d] at time 08:46:48.385661 duration_in_ms=1.009
2017-11-13 08:46:48,386 [salt.state       ][INFO    ][24778] Running state [/etc/salt/grains] at time 08:46:48.386240
2017-11-13 08:46:48,386 [salt.state       ][INFO    ][24778] Executing state file.managed for /etc/salt/grains
2017-11-13 08:46:48,387 [salt.state       ][INFO    ][24778] File /etc/salt/grains exists with proper permissions. No changes made.
2017-11-13 08:46:48,387 [salt.state       ][INFO    ][24778] Completed state [/etc/salt/grains] at time 08:46:48.386989 duration_in_ms=0.748
2017-11-13 08:46:48,387 [salt.state       ][INFO    ][24778] Running state [/etc/salt/grains.d/placeholder] at time 08:46:48.387440
2017-11-13 08:46:48,388 [salt.state       ][INFO    ][24778] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-11-13 08:46:48,388 [salt.state       ][INFO    ][24778] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-11-13 08:46:48,388 [salt.state       ][INFO    ][24778] Completed state [/etc/salt/grains.d/placeholder] at time 08:46:48.388200 duration_in_ms=0.759
2017-11-13 08:46:48,389 [salt.state       ][INFO    ][24778] Running state [/etc/salt/grains.d/sphinx] at time 08:46:48.388573
2017-11-13 08:46:48,389 [salt.state       ][INFO    ][24778] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-11-13 08:46:48,390 [salt.state       ][INFO    ][24778] File changed:
--- 
+++ 
@@ -49,7 +49,6 @@
               - 127.0.0.1
               - 192.168.10.143
               - 192.168.11.251
-              - 192.168.122.1
         system:
           name: System
           param:

2017-11-13 08:46:48,390 [salt.state       ][INFO    ][24778] Completed state [/etc/salt/grains.d/sphinx] at time 08:46:48.390353 duration_in_ms=1.78
2017-11-13 08:46:48,391 [salt.state       ][INFO    ][24778] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 08:46:48.391123
2017-11-13 08:46:48,391 [salt.state       ][INFO    ][24778] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-13 08:46:48,392 [salt.state       ][INFO    ][24778] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-13 08:46:48,392 [salt.state       ][INFO    ][24778] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 08:46:48.391741 duration_in_ms=0.618
2017-11-13 08:46:48,392 [salt.state       ][INFO    ][24778] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 08:46:48.391895
2017-11-13 08:46:48,392 [salt.state       ][INFO    ][24778] Executing state cmd.mod_watch for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-13 08:46:48,393 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"' in directory '/root'
2017-11-13 08:46:48,470 [salt.state       ][INFO    ][24778] {'pid': 24826, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-13 08:46:48,471 [salt.state       ][INFO    ][24778] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 08:46:48.470618 duration_in_ms=78.723
2017-11-13 08:46:48,471 [salt.state       ][INFO    ][24778] Running state [/etc/salt/grains.d/dns_records] at time 08:46:48.471259
2017-11-13 08:46:48,472 [salt.state       ][INFO    ][24778] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-11-13 08:46:48,473 [salt.state       ][INFO    ][24778] File /etc/salt/grains.d/dns_records is in the correct state
2017-11-13 08:46:48,473 [salt.state       ][INFO    ][24778] Completed state [/etc/salt/grains.d/dns_records] at time 08:46:48.472686 duration_in_ms=1.427
2017-11-13 08:46:48,474 [salt.state       ][INFO    ][24778] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 08:46:48.473550
2017-11-13 08:46:48,474 [salt.state       ][INFO    ][24778] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-13 08:46:48,474 [salt.state       ][INFO    ][24778] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-13 08:46:48,474 [salt.state       ][INFO    ][24778] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 08:46:48.474166 duration_in_ms=0.616
2017-11-13 08:46:48,475 [salt.state       ][INFO    ][24778] Running state [/etc/salt/grains.d/salt] at time 08:46:48.474535
2017-11-13 08:46:48,475 [salt.state       ][INFO    ][24778] Executing state file.managed for /etc/salt/grains.d/salt
2017-11-13 08:46:48,476 [salt.state       ][INFO    ][24778] File /etc/salt/grains.d/salt is in the correct state
2017-11-13 08:46:48,476 [salt.state       ][INFO    ][24778] Completed state [/etc/salt/grains.d/salt] at time 08:46:48.475661 duration_in_ms=1.125
2017-11-13 08:46:48,476 [salt.state       ][INFO    ][24778] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 08:46:48.476427
2017-11-13 08:46:48,477 [salt.state       ][INFO    ][24778] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-13 08:46:48,477 [salt.state       ][INFO    ][24778] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-13 08:46:48,477 [salt.state       ][INFO    ][24778] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 08:46:48.477047 duration_in_ms=0.619
2017-11-13 08:46:48,479 [salt.state       ][INFO    ][24778] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 08:46:48.478641
2017-11-13 08:46:48,479 [salt.state       ][INFO    ][24778] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-13 08:46:48,479 [salt.state       ][INFO    ][24778] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-13 08:46:48,479 [salt.state       ][INFO    ][24778] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 08:46:48.479309 duration_in_ms=0.667
2017-11-13 08:46:48,480 [salt.state       ][INFO    ][24778] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 08:46:48.479500
2017-11-13 08:46:48,480 [salt.state       ][INFO    ][24778] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-13 08:46:48,481 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-11-13 08:46:48,487 [salt.state       ][INFO    ][24778] {'pid': 24828, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-13 08:46:48,487 [salt.state       ][INFO    ][24778] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 08:46:48.487278 duration_in_ms=7.778
2017-11-13 08:46:48,488 [salt.state       ][INFO    ][24778] Running state [mine.update] at time 08:46:48.487996
2017-11-13 08:46:48,488 [salt.state       ][INFO    ][24778] Executing state module.wait for mine.update
2017-11-13 08:46:48,488 [salt.state       ][INFO    ][24778] No changes made for mine.update
2017-11-13 08:46:48,489 [salt.state       ][INFO    ][24778] Completed state [mine.update] at time 08:46:48.488602 duration_in_ms=0.605
2017-11-13 08:46:48,489 [salt.state       ][INFO    ][24778] Running state [mine.update] at time 08:46:48.488768
2017-11-13 08:46:48,489 [salt.state       ][INFO    ][24778] Executing state module.mod_watch for mine.update
2017-11-13 08:46:49,003 [salt.state       ][INFO    ][24778] {'ret': True}
2017-11-13 08:46:49,004 [salt.state       ][INFO    ][24778] Completed state [mine.update] at time 08:46:49.003617 duration_in_ms=514.848
2017-11-13 08:46:49,004 [salt.state       ][INFO    ][24778] Running state [ca-certificates] at time 08:46:49.003933
2017-11-13 08:46:49,004 [salt.state       ][INFO    ][24778] Executing state pkg.installed for ca-certificates
2017-11-13 08:46:49,008 [salt.state       ][INFO    ][24778] Package ca-certificates is already installed
2017-11-13 08:46:49,008 [salt.state       ][INFO    ][24778] Completed state [ca-certificates] at time 08:46:49.008398 duration_in_ms=4.465
2017-11-13 08:46:49,009 [salt.state       ][INFO    ][24778] Running state [update-ca-certificates] at time 08:46:49.009087
2017-11-13 08:46:49,009 [salt.state       ][INFO    ][24778] Executing state cmd.wait for update-ca-certificates
2017-11-13 08:46:49,010 [salt.state       ][INFO    ][24778] No changes made for update-ca-certificates
2017-11-13 08:46:49,010 [salt.state       ][INFO    ][24778] Completed state [update-ca-certificates] at time 08:46:49.009666 duration_in_ms=0.578
2017-11-13 08:46:49,010 [salt.state       ][INFO    ][24778] Running state [libvirt-dev] at time 08:46:49.009840
2017-11-13 08:46:49,010 [salt.state       ][INFO    ][24778] Executing state pkg.installed for libvirt-dev
2017-11-13 08:46:49,014 [salt.state       ][INFO    ][24778] Package libvirt-dev is already installed
2017-11-13 08:46:49,014 [salt.state       ][INFO    ][24778] Completed state [libvirt-dev] at time 08:46:49.013889 duration_in_ms=4.049
2017-11-13 08:46:49,014 [salt.state       ][INFO    ][24778] Running state [pkg-config] at time 08:46:49.014066
2017-11-13 08:46:49,014 [salt.state       ][INFO    ][24778] Executing state pkg.installed for pkg-config
2017-11-13 08:46:49,018 [salt.state       ][INFO    ][24778] Package pkg-config is already installed
2017-11-13 08:46:49,018 [salt.state       ][INFO    ][24778] Completed state [pkg-config] at time 08:46:49.018042 duration_in_ms=3.976
2017-11-13 08:46:49,018 [salt.state       ][INFO    ][24778] Running state [virtng.init] at time 08:46:49.018233
2017-11-13 08:46:49,018 [salt.state       ][INFO    ][24778] Executing state module.run for virtng.init
2017-11-13 08:46:49,072 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command ['git', '--version'] in directory '/root'
2017-11-13 08:46:49,155 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'virsh list --all --name| grep -E "^mdb03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:49,171 [salt.state       ][INFO    ][24778] unless execution succeeded
2017-11-13 08:46:49,172 [salt.state       ][INFO    ][24778] Completed state [virtng.init] at time 08:46:49.171708 duration_in_ms=153.474
2017-11-13 08:46:49,172 [salt.state       ][INFO    ][24778] Running state [virt.set_autostart] at time 08:46:49.172007
2017-11-13 08:46:49,172 [salt.state       ][INFO    ][24778] Executing state module.run for virt.set_autostart
2017-11-13 08:46:49,175 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'virsh list --autostart --name| grep -E "^mdb03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:49,190 [salt.state       ][INFO    ][24778] unless execution succeeded
2017-11-13 08:46:49,191 [salt.state       ][INFO    ][24778] Completed state [virt.set_autostart] at time 08:46:49.190583 duration_in_ms=18.576
2017-11-13 08:46:49,191 [salt.state       ][INFO    ][24778] Running state [virtng.init] at time 08:46:49.190862
2017-11-13 08:46:49,191 [salt.state       ][INFO    ][24778] Executing state module.run for virtng.init
2017-11-13 08:46:49,194 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'virsh list --all --name| grep -E "^dbs03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:49,208 [salt.state       ][INFO    ][24778] unless execution succeeded
2017-11-13 08:46:49,209 [salt.state       ][INFO    ][24778] Completed state [virtng.init] at time 08:46:49.208505 duration_in_ms=17.642
2017-11-13 08:46:49,209 [salt.state       ][INFO    ][24778] Running state [virt.set_autostart] at time 08:46:49.208763
2017-11-13 08:46:49,209 [salt.state       ][INFO    ][24778] Executing state module.run for virt.set_autostart
2017-11-13 08:46:49,212 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'virsh list --autostart --name| grep -E "^dbs03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:49,227 [salt.state       ][INFO    ][24778] unless execution succeeded
2017-11-13 08:46:49,227 [salt.state       ][INFO    ][24778] Completed state [virt.set_autostart] at time 08:46:49.226853 duration_in_ms=18.09
2017-11-13 08:46:49,227 [salt.state       ][INFO    ][24778] Running state [virtng.init] at time 08:46:49.227144
2017-11-13 08:46:49,227 [salt.state       ][INFO    ][24778] Executing state module.run for virtng.init
2017-11-13 08:46:49,230 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'virsh list --all --name| grep -E "^prx02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:49,244 [salt.state       ][INFO    ][24778] unless execution succeeded
2017-11-13 08:46:49,245 [salt.state       ][INFO    ][24778] Completed state [virtng.init] at time 08:46:49.244533 duration_in_ms=17.389
2017-11-13 08:46:49,245 [salt.state       ][INFO    ][24778] Running state [virt.set_autostart] at time 08:46:49.244792
2017-11-13 08:46:49,245 [salt.state       ][INFO    ][24778] Executing state module.run for virt.set_autostart
2017-11-13 08:46:49,248 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'virsh list --autostart --name| grep -E "^prx02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:49,263 [salt.state       ][INFO    ][24778] unless execution succeeded
2017-11-13 08:46:49,263 [salt.state       ][INFO    ][24778] Completed state [virt.set_autostart] at time 08:46:49.263434 duration_in_ms=18.642
2017-11-13 08:46:49,264 [salt.state       ][INFO    ][24778] Running state [virtng.init] at time 08:46:49.263703
2017-11-13 08:46:49,264 [salt.state       ][INFO    ][24778] Executing state module.run for virtng.init
2017-11-13 08:46:49,267 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'virsh list --all --name| grep -E "^odl01.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:49,281 [salt.state       ][INFO    ][24778] unless execution succeeded
2017-11-13 08:46:49,282 [salt.state       ][INFO    ][24778] Completed state [virtng.init] at time 08:46:49.281546 duration_in_ms=17.843
2017-11-13 08:46:49,282 [salt.state       ][INFO    ][24778] Running state [virt.set_autostart] at time 08:46:49.281815
2017-11-13 08:46:49,282 [salt.state       ][INFO    ][24778] Executing state module.run for virt.set_autostart
2017-11-13 08:46:49,285 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'virsh list --autostart --name| grep -E "^odl01.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:49,300 [salt.state       ][INFO    ][24778] unless execution succeeded
2017-11-13 08:46:49,300 [salt.state       ][INFO    ][24778] Completed state [virt.set_autostart] at time 08:46:49.300317 duration_in_ms=18.502
2017-11-13 08:46:49,301 [salt.state       ][INFO    ][24778] Running state [virtng.init] at time 08:46:49.300587
2017-11-13 08:46:49,301 [salt.state       ][INFO    ][24778] Executing state module.run for virtng.init
2017-11-13 08:46:49,304 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'virsh list --all --name| grep -E "^msg03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:49,318 [salt.state       ][INFO    ][24778] unless execution succeeded
2017-11-13 08:46:49,319 [salt.state       ][INFO    ][24778] Completed state [virtng.init] at time 08:46:49.318481 duration_in_ms=17.894
2017-11-13 08:46:49,319 [salt.state       ][INFO    ][24778] Running state [virt.set_autostart] at time 08:46:49.318744
2017-11-13 08:46:49,319 [salt.state       ][INFO    ][24778] Executing state module.run for virt.set_autostart
2017-11-13 08:46:49,322 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'virsh list --autostart --name| grep -E "^msg03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:49,337 [salt.state       ][INFO    ][24778] unless execution succeeded
2017-11-13 08:46:49,337 [salt.state       ][INFO    ][24778] Completed state [virt.set_autostart] at time 08:46:49.336996 duration_in_ms=18.252
2017-11-13 08:46:49,337 [salt.state       ][INFO    ][24778] Running state [virtng.init] at time 08:46:49.337268
2017-11-13 08:46:49,338 [salt.state       ][INFO    ][24778] Executing state module.run for virtng.init
2017-11-13 08:46:49,340 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'virsh list --all --name| grep -E "^ctl03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:49,356 [salt.state       ][INFO    ][24778] unless execution succeeded
2017-11-13 08:46:49,356 [salt.state       ][INFO    ][24778] Completed state [virtng.init] at time 08:46:49.355871 duration_in_ms=18.603
2017-11-13 08:46:49,356 [salt.state       ][INFO    ][24778] Running state [virt.set_autostart] at time 08:46:49.356144
2017-11-13 08:46:49,356 [salt.state       ][INFO    ][24778] Executing state module.run for virt.set_autostart
2017-11-13 08:46:49,359 [salt.loaded.int.module.cmdmod][INFO    ][24778] Executing command 'virsh list --autostart --name| grep -E "^ctl03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:49,374 [salt.state       ][INFO    ][24778] unless execution succeeded
2017-11-13 08:46:49,374 [salt.state       ][INFO    ][24778] Completed state [virt.set_autostart] at time 08:46:49.373988 duration_in_ms=17.844
2017-11-13 08:46:49,377 [salt.minion      ][INFO    ][24778] Returning information for job: 20171113084642623782
2017-11-13 08:47:17,658 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171113084717651230
2017-11-13 08:47:17,674 [salt.minion      ][INFO    ][24904] Starting a new job with PID 24904
2017-11-13 08:47:22,070 [salt.state       ][INFO    ][24904] Loading fresh modules for state activity
2017-11-13 08:47:22,182 [salt.state       ][ERROR   ][24904] No contents found in top file
2017-11-13 08:47:22,182 [salt.utils.extmods][INFO    ][24904] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-11-13 08:47:22,186 [salt.utils.extmods][INFO    ][24904] Syncing beacons for environment 'base'
2017-11-13 08:47:22,186 [salt.utils.extmods][INFO    ][24904] Loading cache from salt://_beacons, for base)
2017-11-13 08:47:22,186 [salt.fileclient  ][INFO    ][24904] Caching directory '_beacons/' for environment 'base'
2017-11-13 08:47:22,260 [salt.utils.extmods][INFO    ][24904] Syncing modules for environment 'base'
2017-11-13 08:47:22,260 [salt.utils.extmods][INFO    ][24904] Loading cache from salt://_modules, for base)
2017-11-13 08:47:22,260 [salt.fileclient  ][INFO    ][24904] Caching directory '_modules/' for environment 'base'
2017-11-13 08:47:22,856 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-11-13 08:47:22,870 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-11-13 08:47:22,897 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-11-13 08:47:22,908 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-11-13 08:47:22,924 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-11-13 08:47:22,941 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-11-13 08:47:22,963 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-11-13 08:47:22,984 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-11-13 08:47:22,998 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-11-13 08:47:23,012 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-11-13 08:47:23,027 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-11-13 08:47:23,058 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-11-13 08:47:23,077 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-11-13 08:47:23,090 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-11-13 08:47:23,112 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-11-13 08:47:23,127 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-11-13 08:47:23,141 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-11-13 08:47:23,157 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-11-13 08:47:23,180 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-11-13 08:47:23,217 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-11-13 08:47:23,234 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-11-13 08:47:23,258 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-11-13 08:47:23,280 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-11-13 08:47:23,298 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-11-13 08:47:23,318 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-11-13 08:47:23,334 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-11-13 08:47:23,363 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-11-13 08:47:23,392 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-11-13 08:47:23,414 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-11-13 08:47:23,425 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-11-13 08:47:23,438 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-11-13 08:47:23,454 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-11-13 08:47:23,479 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-11-13 08:47:23,483 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-11-13 08:47:23,483 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-11-13 08:47:23,484 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-11-13 08:47:23,484 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-11-13 08:47:23,484 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-11-13 08:47:23,485 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-11-13 08:47:23,485 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-11-13 08:47:23,485 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-11-13 08:47:23,485 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-11-13 08:47:23,486 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-11-13 08:47:23,486 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-11-13 08:47:23,486 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-11-13 08:47:23,487 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-11-13 08:47:23,487 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-11-13 08:47:23,487 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-11-13 08:47:23,487 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-11-13 08:47:23,488 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-11-13 08:47:23,488 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-11-13 08:47:23,488 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-11-13 08:47:23,488 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-11-13 08:47:23,489 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-11-13 08:47:23,489 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-11-13 08:47:23,489 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-11-13 08:47:23,490 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-11-13 08:47:23,490 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-11-13 08:47:23,490 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-11-13 08:47:23,491 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-11-13 08:47:23,491 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-11-13 08:47:23,491 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-11-13 08:47:23,491 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-11-13 08:47:23,492 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-11-13 08:47:23,492 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-11-13 08:47:23,492 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/testing/django_client_proxy.py' to '/var/cache/salt/minion/extmods/modules/testing/django_client_proxy.py'
2017-11-13 08:47:23,492 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-11-13 08:47:23,493 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-11-13 08:47:23,498 [salt.utils.extmods][INFO    ][24904] Syncing states for environment 'base'
2017-11-13 08:47:23,499 [salt.utils.extmods][INFO    ][24904] Loading cache from salt://_states, for base)
2017-11-13 08:47:23,499 [salt.fileclient  ][INFO    ][24904] Caching directory '_states/' for environment 'base'
2017-11-13 08:47:23,636 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-11-13 08:47:23,709 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-11-13 08:47:23,723 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-11-13 08:47:23,754 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-11-13 08:47:23,765 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-11-13 08:47:23,777 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-11-13 08:47:23,788 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-11-13 08:47:23,836 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-11-13 08:47:23,853 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-11-13 08:47:23,867 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-11-13 08:47:23,902 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-11-13 08:47:23,921 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-11-13 08:47:23,953 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-11-13 08:47:23,963 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-11-13 08:47:23,984 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-11-13 08:47:23,994 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-11-13 08:47:24,019 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-11-13 08:47:24,028 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-11-13 08:47:24,091 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-11-13 08:47:24,113 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-11-13 08:47:24,124 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-11-13 08:47:24,201 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-11-13 08:47:24,270 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-11-13 08:47:24,301 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-11-13 08:47:24,315 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-11-13 08:47:24,341 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-11-13 08:47:24,354 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-11-13 08:47:24,369 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-11-13 08:47:24,390 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-11-13 08:47:24,395 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-11-13 08:47:24,395 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-11-13 08:47:24,395 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-11-13 08:47:24,395 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-11-13 08:47:24,396 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-11-13 08:47:24,396 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-11-13 08:47:24,396 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-11-13 08:47:24,397 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-11-13 08:47:24,397 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-11-13 08:47:24,397 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-11-13 08:47:24,397 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-11-13 08:47:24,398 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-11-13 08:47:24,398 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-11-13 08:47:24,398 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-11-13 08:47:24,398 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-11-13 08:47:24,399 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-11-13 08:47:24,399 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-11-13 08:47:24,399 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-11-13 08:47:24,399 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-11-13 08:47:24,400 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-11-13 08:47:24,400 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-11-13 08:47:24,400 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-11-13 08:47:24,400 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-11-13 08:47:24,401 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-11-13 08:47:24,401 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-11-13 08:47:24,401 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-11-13 08:47:24,402 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-11-13 08:47:24,402 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-11-13 08:47:24,402 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-11-13 08:47:24,402 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-11-13 08:47:24,405 [salt.utils.extmods][INFO    ][24904] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-11-13 08:47:24,408 [salt.utils.extmods][INFO    ][24904] Syncing sdb for environment 'base'
2017-11-13 08:47:24,408 [salt.utils.extmods][INFO    ][24904] Loading cache from salt://_sdb, for base)
2017-11-13 08:47:24,408 [salt.fileclient  ][INFO    ][24904] Caching directory '_sdb/' for environment 'base'
2017-11-13 08:47:24,484 [salt.utils.extmods][INFO    ][24904] Syncing grains for environment 'base'
2017-11-13 08:47:24,484 [salt.utils.extmods][INFO    ][24904] Loading cache from salt://_grains, for base)
2017-11-13 08:47:24,484 [salt.fileclient  ][INFO    ][24904] Caching directory '_grains/' for environment 'base'
2017-11-13 08:47:24,556 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-11-13 08:47:24,570 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-11-13 08:47:24,597 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-11-13 08:47:24,617 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-11-13 08:47:24,628 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-11-13 08:47:24,643 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-11-13 08:47:24,657 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-11-13 08:47:24,694 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-11-13 08:47:24,709 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-11-13 08:47:24,723 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-11-13 08:47:24,736 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-11-13 08:47:24,775 [salt.fileclient  ][INFO    ][24904] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-11-13 08:47:24,779 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-11-13 08:47:24,779 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-11-13 08:47:24,779 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-11-13 08:47:24,779 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-11-13 08:47:24,780 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-11-13 08:47:24,780 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-11-13 08:47:24,780 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-11-13 08:47:24,780 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-11-13 08:47:24,781 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-11-13 08:47:24,781 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-11-13 08:47:24,781 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-11-13 08:47:24,781 [salt.utils.extmods][INFO    ][24904] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-11-13 08:47:24,783 [salt.utils.extmods][INFO    ][24904] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-11-13 08:47:24,786 [salt.utils.extmods][INFO    ][24904] Syncing renderers for environment 'base'
2017-11-13 08:47:24,786 [salt.utils.extmods][INFO    ][24904] Loading cache from salt://_renderers, for base)
2017-11-13 08:47:24,786 [salt.fileclient  ][INFO    ][24904] Caching directory '_renderers/' for environment 'base'
2017-11-13 08:47:24,847 [salt.utils.extmods][INFO    ][24904] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-11-13 08:47:24,851 [salt.utils.extmods][INFO    ][24904] Syncing returners for environment 'base'
2017-11-13 08:47:24,851 [salt.utils.extmods][INFO    ][24904] Loading cache from salt://_returners, for base)
2017-11-13 08:47:24,851 [salt.fileclient  ][INFO    ][24904] Caching directory '_returners/' for environment 'base'
2017-11-13 08:47:24,965 [salt.utils.extmods][INFO    ][24904] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-11-13 08:47:24,969 [salt.utils.extmods][INFO    ][24904] Syncing output for environment 'base'
2017-11-13 08:47:24,969 [salt.utils.extmods][INFO    ][24904] Loading cache from salt://_output, for base)
2017-11-13 08:47:24,969 [salt.fileclient  ][INFO    ][24904] Caching directory '_output/' for environment 'base'
2017-11-13 08:47:25,014 [salt.utils.extmods][INFO    ][24904] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-11-13 08:47:25,017 [salt.utils.extmods][INFO    ][24904] Syncing utils for environment 'base'
2017-11-13 08:47:25,017 [salt.utils.extmods][INFO    ][24904] Loading cache from salt://_utils, for base)
2017-11-13 08:47:25,017 [salt.fileclient  ][INFO    ][24904] Caching directory '_utils/' for environment 'base'
2017-11-13 08:47:25,060 [salt.utils.extmods][INFO    ][24904] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-11-13 08:47:25,063 [salt.utils.extmods][INFO    ][24904] Syncing log_handlers for environment 'base'
2017-11-13 08:47:25,063 [salt.utils.extmods][INFO    ][24904] Loading cache from salt://_log_handlers, for base)
2017-11-13 08:47:25,063 [salt.fileclient  ][INFO    ][24904] Caching directory '_log_handlers/' for environment 'base'
2017-11-13 08:47:25,141 [salt.utils.extmods][INFO    ][24904] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-11-13 08:47:25,145 [salt.utils.extmods][INFO    ][24904] Syncing proxy for environment 'base'
2017-11-13 08:47:25,145 [salt.utils.extmods][INFO    ][24904] Loading cache from salt://_proxy, for base)
2017-11-13 08:47:25,145 [salt.fileclient  ][INFO    ][24904] Caching directory '_proxy/' for environment 'base'
2017-11-13 08:47:25,190 [salt.utils.extmods][INFO    ][24904] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-11-13 08:47:25,195 [salt.utils.extmods][INFO    ][24904] Syncing engines for environment 'base'
2017-11-13 08:47:25,196 [salt.utils.extmods][INFO    ][24904] Loading cache from salt://_engines, for base)
2017-11-13 08:47:25,196 [salt.fileclient  ][INFO    ][24904] Caching directory '_engines/' for environment 'base'
2017-11-13 08:47:25,242 [salt.minion      ][INFO    ][24904] Returning information for job: 20171113084717651230
2017-11-13 08:57:33,292 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command test.ping with jid 20171113085733284742
2017-11-13 08:57:33,307 [salt.minion      ][INFO    ][25182] Starting a new job with PID 25182
2017-11-13 08:57:33,345 [salt.minion      ][INFO    ][25182] Returning information for job: 20171113085733284742
2017-11-13 08:57:42,444 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command state.sls with jid 20171113085742436998
2017-11-13 08:57:42,454 [salt.minion      ][INFO    ][25189] Starting a new job with PID 25189
2017-11-13 08:57:43,075 [salt.state       ][INFO    ][25189] Loading fresh modules for state activity
2017-11-13 08:57:43,103 [salt.fileclient  ][INFO    ][25189] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-13 08:57:43,120 [salt.fileclient  ][INFO    ][25189] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-13 08:57:43,148 [salt.fileclient  ][INFO    ][25189] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 08:57:43,477 [salt.state       ][INFO    ][25189] Running state [lsof] at time 08:57:43.476819
2017-11-13 08:57:43,477 [salt.state       ][INFO    ][25189] Executing state pkg.installed for lsof
2017-11-13 08:57:43,477 [salt.loaded.int.module.cmdmod][INFO    ][25189] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 08:57:43,728 [salt.state       ][INFO    ][25189] Package lsof is already installed
2017-11-13 08:57:43,728 [salt.state       ][INFO    ][25189] Completed state [lsof] at time 08:57:43.728077 duration_in_ms=251.258
2017-11-13 08:57:43,728 [salt.state       ][INFO    ][25189] Running state [keepalived] at time 08:57:43.728303
2017-11-13 08:57:43,729 [salt.state       ][INFO    ][25189] Executing state pkg.installed for keepalived
2017-11-13 08:57:43,745 [salt.loaded.int.module.cmdmod][INFO    ][25189] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-13 08:57:45,758 [salt.loaded.int.module.cmdmod][INFO    ][25189] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'keepalived'] in directory '/root'
2017-11-13 08:57:48,432 [salt.loaded.int.module.cmdmod][INFO    ][25189] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 08:57:48,456 [salt.state       ][INFO    ][25189] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4'
'keepalived' changed from 'absent' to '1:1.2.19-1ubuntu0.2'
'ipvsadm' changed from 'absent' to '1:1.28-3'
'libsensors4' changed from 'absent' to '1:3.4.0-2'

2017-11-13 08:57:48,467 [salt.state       ][INFO    ][25189] Loading fresh modules for state activity
2017-11-13 08:57:48,482 [salt.state       ][INFO    ][25189] Completed state [keepalived] at time 08:57:48.482427 duration_in_ms=4754.124
2017-11-13 08:57:48,485 [salt.state       ][INFO    ][25189] Running state [/etc/keepalived/keepalived.conf] at time 08:57:48.484474
2017-11-13 08:57:48,485 [salt.state       ][INFO    ][25189] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-13 08:57:48,510 [salt.fileclient  ][INFO    ][25189] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-13 08:57:48,548 [salt.fileclient  ][INFO    ][25189] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 08:57:48,556 [salt.state       ][INFO    ][25189] File changed:
New file
2017-11-13 08:57:48,557 [salt.state       ][INFO    ][25189] Completed state [/etc/keepalived/keepalived.conf] at time 08:57:48.556614 duration_in_ms=72.14
2017-11-13 08:57:48,626 [salt.state       ][INFO    ][25189] Running state [keepalived] at time 08:57:48.625630
2017-11-13 08:57:48,626 [salt.state       ][INFO    ][25189] Executing state service.running for keepalived
2017-11-13 08:57:48,628 [salt.loaded.int.module.cmdmod][INFO    ][25189] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-13 08:57:48,636 [salt.loaded.int.module.cmdmod][INFO    ][25189] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-13 08:57:48,643 [salt.loaded.int.module.cmdmod][INFO    ][25189] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-13 08:57:48,649 [salt.loaded.int.module.cmdmod][INFO    ][25189] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-13 08:57:48,656 [salt.loaded.int.module.cmdmod][INFO    ][25189] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-11-13 08:57:48,690 [salt.loaded.int.module.cmdmod][INFO    ][25189] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-13 08:57:48,700 [salt.loaded.int.module.cmdmod][INFO    ][25189] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-13 08:57:48,708 [salt.loaded.int.module.cmdmod][INFO    ][25189] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-13 08:57:48,713 [salt.state       ][INFO    ][25189] {'keepalived': True}
2017-11-13 08:57:48,714 [salt.state       ][INFO    ][25189] Completed state [keepalived] at time 08:57:48.713734 duration_in_ms=88.103
2017-11-13 08:57:48,715 [salt.minion      ][INFO    ][25189] Returning information for job: 20171113085742436998
2017-11-13 08:59:46,322 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command pillar.get with jid 20171113085946315696
2017-11-13 08:59:46,340 [salt.minion      ][INFO    ][26828] Starting a new job with PID 26828
2017-11-13 08:59:46,344 [salt.minion      ][INFO    ][26828] Returning information for job: 20171113085946315696
2017-11-13 09:05:14,222 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command state.sls with jid 20171113090514215274
2017-11-13 09:05:14,234 [salt.minion      ][INFO    ][26956] Starting a new job with PID 26956
2017-11-13 09:05:16,880 [salt.state       ][INFO    ][26956] Loading fresh modules for state activity
2017-11-13 09:05:16,908 [salt.fileclient  ][INFO    ][26956] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-13 09:05:16,930 [salt.fileclient  ][INFO    ][26956] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 09:05:17,238 [salt.state       ][INFO    ][26956] Running state [attr] at time 09:05:17.238278
2017-11-13 09:05:17,239 [salt.state       ][INFO    ][26956] Executing state pkg.installed for attr
2017-11-13 09:05:17,239 [salt.loaded.int.module.cmdmod][INFO    ][26956] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 09:05:17,519 [salt.loaded.int.module.cmdmod][INFO    ][26956] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-13 09:05:19,452 [salt.loaded.int.module.cmdmod][INFO    ][26956] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'attr'] in directory '/root'
2017-11-13 09:05:20,821 [salt.loaded.int.module.cmdmod][INFO    ][26956] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 09:05:20,849 [salt.state       ][INFO    ][26956] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-11-13 09:05:20,860 [salt.state       ][INFO    ][26956] Loading fresh modules for state activity
2017-11-13 09:05:20,876 [salt.state       ][INFO    ][26956] Completed state [attr] at time 09:05:20.875948 duration_in_ms=3637.671
2017-11-13 09:05:20,882 [salt.state       ][INFO    ][26956] Running state [glusterfs-server] at time 09:05:20.881561
2017-11-13 09:05:20,882 [salt.state       ][INFO    ][26956] Executing state pkg.installed for glusterfs-server
2017-11-13 09:05:21,102 [salt.loaded.int.module.cmdmod][INFO    ][26956] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'glusterfs-server'] in directory '/root'
2017-11-13 09:05:24,333 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command saltutil.find_job with jid 20171113090524318177
2017-11-13 09:05:24,344 [salt.minion      ][INFO    ][30799] Starting a new job with PID 30799
2017-11-13 09:05:24,355 [salt.minion      ][INFO    ][30799] Returning information for job: 20171113090524318177
2017-11-13 09:05:27,188 [salt.loaded.int.module.cmdmod][INFO    ][26956] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 09:05:27,218 [salt.state       ][INFO    ][26956] Made the following changes:
'keyutils' changed from 'absent' to '1.5.9-8ubuntu1'
'nfs-common' changed from 'absent' to '1:1.2.8-9ubuntu12.1'
'rpcbind' changed from 'absent' to '0.2.3-0.2'
'glusterfs-common' changed from 'absent' to '3.7.6-1ubuntu1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'libnfsidmap2' changed from 'absent' to '0.25-5'
'portmap' changed from 'absent' to '1'
'libattr1-dev' changed from 'absent' to '1:2.4.47-2'
'attr-dev' changed from 'absent' to '1'
'acl-dev' changed from 'absent' to '1'
'glusterfs-server' changed from 'absent' to '3.7.6-1ubuntu1'
'glusterfs-client' changed from 'absent' to '3.7.6-1ubuntu1'
'libpython2.7' changed from 'absent' to '2.7.12-1ubuntu0~16.04.1'
'nfs-client' changed from 'absent' to '1'
'liburcu4' changed from 'absent' to '0.9.1-3'
'libacl1-dev' changed from 'absent' to '2.2.52-3'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libtirpc1' changed from 'absent' to '0.2.5-1'

2017-11-13 09:05:27,227 [salt.state       ][INFO    ][26956] Loading fresh modules for state activity
2017-11-13 09:05:27,244 [salt.state       ][INFO    ][26956] Completed state [glusterfs-server] at time 09:05:27.243971 duration_in_ms=6362.41
2017-11-13 09:05:27,249 [salt.state       ][INFO    ][26956] Running state [bc] at time 09:05:27.249199
2017-11-13 09:05:27,249 [salt.state       ][INFO    ][26956] Executing state pkg.installed for bc
2017-11-13 09:05:27,536 [salt.loaded.int.module.cmdmod][INFO    ][26956] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'bc'] in directory '/root'
2017-11-13 09:05:28,992 [salt.loaded.int.module.cmdmod][INFO    ][26956] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 09:05:29,021 [salt.state       ][INFO    ][26956] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-11-13 09:05:29,031 [salt.state       ][INFO    ][26956] Loading fresh modules for state activity
2017-11-13 09:05:29,047 [salt.state       ][INFO    ][26956] Completed state [bc] at time 09:05:29.046680 duration_in_ms=1797.481
2017-11-13 09:05:29,117 [salt.state       ][INFO    ][26956] Running state [glusterfs-server] at time 09:05:29.117139
2017-11-13 09:05:29,117 [salt.state       ][INFO    ][26956] Executing state service.running for glusterfs-server
2017-11-13 09:05:29,119 [salt.loaded.int.module.cmdmod][INFO    ][26956] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-13 09:05:29,129 [salt.loaded.int.module.cmdmod][INFO    ][26956] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-13 09:05:29,137 [salt.loaded.int.module.cmdmod][INFO    ][26956] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-13 09:05:29,146 [salt.state       ][INFO    ][26956] The service glusterfs-server is already running
2017-11-13 09:05:29,147 [salt.state       ][INFO    ][26956] Completed state [glusterfs-server] at time 09:05:29.146486 duration_in_ms=29.347
2017-11-13 09:05:29,148 [salt.state       ][INFO    ][26956] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:05:29.148207
2017-11-13 09:05:29,148 [salt.state       ][INFO    ][26956] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-13 09:05:29,153 [salt.state       ][INFO    ][26956] File changed:
New file
2017-11-13 09:05:29,153 [salt.state       ][INFO    ][26956] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:05:29.152997 duration_in_ms=4.79
2017-11-13 09:05:29,153 [salt.state       ][INFO    ][26956] Running state [/srv/glusterfs/glance] at time 09:05:29.153192
2017-11-13 09:05:29,153 [salt.state       ][INFO    ][26956] Executing state file.directory for /srv/glusterfs/glance
2017-11-13 09:05:29,154 [salt.state       ][INFO    ][26956] {'/srv/glusterfs/glance': 'New Dir'}
2017-11-13 09:05:29,154 [salt.state       ][INFO    ][26956] Completed state [/srv/glusterfs/glance] at time 09:05:29.154218 duration_in_ms=1.026
2017-11-13 09:05:29,154 [salt.state       ][INFO    ][26956] Running state [/srv/glusterfs/keystone-keys] at time 09:05:29.154396
2017-11-13 09:05:29,155 [salt.state       ][INFO    ][26956] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-13 09:05:29,155 [salt.state       ][INFO    ][26956] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-11-13 09:05:29,155 [salt.state       ][INFO    ][26956] Completed state [/srv/glusterfs/keystone-keys] at time 09:05:29.155251 duration_in_ms=0.855
2017-11-13 09:05:29,155 [salt.state       ][INFO    ][26956] Running state [/srv/glusterfs/nova_instances] at time 09:05:29.155438
2017-11-13 09:05:29,156 [salt.state       ][INFO    ][26956] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-13 09:05:29,156 [salt.state       ][INFO    ][26956] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-11-13 09:05:29,156 [salt.state       ][INFO    ][26956] Completed state [/srv/glusterfs/nova_instances] at time 09:05:29.156278 duration_in_ms=0.84
2017-11-13 09:05:29,156 [salt.state       ][INFO    ][26956] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:05:29.156461
2017-11-13 09:05:29,157 [salt.state       ][INFO    ][26956] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-13 09:05:29,157 [salt.state       ][INFO    ][26956] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-11-13 09:05:29,157 [salt.state       ][INFO    ][26956] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:05:29.157270 duration_in_ms=0.809
2017-11-13 09:05:29,158 [salt.minion      ][INFO    ][26956] Returning information for job: 20171113090514215274
2017-11-13 09:05:37,138 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command test.ping with jid 20171113090537130608
2017-11-13 09:05:37,148 [salt.minion      ][INFO    ][31740] Starting a new job with PID 31740
2017-11-13 09:05:37,184 [salt.minion      ][INFO    ][31740] Returning information for job: 20171113090537130608
2017-11-13 09:06:04,814 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command state.sls with jid 20171113090604802995
2017-11-13 09:06:04,826 [salt.minion      ][INFO    ][31959] Starting a new job with PID 31959
2017-11-13 09:06:07,064 [salt.state       ][INFO    ][31959] Loading fresh modules for state activity
2017-11-13 09:06:07,096 [salt.fileclient  ][INFO    ][31959] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-13 09:06:07,137 [salt.fileclient  ][INFO    ][31959] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 09:06:07,214 [salt.fileclient  ][INFO    ][31959] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-13 09:06:07,236 [salt.fileclient  ][INFO    ][31959] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 09:06:07,536 [salt.state       ][INFO    ][31959] Running state [attr] at time 09:06:07.536366
2017-11-13 09:06:07,537 [salt.state       ][INFO    ][31959] Executing state pkg.installed for attr
2017-11-13 09:06:07,537 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 09:06:07,805 [salt.state       ][INFO    ][31959] Package attr is already installed
2017-11-13 09:06:07,806 [salt.state       ][INFO    ][31959] Completed state [attr] at time 09:06:07.805464 duration_in_ms=269.097
2017-11-13 09:06:07,806 [salt.state       ][INFO    ][31959] Running state [glusterfs-server] at time 09:06:07.805729
2017-11-13 09:06:07,806 [salt.state       ][INFO    ][31959] Executing state pkg.installed for glusterfs-server
2017-11-13 09:06:07,810 [salt.state       ][INFO    ][31959] Package glusterfs-server is already installed
2017-11-13 09:06:07,810 [salt.state       ][INFO    ][31959] Completed state [glusterfs-server] at time 09:06:07.809801 duration_in_ms=4.072
2017-11-13 09:06:07,810 [salt.state       ][INFO    ][31959] Running state [bc] at time 09:06:07.809995
2017-11-13 09:06:07,810 [salt.state       ][INFO    ][31959] Executing state pkg.installed for bc
2017-11-13 09:06:07,814 [salt.state       ][INFO    ][31959] Package bc is already installed
2017-11-13 09:06:07,814 [salt.state       ][INFO    ][31959] Completed state [bc] at time 09:06:07.813873 duration_in_ms=3.877
2017-11-13 09:06:07,815 [salt.state       ][INFO    ][31959] Running state [glusterfs-server] at time 09:06:07.814855
2017-11-13 09:06:07,815 [salt.state       ][INFO    ][31959] Executing state service.running for glusterfs-server
2017-11-13 09:06:07,816 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-13 09:06:07,825 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-11-13 09:06:07,875 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-13 09:06:07,888 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-13 09:06:07,893 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-13 09:06:07,900 [salt.state       ][INFO    ][31959] The service glusterfs-server is already running
2017-11-13 09:06:07,900 [salt.state       ][INFO    ][31959] Completed state [glusterfs-server] at time 09:06:07.900348 duration_in_ms=85.493
2017-11-13 09:06:07,902 [salt.state       ][INFO    ][31959] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:06:07.901752
2017-11-13 09:06:07,902 [salt.state       ][INFO    ][31959] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-13 09:06:07,904 [salt.state       ][INFO    ][31959] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-13 09:06:07,904 [salt.state       ][INFO    ][31959] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:06:07.904002 duration_in_ms=2.251
2017-11-13 09:06:07,904 [salt.state       ][INFO    ][31959] Running state [/srv/glusterfs/glance] at time 09:06:07.904179
2017-11-13 09:06:07,904 [salt.state       ][INFO    ][31959] Executing state file.directory for /srv/glusterfs/glance
2017-11-13 09:06:07,905 [salt.state       ][INFO    ][31959] Directory /srv/glusterfs/glance is in the correct state
2017-11-13 09:06:07,905 [salt.state       ][INFO    ][31959] Completed state [/srv/glusterfs/glance] at time 09:06:07.904834 duration_in_ms=0.655
2017-11-13 09:06:07,905 [salt.state       ][INFO    ][31959] Running state [/srv/glusterfs/keystone-keys] at time 09:06:07.905000
2017-11-13 09:06:07,905 [salt.state       ][INFO    ][31959] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-13 09:06:07,906 [salt.state       ][INFO    ][31959] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-13 09:06:07,906 [salt.state       ][INFO    ][31959] Completed state [/srv/glusterfs/keystone-keys] at time 09:06:07.905658 duration_in_ms=0.659
2017-11-13 09:06:07,906 [salt.state       ][INFO    ][31959] Running state [/srv/glusterfs/nova_instances] at time 09:06:07.905821
2017-11-13 09:06:07,906 [salt.state       ][INFO    ][31959] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-13 09:06:07,906 [salt.state       ][INFO    ][31959] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-13 09:06:07,906 [salt.state       ][INFO    ][31959] Completed state [/srv/glusterfs/nova_instances] at time 09:06:07.906468 duration_in_ms=0.647
2017-11-13 09:06:07,907 [salt.state       ][INFO    ][31959] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:06:07.906634
2017-11-13 09:06:07,907 [salt.state       ][INFO    ][31959] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-13 09:06:07,907 [salt.state       ][INFO    ][31959] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-13 09:06:07,907 [salt.state       ][INFO    ][31959] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:06:07.907330 duration_in_ms=0.695
2017-11-13 09:06:07,961 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command ['git', '--version'] in directory '/root'
2017-11-13 09:06:08,043 [salt.state       ][INFO    ][31959] Running state [sleep 5] at time 09:06:08.043174
2017-11-13 09:06:08,043 [salt.state       ][INFO    ][31959] Executing state cmd.wait for sleep 5
2017-11-13 09:06:08,044 [salt.state       ][INFO    ][31959] No changes made for sleep 5
2017-11-13 09:06:08,044 [salt.state       ][INFO    ][31959] Completed state [sleep 5] at time 09:06:08.043807 duration_in_ms=0.633
2017-11-13 09:06:08,044 [salt.state       ][INFO    ][31959] Running state [192.168.10.143] at time 09:06:08.044296
2017-11-13 09:06:08,044 [salt.state       ][INFO    ][31959] Executing state glusterfs.peered for 192.168.10.143
2017-11-13 09:06:08,045 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:08,054 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:08,108 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:08,116 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:08,170 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:08,176 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:08,231 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:08,237 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:08,290 [salt.state       ][INFO    ][31959] Peering with localhost is not needed
2017-11-13 09:06:08,290 [salt.state       ][INFO    ][31959] Completed state [192.168.10.143] at time 09:06:08.289887 duration_in_ms=245.59
2017-11-13 09:06:08,293 [salt.state       ][INFO    ][31959] Running state [192.168.10.142] at time 09:06:08.293312
2017-11-13 09:06:08,294 [salt.state       ][INFO    ][31959] Executing state glusterfs.peered for 192.168.10.142
2017-11-13 09:06:08,294 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:08,302 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:08,356 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:08,363 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:08,415 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:08,423 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:08,477 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:08,485 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:08,538 [salt.state       ][INFO    ][31959] {'new': {'192.168.10.141': [], 'kvm02.baremetal-mcp-ocata-odl-ha.local': ['192.168.10.142']}, 'old': {'192.168.10.141': [], 'kvm02.baremetal-mcp-ocata-odl-ha.local': []}}
2017-11-13 09:06:08,538 [salt.state       ][INFO    ][31959] Completed state [192.168.10.142] at time 09:06:08.537953 duration_in_ms=244.641
2017-11-13 09:06:08,541 [salt.state       ][INFO    ][31959] Running state [192.168.10.141] at time 09:06:08.541267
2017-11-13 09:06:08,542 [salt.state       ][INFO    ][31959] Executing state glusterfs.peered for 192.168.10.141
2017-11-13 09:06:08,542 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:08,550 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:08,602 [salt.state       ][INFO    ][31959] Host 192.168.10.141 already peered
2017-11-13 09:06:08,602 [salt.state       ][INFO    ][31959] Completed state [192.168.10.141] at time 09:06:08.602018 duration_in_ms=60.75
2017-11-13 09:06:08,603 [salt.state       ][INFO    ][31959] Running state [glance] at time 09:06:08.603189
2017-11-13 09:06:08,603 [salt.state       ][INFO    ][31959] Executing state glusterfs.created for glance
2017-11-13 09:06:08,604 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:08,612 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:08,663 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:08,670 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:08,727 [salt.state       ][INFO    ][31959] Volume glance already exists and is started.
2017-11-13 09:06:08,727 [salt.state       ][INFO    ][31959] Completed state [glance] at time 09:06:08.726827 duration_in_ms=123.637
2017-11-13 09:06:08,730 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:06:08.730350
2017-11-13 09:06:08,731 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 09:06:08,731 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 09:06:08,782 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:08,782 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:06:08.781753 duration_in_ms=51.39
2017-11-13 09:06:08,782 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:06:08.782268
2017-11-13 09:06:08,783 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-13 09:06:08,783 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 09:06:08,834 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:08,834 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:06:08.834414 duration_in_ms=52.144
2017-11-13 09:06:08,835 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:06:08.835001
2017-11-13 09:06:08,835 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-13 09:06:08,836 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 09:06:08,889 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:08,890 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:06:08.889600 duration_in_ms=54.598
2017-11-13 09:06:08,890 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:06:08.890151
2017-11-13 09:06:08,890 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 09:06:08,891 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 09:06:08,942 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:08,942 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:06:08.942409 duration_in_ms=52.256
2017-11-13 09:06:08,943 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:06:08.942994
2017-11-13 09:06:08,943 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-13 09:06:08,944 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 09:06:08,997 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:08,998 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:06:08.997506 duration_in_ms=54.511
2017-11-13 09:06:08,999 [salt.state       ][INFO    ][31959] Running state [glance] at time 09:06:08.998696
2017-11-13 09:06:08,999 [salt.state       ][INFO    ][31959] Executing state glusterfs.started for glance
2017-11-13 09:06:08,999 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:09,007 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:09,059 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:09,067 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:09,126 [salt.state       ][INFO    ][31959] Volume glance is already started
2017-11-13 09:06:09,126 [salt.state       ][INFO    ][31959] Completed state [glance] at time 09:06:09.126361 duration_in_ms=127.664
2017-11-13 09:06:09,128 [salt.state       ][INFO    ][31959] Running state [keystone-keys] at time 09:06:09.127601
2017-11-13 09:06:09,128 [salt.state       ][INFO    ][31959] Executing state glusterfs.created for keystone-keys
2017-11-13 09:06:09,128 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:09,136 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:09,189 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:09,197 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:09,254 [salt.state       ][INFO    ][31959] Volume keystone-keys already exists and is started.
2017-11-13 09:06:09,255 [salt.state       ][INFO    ][31959] Completed state [keystone-keys] at time 09:06:09.254704 duration_in_ms=127.103
2017-11-13 09:06:09,259 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:06:09.258651
2017-11-13 09:06:09,259 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-13 09:06:09,259 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 09:06:09,316 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:09,316 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:06:09.316051 duration_in_ms=57.399
2017-11-13 09:06:09,317 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:06:09.316637
2017-11-13 09:06:09,317 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 09:06:09,317 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 09:06:09,369 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:09,370 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:06:09.369615 duration_in_ms=52.977
2017-11-13 09:06:09,370 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:06:09.370157
2017-11-13 09:06:09,370 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 09:06:09,371 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 09:06:09,423 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:09,423 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:06:09.422888 duration_in_ms=52.731
2017-11-13 09:06:09,425 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:06:09.424873
2017-11-13 09:06:09,426 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-13 09:06:09,427 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 09:06:09,480 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:09,480 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:06:09.480220 duration_in_ms=55.348
2017-11-13 09:06:09,481 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:06:09.480772
2017-11-13 09:06:09,481 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-13 09:06:09,481 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 09:06:09,532 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:09,532 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:06:09.532003 duration_in_ms=51.231
2017-11-13 09:06:09,533 [salt.state       ][INFO    ][31959] Running state [keystone-keys] at time 09:06:09.533246
2017-11-13 09:06:09,534 [salt.state       ][INFO    ][31959] Executing state glusterfs.started for keystone-keys
2017-11-13 09:06:09,534 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:09,542 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:09,595 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:09,603 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:09,660 [salt.state       ][INFO    ][31959] Volume keystone-keys is already started
2017-11-13 09:06:09,660 [salt.state       ][INFO    ][31959] Completed state [keystone-keys] at time 09:06:09.660239 duration_in_ms=126.993
2017-11-13 09:06:09,662 [salt.state       ][INFO    ][31959] Running state [nova_instances] at time 09:06:09.661628
2017-11-13 09:06:09,662 [salt.state       ][INFO    ][31959] Executing state glusterfs.created for nova_instances
2017-11-13 09:06:09,662 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:09,671 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:09,725 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:09,732 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:09,786 [salt.state       ][INFO    ][31959] Volume nova_instances already exists and is started.
2017-11-13 09:06:09,786 [salt.state       ][INFO    ][31959] Completed state [nova_instances] at time 09:06:09.786175 duration_in_ms=124.545
2017-11-13 09:06:09,790 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:06:09.789861
2017-11-13 09:06:09,790 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 09:06:09,791 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 09:06:09,841 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:09,842 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:06:09.841485 duration_in_ms=51.623
2017-11-13 09:06:09,842 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 09:06:09.842077
2017-11-13 09:06:09,842 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-11-13 09:06:09,843 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-11-13 09:06:09,894 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:09,895 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 09:06:09.894459 duration_in_ms=52.381
2017-11-13 09:06:09,895 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 09:06:09.895010
2017-11-13 09:06:09,895 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-11-13 09:06:09,896 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-11-13 09:06:09,946 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:09,947 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 09:06:09.946590 duration_in_ms=51.58
2017-11-13 09:06:09,947 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 09:06:09.947217
2017-11-13 09:06:09,948 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-11-13 09:06:09,948 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-11-13 09:06:09,998 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:09,998 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 09:06:09.998368 duration_in_ms=51.151
2017-11-13 09:06:09,999 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:06:09.998942
2017-11-13 09:06:09,999 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 09:06:09,1000 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 09:06:10,052 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:10,052 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:06:10.052181 duration_in_ms=53.238
2017-11-13 09:06:10,053 [salt.state       ][INFO    ][31959] Running state [nova_instances] at time 09:06:10.053430
2017-11-13 09:06:10,054 [salt.state       ][INFO    ][31959] Executing state glusterfs.started for nova_instances
2017-11-13 09:06:10,054 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:10,062 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:10,116 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:10,124 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:10,181 [salt.state       ][INFO    ][31959] Volume nova_instances is already started
2017-11-13 09:06:10,181 [salt.state       ][INFO    ][31959] Completed state [nova_instances] at time 09:06:10.181353 duration_in_ms=127.923
2017-11-13 09:06:10,182 [salt.state       ][INFO    ][31959] Running state [keystone-credential-keys] at time 09:06:10.182349
2017-11-13 09:06:10,183 [salt.state       ][INFO    ][31959] Executing state glusterfs.created for keystone-credential-keys
2017-11-13 09:06:10,183 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:10,192 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:10,246 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:10,254 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:10,311 [salt.state       ][INFO    ][31959] Volume keystone-credential-keys already exists and is started.
2017-11-13 09:06:10,311 [salt.state       ][INFO    ][31959] Completed state [keystone-credential-keys] at time 09:06:10.311335 duration_in_ms=128.985
2017-11-13 09:06:10,315 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:06:10.315280
2017-11-13 09:06:10,316 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-13 09:06:10,316 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 09:06:10,366 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:10,366 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:06:10.366394 duration_in_ms=51.112
2017-11-13 09:06:10,367 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:06:10.367032
2017-11-13 09:06:10,367 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 09:06:10,368 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 09:06:10,423 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:10,423 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:06:10.422771 duration_in_ms=55.739
2017-11-13 09:06:10,423 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:06:10.423415
2017-11-13 09:06:10,424 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-13 09:06:10,424 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 09:06:10,475 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:10,475 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:06:10.474956 duration_in_ms=51.541
2017-11-13 09:06:10,476 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:06:10.475538
2017-11-13 09:06:10,476 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 09:06:10,476 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 09:06:10,527 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:10,527 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:06:10.526871 duration_in_ms=51.332
2017-11-13 09:06:10,528 [salt.state       ][INFO    ][31959] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:06:10.527495
2017-11-13 09:06:10,528 [salt.state       ][INFO    ][31959] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-13 09:06:10,528 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 09:06:10,579 [salt.state       ][INFO    ][31959] unless execution succeeded
2017-11-13 09:06:10,579 [salt.state       ][INFO    ][31959] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:06:10.578721 duration_in_ms=51.226
2017-11-13 09:06:10,580 [salt.state       ][INFO    ][31959] Running state [keystone-credential-keys] at time 09:06:10.579964
2017-11-13 09:06:10,580 [salt.state       ][INFO    ][31959] Executing state glusterfs.started for keystone-credential-keys
2017-11-13 09:06:10,581 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:10,587 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:10,638 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:10,645 [salt.loaded.int.module.cmdmod][INFO    ][31959] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:10,704 [salt.state       ][INFO    ][31959] Volume keystone-credential-keys is already started
2017-11-13 09:06:10,704 [salt.state       ][INFO    ][31959] Completed state [keystone-credential-keys] at time 09:06:10.703989 duration_in_ms=124.025
2017-11-13 09:06:10,706 [salt.minion      ][INFO    ][31959] Returning information for job: 20171113090604802995
2017-11-13 09:06:15,938 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command test.ping with jid 20171113090615931712
2017-11-13 09:06:15,950 [salt.minion      ][INFO    ][32386] Starting a new job with PID 32386
2017-11-13 09:06:15,984 [salt.minion      ][INFO    ][32386] Returning information for job: 20171113090615931712
2017-11-13 09:06:16,488 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command cmd.run with jid 20171113090616481580
2017-11-13 09:06:16,499 [salt.minion      ][INFO    ][32391] Starting a new job with PID 32391
2017-11-13 09:06:16,502 [salt.loaded.int.module.cmdmod][INFO    ][32391] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-13 09:06:16,619 [salt.minion      ][INFO    ][32391] Returning information for job: 20171113090616481580
2017-11-13 09:46:24,027 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command test.ping with jid 20171113094624020784
2017-11-13 09:46:24,040 [salt.minion      ][INFO    ][33209] Starting a new job with PID 33209
2017-11-13 09:46:24,079 [salt.minion      ][INFO    ][33209] Returning information for job: 20171113094624020784
2017-11-13 09:46:31,077 [salt.utils.schedule][INFO    ][24652] Running scheduled job: __mine_interval
2017-11-13 09:46:32,257 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command state.sls with jid 20171113094632249172
2017-11-13 09:46:32,268 [salt.minion      ][INFO    ][33225] Starting a new job with PID 33225
2017-11-13 09:46:32,845 [salt.state       ][INFO    ][33225] Loading fresh modules for state activity
2017-11-13 09:46:32,876 [salt.fileclient  ][INFO    ][33225] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-13 09:46:32,892 [salt.fileclient  ][INFO    ][33225] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-13 09:46:32,920 [salt.fileclient  ][INFO    ][33225] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 09:46:33,232 [salt.state       ][INFO    ][33225] Running state [lsof] at time 09:46:33.232183
2017-11-13 09:46:33,233 [salt.state       ][INFO    ][33225] Executing state pkg.installed for lsof
2017-11-13 09:46:33,233 [salt.loaded.int.module.cmdmod][INFO    ][33225] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 09:46:33,519 [salt.state       ][INFO    ][33225] Package lsof is already installed
2017-11-13 09:46:33,520 [salt.state       ][INFO    ][33225] Completed state [lsof] at time 09:46:33.519619 duration_in_ms=287.436
2017-11-13 09:46:33,520 [salt.state       ][INFO    ][33225] Running state [keepalived] at time 09:46:33.519893
2017-11-13 09:46:33,520 [salt.state       ][INFO    ][33225] Executing state pkg.installed for keepalived
2017-11-13 09:46:33,524 [salt.state       ][INFO    ][33225] Package keepalived is already installed
2017-11-13 09:46:33,524 [salt.state       ][INFO    ][33225] Completed state [keepalived] at time 09:46:33.524435 duration_in_ms=4.542
2017-11-13 09:46:33,526 [salt.state       ][INFO    ][33225] Running state [/etc/keepalived/keepalived.conf] at time 09:46:33.526203
2017-11-13 09:46:33,526 [salt.state       ][INFO    ][33225] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-13 09:46:33,558 [salt.fileclient  ][INFO    ][33225] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-13 09:46:33,599 [salt.fileclient  ][INFO    ][33225] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 09:46:33,605 [salt.state       ][INFO    ][33225] File /etc/keepalived/keepalived.conf is in the correct state
2017-11-13 09:46:33,605 [salt.state       ][INFO    ][33225] Completed state [/etc/keepalived/keepalived.conf] at time 09:46:33.605413 duration_in_ms=79.21
2017-11-13 09:46:33,606 [salt.state       ][INFO    ][33225] Running state [keepalived] at time 09:46:33.606071
2017-11-13 09:46:33,606 [salt.state       ][INFO    ][33225] Executing state service.running for keepalived
2017-11-13 09:46:33,607 [salt.loaded.int.module.cmdmod][INFO    ][33225] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-13 09:46:33,622 [salt.loaded.int.module.cmdmod][INFO    ][33225] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-13 09:46:33,629 [salt.loaded.int.module.cmdmod][INFO    ][33225] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-13 09:46:33,636 [salt.state       ][INFO    ][33225] The service keepalived is already running
2017-11-13 09:46:33,636 [salt.state       ][INFO    ][33225] Completed state [keepalived] at time 09:46:33.636033 duration_in_ms=29.962
2017-11-13 09:46:33,637 [salt.minion      ][INFO    ][33225] Returning information for job: 20171113094632249172
2017-11-13 09:47:01,742 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command pillar.get with jid 20171113094701736532
2017-11-13 09:47:01,756 [salt.minion      ][INFO    ][33255] Starting a new job with PID 33255
2017-11-13 09:47:01,760 [salt.minion      ][INFO    ][33255] Returning information for job: 20171113094701736532
2017-11-13 09:47:51,888 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command state.sls with jid 20171113094751880184
2017-11-13 09:47:51,900 [salt.minion      ][INFO    ][33266] Starting a new job with PID 33266
2017-11-13 09:47:54,484 [salt.state       ][INFO    ][33266] Loading fresh modules for state activity
2017-11-13 09:47:54,509 [salt.fileclient  ][INFO    ][33266] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-13 09:47:54,534 [salt.fileclient  ][INFO    ][33266] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 09:47:54,851 [salt.state       ][INFO    ][33266] Running state [attr] at time 09:47:54.851264
2017-11-13 09:47:54,852 [salt.state       ][INFO    ][33266] Executing state pkg.installed for attr
2017-11-13 09:47:54,852 [salt.loaded.int.module.cmdmod][INFO    ][33266] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 09:47:55,129 [salt.state       ][INFO    ][33266] Package attr is already installed
2017-11-13 09:47:55,129 [salt.state       ][INFO    ][33266] Completed state [attr] at time 09:47:55.129122 duration_in_ms=277.857
2017-11-13 09:47:55,130 [salt.state       ][INFO    ][33266] Running state [glusterfs-server] at time 09:47:55.129483
2017-11-13 09:47:55,130 [salt.state       ][INFO    ][33266] Executing state pkg.installed for glusterfs-server
2017-11-13 09:47:55,134 [salt.state       ][INFO    ][33266] Package glusterfs-server is already installed
2017-11-13 09:47:55,134 [salt.state       ][INFO    ][33266] Completed state [glusterfs-server] at time 09:47:55.133687 duration_in_ms=4.203
2017-11-13 09:47:55,134 [salt.state       ][INFO    ][33266] Running state [bc] at time 09:47:55.133953
2017-11-13 09:47:55,134 [salt.state       ][INFO    ][33266] Executing state pkg.installed for bc
2017-11-13 09:47:55,138 [salt.state       ][INFO    ][33266] Package bc is already installed
2017-11-13 09:47:55,138 [salt.state       ][INFO    ][33266] Completed state [bc] at time 09:47:55.138014 duration_in_ms=4.06
2017-11-13 09:47:55,139 [salt.state       ][INFO    ][33266] Running state [glusterfs-server] at time 09:47:55.138899
2017-11-13 09:47:55,139 [salt.state       ][INFO    ][33266] Executing state service.running for glusterfs-server
2017-11-13 09:47:55,140 [salt.loaded.int.module.cmdmod][INFO    ][33266] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-13 09:47:55,156 [salt.loaded.int.module.cmdmod][INFO    ][33266] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-13 09:47:55,163 [salt.loaded.int.module.cmdmod][INFO    ][33266] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-13 09:47:55,173 [salt.state       ][INFO    ][33266] The service glusterfs-server is already running
2017-11-13 09:47:55,173 [salt.state       ][INFO    ][33266] Completed state [glusterfs-server] at time 09:47:55.173367 duration_in_ms=34.466
2017-11-13 09:47:55,175 [salt.state       ][INFO    ][33266] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:47:55.174891
2017-11-13 09:47:55,175 [salt.state       ][INFO    ][33266] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-13 09:47:55,177 [salt.state       ][INFO    ][33266] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-13 09:47:55,177 [salt.state       ][INFO    ][33266] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:47:55.177398 duration_in_ms=2.507
2017-11-13 09:47:55,178 [salt.state       ][INFO    ][33266] Running state [/srv/glusterfs/glance] at time 09:47:55.177655
2017-11-13 09:47:55,178 [salt.state       ][INFO    ][33266] Executing state file.directory for /srv/glusterfs/glance
2017-11-13 09:47:55,178 [salt.state       ][INFO    ][33266] Directory /srv/glusterfs/glance is in the correct state
2017-11-13 09:47:55,179 [salt.state       ][INFO    ][33266] Completed state [/srv/glusterfs/glance] at time 09:47:55.178626 duration_in_ms=0.97
2017-11-13 09:47:55,179 [salt.state       ][INFO    ][33266] Running state [/srv/glusterfs/keystone-keys] at time 09:47:55.178875
2017-11-13 09:47:55,179 [salt.state       ][INFO    ][33266] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-13 09:47:55,180 [salt.state       ][INFO    ][33266] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-13 09:47:55,180 [salt.state       ][INFO    ][33266] Completed state [/srv/glusterfs/keystone-keys] at time 09:47:55.179889 duration_in_ms=1.013
2017-11-13 09:47:55,180 [salt.state       ][INFO    ][33266] Running state [/srv/glusterfs/nova_instances] at time 09:47:55.180136
2017-11-13 09:47:55,180 [salt.state       ][INFO    ][33266] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-13 09:47:55,181 [salt.state       ][INFO    ][33266] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-13 09:47:55,181 [salt.state       ][INFO    ][33266] Completed state [/srv/glusterfs/nova_instances] at time 09:47:55.181035 duration_in_ms=0.899
2017-11-13 09:47:55,181 [salt.state       ][INFO    ][33266] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:47:55.181281
2017-11-13 09:47:55,182 [salt.state       ][INFO    ][33266] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-13 09:47:55,182 [salt.state       ][INFO    ][33266] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-13 09:47:55,182 [salt.state       ][INFO    ][33266] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:47:55.182213 duration_in_ms=0.931
2017-11-13 09:47:55,183 [salt.minion      ][INFO    ][33266] Returning information for job: 20171113094751880184
2017-11-13 09:47:56,520 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command test.ping with jid 20171113094756513664
2017-11-13 09:47:56,532 [salt.minion      ][INFO    ][33290] Starting a new job with PID 33290
2017-11-13 09:47:56,566 [salt.minion      ][INFO    ][33290] Returning information for job: 20171113094756513664
2017-11-13 09:48:03,284 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command state.sls with jid 20171113094803277426
2017-11-13 09:48:03,295 [salt.minion      ][INFO    ][33295] Starting a new job with PID 33295
2017-11-13 09:48:03,858 [salt.state       ][INFO    ][33295] Loading fresh modules for state activity
2017-11-13 09:48:03,882 [salt.fileclient  ][INFO    ][33295] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-13 09:48:03,919 [salt.fileclient  ][INFO    ][33295] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 09:48:03,994 [salt.fileclient  ][INFO    ][33295] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-13 09:48:04,013 [salt.fileclient  ][INFO    ][33295] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 09:48:04,329 [salt.state       ][INFO    ][33295] Running state [attr] at time 09:48:04.328681
2017-11-13 09:48:04,329 [salt.state       ][INFO    ][33295] Executing state pkg.installed for attr
2017-11-13 09:48:04,330 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 09:48:04,613 [salt.state       ][INFO    ][33295] Package attr is already installed
2017-11-13 09:48:04,613 [salt.state       ][INFO    ][33295] Completed state [attr] at time 09:48:04.613394 duration_in_ms=284.714
2017-11-13 09:48:04,614 [salt.state       ][INFO    ][33295] Running state [glusterfs-server] at time 09:48:04.613694
2017-11-13 09:48:04,614 [salt.state       ][INFO    ][33295] Executing state pkg.installed for glusterfs-server
2017-11-13 09:48:04,618 [salt.state       ][INFO    ][33295] Package glusterfs-server is already installed
2017-11-13 09:48:04,618 [salt.state       ][INFO    ][33295] Completed state [glusterfs-server] at time 09:48:04.617912 duration_in_ms=4.218
2017-11-13 09:48:04,618 [salt.state       ][INFO    ][33295] Running state [bc] at time 09:48:04.618096
2017-11-13 09:48:04,618 [salt.state       ][INFO    ][33295] Executing state pkg.installed for bc
2017-11-13 09:48:04,622 [salt.state       ][INFO    ][33295] Package bc is already installed
2017-11-13 09:48:04,622 [salt.state       ][INFO    ][33295] Completed state [bc] at time 09:48:04.622171 duration_in_ms=4.075
2017-11-13 09:48:04,623 [salt.state       ][INFO    ][33295] Running state [glusterfs-server] at time 09:48:04.623240
2017-11-13 09:48:04,623 [salt.state       ][INFO    ][33295] Executing state service.running for glusterfs-server
2017-11-13 09:48:04,624 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-13 09:48:04,639 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-13 09:48:04,646 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-13 09:48:04,656 [salt.state       ][INFO    ][33295] The service glusterfs-server is already running
2017-11-13 09:48:04,656 [salt.state       ][INFO    ][33295] Completed state [glusterfs-server] at time 09:48:04.655833 duration_in_ms=32.593
2017-11-13 09:48:04,657 [salt.state       ][INFO    ][33295] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:48:04.657324
2017-11-13 09:48:04,658 [salt.state       ][INFO    ][33295] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-13 09:48:04,660 [salt.state       ][INFO    ][33295] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-13 09:48:04,660 [salt.state       ][INFO    ][33295] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:48:04.659760 duration_in_ms=2.436
2017-11-13 09:48:04,660 [salt.state       ][INFO    ][33295] Running state [/srv/glusterfs/glance] at time 09:48:04.659963
2017-11-13 09:48:04,660 [salt.state       ][INFO    ][33295] Executing state file.directory for /srv/glusterfs/glance
2017-11-13 09:48:04,661 [salt.state       ][INFO    ][33295] Directory /srv/glusterfs/glance is in the correct state
2017-11-13 09:48:04,661 [salt.state       ][INFO    ][33295] Completed state [/srv/glusterfs/glance] at time 09:48:04.660821 duration_in_ms=0.858
2017-11-13 09:48:04,661 [salt.state       ][INFO    ][33295] Running state [/srv/glusterfs/keystone-keys] at time 09:48:04.661010
2017-11-13 09:48:04,661 [salt.state       ][INFO    ][33295] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-13 09:48:04,662 [salt.state       ][INFO    ][33295] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-13 09:48:04,662 [salt.state       ][INFO    ][33295] Completed state [/srv/glusterfs/keystone-keys] at time 09:48:04.661805 duration_in_ms=0.795
2017-11-13 09:48:04,662 [salt.state       ][INFO    ][33295] Running state [/srv/glusterfs/nova_instances] at time 09:48:04.661993
2017-11-13 09:48:04,662 [salt.state       ][INFO    ][33295] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-13 09:48:04,663 [salt.state       ][INFO    ][33295] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-13 09:48:04,663 [salt.state       ][INFO    ][33295] Completed state [/srv/glusterfs/nova_instances] at time 09:48:04.662756 duration_in_ms=0.763
2017-11-13 09:48:04,663 [salt.state       ][INFO    ][33295] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:48:04.662942
2017-11-13 09:48:04,663 [salt.state       ][INFO    ][33295] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-13 09:48:04,664 [salt.state       ][INFO    ][33295] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-13 09:48:04,664 [salt.state       ][INFO    ][33295] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:48:04.663855 duration_in_ms=0.913
2017-11-13 09:48:04,705 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command ['git', '--version'] in directory '/root'
2017-11-13 09:48:04,797 [salt.state       ][INFO    ][33295] Running state [sleep 5] at time 09:48:04.797112
2017-11-13 09:48:04,797 [salt.state       ][INFO    ][33295] Executing state cmd.wait for sleep 5
2017-11-13 09:48:04,798 [salt.state       ][INFO    ][33295] No changes made for sleep 5
2017-11-13 09:48:04,798 [salt.state       ][INFO    ][33295] Completed state [sleep 5] at time 09:48:04.797818 duration_in_ms=0.706
2017-11-13 09:48:04,798 [salt.state       ][INFO    ][33295] Running state [192.168.10.143] at time 09:48:04.798401
2017-11-13 09:48:04,799 [salt.state       ][INFO    ][33295] Executing state glusterfs.peered for 192.168.10.143
2017-11-13 09:48:04,799 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:04,809 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:04,863 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:04,871 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:04,924 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:04,932 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:04,985 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:04,993 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:05,047 [salt.state       ][INFO    ][33295] Peering with localhost is not needed
2017-11-13 09:48:05,047 [salt.state       ][INFO    ][33295] Completed state [192.168.10.143] at time 09:48:05.047083 duration_in_ms=248.681
2017-11-13 09:48:05,050 [salt.state       ][INFO    ][33295] Running state [192.168.10.142] at time 09:48:05.050307
2017-11-13 09:48:05,051 [salt.state       ][INFO    ][33295] Executing state glusterfs.peered for 192.168.10.142
2017-11-13 09:48:05,051 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:05,059 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:05,112 [salt.state       ][INFO    ][33295] Host 192.168.10.142 already peered
2017-11-13 09:48:05,112 [salt.state       ][INFO    ][33295] Completed state [192.168.10.142] at time 09:48:05.111886 duration_in_ms=61.57
2017-11-13 09:48:05,115 [salt.state       ][INFO    ][33295] Running state [192.168.10.141] at time 09:48:05.114907
2017-11-13 09:48:05,115 [salt.state       ][INFO    ][33295] Executing state glusterfs.peered for 192.168.10.141
2017-11-13 09:48:05,116 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:05,124 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:05,178 [salt.state       ][INFO    ][33295] Host 192.168.10.141 already peered
2017-11-13 09:48:05,178 [salt.state       ][INFO    ][33295] Completed state [192.168.10.141] at time 09:48:05.178109 duration_in_ms=63.202
2017-11-13 09:48:05,179 [salt.state       ][INFO    ][33295] Running state [glance] at time 09:48:05.179246
2017-11-13 09:48:05,180 [salt.state       ][INFO    ][33295] Executing state glusterfs.created for glance
2017-11-13 09:48:05,180 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:05,188 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:05,241 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:05,248 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:05,309 [salt.state       ][INFO    ][33295] Volume glance already exists and is started.
2017-11-13 09:48:05,309 [salt.state       ][INFO    ][33295] Completed state [glance] at time 09:48:05.309218 duration_in_ms=129.971
2017-11-13 09:48:05,314 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:48:05.314020
2017-11-13 09:48:05,314 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 09:48:05,315 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 09:48:05,376 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:05,377 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:48:05.376764 duration_in_ms=62.745
2017-11-13 09:48:05,377 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:48:05.377414
2017-11-13 09:48:05,378 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-13 09:48:05,378 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 09:48:05,432 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:05,433 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:48:05.432520 duration_in_ms=55.105
2017-11-13 09:48:05,433 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:48:05.433137
2017-11-13 09:48:05,433 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-13 09:48:05,434 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 09:48:05,488 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:05,488 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:48:05.487956 duration_in_ms=54.818
2017-11-13 09:48:05,489 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:48:05.488560
2017-11-13 09:48:05,489 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 09:48:05,489 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 09:48:05,542 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:05,543 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:48:05.542506 duration_in_ms=53.946
2017-11-13 09:48:05,543 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:48:05.543071
2017-11-13 09:48:05,543 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-13 09:48:05,544 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 09:48:05,596 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:05,596 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:48:05.595853 duration_in_ms=52.781
2017-11-13 09:48:05,597 [salt.state       ][INFO    ][33295] Running state [glance] at time 09:48:05.597083
2017-11-13 09:48:05,597 [salt.state       ][INFO    ][33295] Executing state glusterfs.started for glance
2017-11-13 09:48:05,598 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:05,606 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:05,659 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:05,667 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:05,724 [salt.state       ][INFO    ][33295] Volume glance is already started
2017-11-13 09:48:05,724 [salt.state       ][INFO    ][33295] Completed state [glance] at time 09:48:05.724156 duration_in_ms=127.071
2017-11-13 09:48:05,725 [salt.state       ][INFO    ][33295] Running state [keystone-keys] at time 09:48:05.725208
2017-11-13 09:48:05,725 [salt.state       ][INFO    ][33295] Executing state glusterfs.created for keystone-keys
2017-11-13 09:48:05,726 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:05,734 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:05,787 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:05,795 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:05,855 [salt.state       ][INFO    ][33295] Volume keystone-keys already exists and is started.
2017-11-13 09:48:05,856 [salt.state       ][INFO    ][33295] Completed state [keystone-keys] at time 09:48:05.855892 duration_in_ms=130.683
2017-11-13 09:48:05,861 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:48:05.860768
2017-11-13 09:48:05,861 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-13 09:48:05,862 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 09:48:05,916 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:05,916 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:48:05.916399 duration_in_ms=55.63
2017-11-13 09:48:05,917 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:48:05.917091
2017-11-13 09:48:05,917 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 09:48:05,918 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 09:48:05,973 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:05,974 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:48:05.973474 duration_in_ms=56.383
2017-11-13 09:48:05,974 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:48:05.974030
2017-11-13 09:48:05,974 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 09:48:05,975 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 09:48:06,027 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:06,027 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:48:06.027343 duration_in_ms=53.313
2017-11-13 09:48:06,028 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:48:06.028030
2017-11-13 09:48:06,028 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-13 09:48:06,029 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 09:48:06,082 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:06,082 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:48:06.082053 duration_in_ms=54.023
2017-11-13 09:48:06,083 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:48:06.082632
2017-11-13 09:48:06,083 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-13 09:48:06,083 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 09:48:06,134 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:06,135 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:48:06.134683 duration_in_ms=52.051
2017-11-13 09:48:06,136 [salt.state       ][INFO    ][33295] Running state [keystone-keys] at time 09:48:06.136020
2017-11-13 09:48:06,136 [salt.state       ][INFO    ][33295] Executing state glusterfs.started for keystone-keys
2017-11-13 09:48:06,137 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:06,145 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:06,197 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:06,205 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:06,262 [salt.state       ][INFO    ][33295] Volume keystone-keys is already started
2017-11-13 09:48:06,263 [salt.state       ][INFO    ][33295] Completed state [keystone-keys] at time 09:48:06.262456 duration_in_ms=126.435
2017-11-13 09:48:06,264 [salt.state       ][INFO    ][33295] Running state [nova_instances] at time 09:48:06.264176
2017-11-13 09:48:06,264 [salt.state       ][INFO    ][33295] Executing state glusterfs.created for nova_instances
2017-11-13 09:48:06,265 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:06,273 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:06,326 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:06,334 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:06,392 [salt.state       ][INFO    ][33295] Volume nova_instances already exists and is started.
2017-11-13 09:48:06,392 [salt.state       ][INFO    ][33295] Completed state [nova_instances] at time 09:48:06.392008 duration_in_ms=127.831
2017-11-13 09:48:06,396 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:48:06.395750
2017-11-13 09:48:06,396 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 09:48:06,397 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 09:48:06,453 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:06,454 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:48:06.453533 duration_in_ms=57.782
2017-11-13 09:48:06,454 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 09:48:06.454230
2017-11-13 09:48:06,455 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-11-13 09:48:06,455 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-11-13 09:48:06,510 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:06,510 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 09:48:06.510071 duration_in_ms=55.841
2017-11-13 09:48:06,511 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 09:48:06.510620
2017-11-13 09:48:06,511 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-11-13 09:48:06,511 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-11-13 09:48:06,565 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:06,565 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 09:48:06.565217 duration_in_ms=54.597
2017-11-13 09:48:06,566 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 09:48:06.565799
2017-11-13 09:48:06,566 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-11-13 09:48:06,566 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-11-13 09:48:06,617 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:06,618 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 09:48:06.617658 duration_in_ms=51.858
2017-11-13 09:48:06,618 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:48:06.618308
2017-11-13 09:48:06,619 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 09:48:06,619 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 09:48:06,670 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:06,671 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:48:06.670619 duration_in_ms=52.311
2017-11-13 09:48:06,672 [salt.state       ][INFO    ][33295] Running state [nova_instances] at time 09:48:06.671980
2017-11-13 09:48:06,672 [salt.state       ][INFO    ][33295] Executing state glusterfs.started for nova_instances
2017-11-13 09:48:06,673 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:06,680 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:06,732 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:06,741 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:06,799 [salt.state       ][INFO    ][33295] Volume nova_instances is already started
2017-11-13 09:48:06,800 [salt.state       ][INFO    ][33295] Completed state [nova_instances] at time 09:48:06.799594 duration_in_ms=127.612
2017-11-13 09:48:06,801 [salt.state       ][INFO    ][33295] Running state [keystone-credential-keys] at time 09:48:06.800843
2017-11-13 09:48:06,801 [salt.state       ][INFO    ][33295] Executing state glusterfs.created for keystone-credential-keys
2017-11-13 09:48:06,802 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:06,810 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:06,861 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:06,869 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:06,928 [salt.state       ][INFO    ][33295] Volume keystone-credential-keys already exists and is started.
2017-11-13 09:48:06,929 [salt.state       ][INFO    ][33295] Completed state [keystone-credential-keys] at time 09:48:06.928453 duration_in_ms=127.609
2017-11-13 09:48:06,933 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:48:06.932821
2017-11-13 09:48:06,933 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-13 09:48:06,934 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 09:48:06,986 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:06,987 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:48:06.986583 duration_in_ms=53.763
2017-11-13 09:48:06,987 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:48:06.987239
2017-11-13 09:48:06,988 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 09:48:06,988 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 09:48:07,044 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:07,044 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:48:07.044247 duration_in_ms=57.008
2017-11-13 09:48:07,045 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:48:07.044790
2017-11-13 09:48:07,045 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-13 09:48:07,045 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 09:48:07,101 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:07,101 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:48:07.101321 duration_in_ms=56.53
2017-11-13 09:48:07,102 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:48:07.102002
2017-11-13 09:48:07,102 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 09:48:07,103 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 09:48:07,157 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:07,157 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:48:07.157277 duration_in_ms=55.274
2017-11-13 09:48:07,158 [salt.state       ][INFO    ][33295] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:48:07.157800
2017-11-13 09:48:07,158 [salt.state       ][INFO    ][33295] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-13 09:48:07,158 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 09:48:07,212 [salt.state       ][INFO    ][33295] unless execution succeeded
2017-11-13 09:48:07,212 [salt.state       ][INFO    ][33295] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:48:07.212235 duration_in_ms=54.435
2017-11-13 09:48:07,214 [salt.state       ][INFO    ][33295] Running state [keystone-credential-keys] at time 09:48:07.213619
2017-11-13 09:48:07,214 [salt.state       ][INFO    ][33295] Executing state glusterfs.started for keystone-credential-keys
2017-11-13 09:48:07,214 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:07,223 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:07,280 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:07,287 [salt.loaded.int.module.cmdmod][INFO    ][33295] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:07,345 [salt.state       ][INFO    ][33295] Volume keystone-credential-keys is already started
2017-11-13 09:48:07,346 [salt.state       ][INFO    ][33295] Completed state [keystone-credential-keys] at time 09:48:07.345473 duration_in_ms=131.844
2017-11-13 09:48:07,348 [salt.minion      ][INFO    ][33295] Returning information for job: 20171113094803277426
2017-11-13 09:48:12,458 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command test.ping with jid 20171113094812453047
2017-11-13 09:48:12,471 [salt.minion      ][INFO    ][33681] Starting a new job with PID 33681
2017-11-13 09:48:12,506 [salt.minion      ][INFO    ][33681] Returning information for job: 20171113094812453047
2017-11-13 09:48:13,027 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command cmd.run with jid 20171113094813022218
2017-11-13 09:48:13,040 [salt.minion      ][INFO    ][33686] Starting a new job with PID 33686
2017-11-13 09:48:13,044 [salt.loaded.int.module.cmdmod][INFO    ][33686] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-13 09:48:13,166 [salt.minion      ][INFO    ][33686] Returning information for job: 20171113094813022218
2017-11-13 10:11:20,080 [salt.minion      ][INFO    ][24652] User sudo_ubuntu Executing command cp.push_dir with jid 20171113101120072933
2017-11-13 10:11:20,094 [salt.minion      ][INFO    ][34006] Starting a new job with PID 34006
