2017-10-28 20:12:01,264 [salt.cli.daemons ][WARNING ][2412] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-28 20:13:06,052 [salt.state       ][ERROR   ][3933] No contents found in top file
2017-10-28 20:14:53,822 [salt.utils.parsers][WARNING ][2412] Minion received a SIGTERM. Exiting.
2017-10-28 20:19:17,688 [salt.cli.daemons ][WARNING ][3534] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-28 20:21:20,007 [salt.loaded.int.module.cmdmod][ERROR   ][10737] Command 'virsh net-destroy default 2>&1 1>/dev/null; virsh net-undefine default' failed with return code: 1
2017-10-28 20:21:20,008 [salt.loaded.int.module.cmdmod][ERROR   ][10737] stderr: error: failed to get network 'default'
error: Network not found: no network with matching name 'default'
2017-10-28 20:21:20,009 [salt.loaded.int.module.cmdmod][ERROR   ][10737] retcode: 1
2017-10-28 20:21:20,010 [salt.state       ][ERROR   ][10737] {'pid': 15650, 'retcode': 1, 'stderr': "error: failed to get network 'default'\nerror: Network not found: no network with matching name 'default'", 'stdout': ''}
2017-10-28 20:21:36,619 [py.warnings      ][WARNING ][15727] /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-10-28 20:22:03,936 [salt.loaded.int.module.cmdmod][ERROR   ][15727] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-28 20:22:03,937 [salt.loaded.int.module.cmdmod][ERROR   ][15727] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-28 20:22:08,945 [salt.loaded.int.module.cmdmod][ERROR   ][15727] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-28 20:22:08,946 [salt.loaded.int.module.cmdmod][ERROR   ][15727] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-28 20:22:13,984 [salt.loaded.int.module.cmdmod][ERROR   ][15727] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-28 20:22:13,984 [salt.loaded.int.module.cmdmod][ERROR   ][15727] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-28 20:22:19,064 [salt.loaded.int.module.cmdmod][ERROR   ][15727] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-28 20:22:19,064 [salt.loaded.int.module.cmdmod][ERROR   ][15727] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-28 20:22:23,231 [salt.loaded.int.module.cmdmod][ERROR   ][15727] 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-10-28 20:22:26,927 [salt.state       ][ERROR   ][23165] No contents found in top file
2017-10-28 20:22:30,484 [salt.loaded.int.module.cmdmod][INFO    ][23377] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-10-28 20:22:30,495 [salt.loaded.int.module.cmdmod][INFO    ][23377] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-10-28 20:22:30,513 [salt.loaded.int.module.cmdmod][INFO    ][23377] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-10-28 20:22:30,528 [salt.utils.parsers][WARNING ][3534] Minion received a SIGTERM. Exiting.
2017-10-28 20:22:30,966 [salt.cli.daemons ][INFO    ][23509] Setting up the Salt Minion "kvm02.baremetal-mcp-ocata-ovs-ha.local"
2017-10-28 20:22:31,866 [salt.minion      ][INFO    ][23509] Creating minion process manager
2017-10-28 20:22:31,867 [salt.cli.daemons ][WARNING ][23509] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-28 20:22:31,867 [salt.cli.daemons ][INFO    ][23509] The Salt Minion is starting up
2017-10-28 20:22:31,868 [salt.minion      ][INFO    ][23509] Minion is starting as user 'root'
2017-10-28 20:22:31,868 [salt.utils.event ][INFO    ][23509] Starting pull socket on /var/run/salt/minion/minion_event_a1b2f32648_pull.ipc
2017-10-28 20:22:33,279 [salt.loaded.int.module.cmdmod][INFO    ][23509] Executing command ['date', '+%z'] in directory '/root'
2017-10-28 20:22:33,291 [salt.utils.schedule][INFO    ][23509] Updating job settings for scheduled job: __mine_interval
2017-10-28 20:22:33,295 [salt.minion      ][INFO    ][23509] Added mine.update to scheduler
2017-10-28 20:22:33,332 [salt.minion      ][INFO    ][23509] Minion is ready to receive requests!
2017-10-28 20:22:34,334 [salt.utils.schedule][INFO    ][23509] Running scheduled job: __mine_interval
2017-10-28 20:22:45,264 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command state.sls with jid 20171028202245256904
2017-10-28 20:22:45,283 [salt.minion      ][INFO    ][23636] Starting a new job with PID 23636
2017-10-28 20:22:47,900 [salt.state       ][INFO    ][23636] Loading fresh modules for state activity
2017-10-28 20:22:47,936 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-10-28 20:22:48,258 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-10-28 20:22:48,291 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-28 20:22:48,386 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-10-28 20:22:48,407 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-10-28 20:22:48,447 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-28 20:22:48,899 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-10-28 20:22:48,923 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-28 20:22:49,033 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-10-28 20:22:49,059 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-10-28 20:22:49,075 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-28 20:22:49,094 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-10-28 20:22:49,105 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-10-28 20:22:49,143 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-10-28 20:22:49,168 [py.warnings      ][WARNING ][23636] /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-10-28 20:22:49,169 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command 'salt-minion --version' in directory '/root'
2017-10-28 20:22:49,512 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-10-28 20:22:49,538 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-10-28 20:22:49,555 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-10-28 20:22:49,572 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-10-28 20:22:49,643 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-10-28 20:22:49,703 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-10-28 20:22:49,795 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-10-28 20:22:49,834 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-28 20:22:49,920 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-10-28 20:22:49,952 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-28 20:22:50,024 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-10-28 20:22:50,057 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-10-28 20:22:50,078 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-28 20:22:50,104 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-10-28 20:22:50,121 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-10-28 20:22:50,166 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-10-28 20:22:50,196 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command 'salt-minion --version' in directory '/root'
2017-10-28 20:22:50,612 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-10-28 20:22:50,640 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-10-28 20:22:50,663 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-10-28 20:22:50,683 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-10-28 20:22:50,765 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-10-28 20:22:50,831 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-10-28 20:22:50,935 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-10-28 20:22:51,025 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-28 20:22:51,517 [salt.state       ][INFO    ][23636] Running state [salt-minion] at time 20:22:51.516608
2017-10-28 20:22:51,517 [salt.state       ][INFO    ][23636] Executing state pkg.installed for salt-minion
2017-10-28 20:22:51,518 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-28 20:22:51,958 [salt.state       ][INFO    ][23636] Package salt-minion is already installed
2017-10-28 20:22:51,959 [salt.state       ][INFO    ][23636] Completed state [salt-minion] at time 20:22:51.958593 duration_in_ms=441.984
2017-10-28 20:22:51,959 [salt.state       ][INFO    ][23636] Running state [salt_minion_dependency_packages] at time 20:22:51.959397
2017-10-28 20:22:51,960 [salt.state       ][INFO    ][23636] Executing state pkg.installed for salt_minion_dependency_packages
2017-10-28 20:22:51,964 [salt.state       ][INFO    ][23636] All specified packages are already installed
2017-10-28 20:22:51,965 [salt.state       ][INFO    ][23636] Completed state [salt_minion_dependency_packages] at time 20:22:51.964490 duration_in_ms=5.093
2017-10-28 20:22:51,967 [salt.state       ][INFO    ][23636] Running state [/etc/salt/minion.d/minion.conf] at time 20:22:51.966944
2017-10-28 20:22:51,967 [salt.state       ][INFO    ][23636] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-10-28 20:22:51,989 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-10-28 20:22:52,063 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-28 20:22:52,132 [salt.fileclient  ][INFO    ][23636] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-28 20:22:52,199 [salt.state       ][INFO    ][23636] File /etc/salt/minion.d/minion.conf is in the correct state
2017-10-28 20:22:52,199 [salt.state       ][INFO    ][23636] Completed state [/etc/salt/minion.d/minion.conf] at time 20:22:52.199112 duration_in_ms=232.167
2017-10-28 20:22:52,200 [salt.state       ][INFO    ][23636] Running state [/etc/salt/minion.d/_virt.conf] at time 20:22:52.199576
2017-10-28 20:22:52,200 [salt.state       ][INFO    ][23636] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-10-28 20:22:52,201 [salt.state       ][INFO    ][23636] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-10-28 20:22:52,201 [salt.state       ][INFO    ][23636] Completed state [/etc/salt/minion.d/_virt.conf] at time 20:22:52.200939 duration_in_ms=1.363
2017-10-28 20:22:52,203 [salt.state       ][INFO    ][23636] Running state [salt-minion] at time 20:22:52.202752
2017-10-28 20:22:52,203 [salt.state       ][INFO    ][23636] Executing state service.running for salt-minion
2017-10-28 20:22:52,204 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-10-28 20:22:52,223 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-10-28 20:22:52,234 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-10-28 20:22:52,242 [salt.state       ][INFO    ][23636] The service salt-minion is already running
2017-10-28 20:22:52,243 [salt.state       ][INFO    ][23636] Completed state [salt-minion] at time 20:22:52.242573 duration_in_ms=39.821
2017-10-28 20:22:52,245 [salt.state       ][INFO    ][23636] Running state [/etc/salt/grains.d] at time 20:22:52.244630
2017-10-28 20:22:52,245 [salt.state       ][INFO    ][23636] Executing state file.directory for /etc/salt/grains.d
2017-10-28 20:22:52,247 [salt.state       ][INFO    ][23636] Directory /etc/salt/grains.d is in the correct state
2017-10-28 20:22:52,247 [salt.state       ][INFO    ][23636] Completed state [/etc/salt/grains.d] at time 20:22:52.246657 duration_in_ms=2.027
2017-10-28 20:22:52,247 [salt.state       ][INFO    ][23636] Running state [/etc/salt/grains] at time 20:22:52.247247
2017-10-28 20:22:52,248 [salt.state       ][INFO    ][23636] Executing state file.managed for /etc/salt/grains
2017-10-28 20:22:52,248 [salt.state       ][INFO    ][23636] File /etc/salt/grains exists with proper permissions. No changes made.
2017-10-28 20:22:52,248 [salt.state       ][INFO    ][23636] Completed state [/etc/salt/grains] at time 20:22:52.248057 duration_in_ms=0.81
2017-10-28 20:22:52,248 [salt.state       ][INFO    ][23636] Running state [/etc/salt/grains.d/placeholder] at time 20:22:52.248420
2017-10-28 20:22:52,249 [salt.state       ][INFO    ][23636] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-10-28 20:22:52,249 [salt.state       ][INFO    ][23636] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-10-28 20:22:52,251 [salt.state       ][INFO    ][23636] Completed state [/etc/salt/grains.d/placeholder] at time 20:22:52.250822 duration_in_ms=2.402
2017-10-28 20:22:52,251 [salt.state       ][INFO    ][23636] Running state [/etc/salt/grains.d/sphinx] at time 20:22:52.251200
2017-10-28 20:22:52,251 [salt.state       ][INFO    ][23636] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-10-28 20:22:52,253 [salt.state       ][INFO    ][23636] File changed:
--- 
+++ 
@@ -49,7 +49,6 @@
               - 127.0.0.1
               - 192.168.10.142
               - 192.168.11.252
-              - 192.168.122.1
         system:
           name: System
           param:

2017-10-28 20:22:52,253 [salt.state       ][INFO    ][23636] Completed state [/etc/salt/grains.d/sphinx] at time 20:22:52.253260 duration_in_ms=2.059
2017-10-28 20:22:52,254 [salt.state       ][INFO    ][23636] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:22:52.254342
2017-10-28 20:22:52,255 [salt.state       ][INFO    ][23636] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-10-28 20:22:52,255 [salt.state       ][INFO    ][23636] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-10-28 20:22:52,255 [salt.state       ][INFO    ][23636] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:22:52.254954 duration_in_ms=0.612
2017-10-28 20:22:52,255 [salt.state       ][INFO    ][23636] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:22:52.255430
2017-10-28 20:22:52,256 [salt.state       ][INFO    ][23636] Executing state cmd.mod_watch for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-10-28 20:22:52,257 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command 'python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"' in directory '/root'
2017-10-28 20:22:52,365 [salt.state       ][INFO    ][23636] {'pid': 23684, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-10-28 20:22:52,366 [salt.state       ][INFO    ][23636] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:22:52.366284 duration_in_ms=110.853
2017-10-28 20:22:52,367 [salt.state       ][INFO    ][23636] Running state [/etc/salt/grains.d/dns_records] at time 20:22:52.367379
2017-10-28 20:22:52,368 [salt.state       ][INFO    ][23636] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-10-28 20:22:52,369 [salt.state       ][INFO    ][23636] File /etc/salt/grains.d/dns_records is in the correct state
2017-10-28 20:22:52,370 [salt.state       ][INFO    ][23636] Completed state [/etc/salt/grains.d/dns_records] at time 20:22:52.369640 duration_in_ms=2.261
2017-10-28 20:22:52,372 [salt.state       ][INFO    ][23636] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:22:52.371723
2017-10-28 20:22:52,372 [salt.state       ][INFO    ][23636] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-10-28 20:22:52,372 [salt.state       ][INFO    ][23636] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-10-28 20:22:52,373 [salt.state       ][INFO    ][23636] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:22:52.372563 duration_in_ms=0.84
2017-10-28 20:22:52,373 [salt.state       ][INFO    ][23636] Running state [/etc/salt/grains.d/salt] at time 20:22:52.373114
2017-10-28 20:22:52,374 [salt.state       ][INFO    ][23636] Executing state file.managed for /etc/salt/grains.d/salt
2017-10-28 20:22:52,376 [salt.state       ][INFO    ][23636] File /etc/salt/grains.d/salt is in the correct state
2017-10-28 20:22:52,376 [salt.state       ][INFO    ][23636] Completed state [/etc/salt/grains.d/salt] at time 20:22:52.376252 duration_in_ms=3.138
2017-10-28 20:22:52,378 [salt.state       ][INFO    ][23636] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:22:52.377771
2017-10-28 20:22:52,378 [salt.state       ][INFO    ][23636] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-10-28 20:22:52,378 [salt.state       ][INFO    ][23636] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-10-28 20:22:52,379 [salt.state       ][INFO    ][23636] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:22:52.378516 duration_in_ms=0.744
2017-10-28 20:22:52,382 [salt.state       ][INFO    ][23636] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:22:52.382062
2017-10-28 20:22:52,383 [salt.state       ][INFO    ][23636] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-28 20:22:52,383 [salt.state       ][INFO    ][23636] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-28 20:22:52,383 [salt.state       ][INFO    ][23636] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:22:52.383023 duration_in_ms=0.961
2017-10-28 20:22:52,383 [salt.state       ][INFO    ][23636] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:22:52.383181
2017-10-28 20:22:52,383 [salt.state       ][INFO    ][23636] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-28 20:22:52,386 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-10-28 20:22:52,396 [salt.state       ][INFO    ][23636] {'pid': 23686, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-10-28 20:22:52,396 [salt.state       ][INFO    ][23636] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:22:52.395856 duration_in_ms=12.675
2017-10-28 20:22:52,398 [salt.state       ][INFO    ][23636] Running state [mine.update] at time 20:22:52.397733
2017-10-28 20:22:52,399 [salt.state       ][INFO    ][23636] Executing state module.wait for mine.update
2017-10-28 20:22:52,399 [salt.state       ][INFO    ][23636] No changes made for mine.update
2017-10-28 20:22:52,399 [salt.state       ][INFO    ][23636] Completed state [mine.update] at time 20:22:52.398965 duration_in_ms=1.232
2017-10-28 20:22:52,399 [salt.state       ][INFO    ][23636] Running state [mine.update] at time 20:22:52.399128
2017-10-28 20:22:52,399 [salt.state       ][INFO    ][23636] Executing state module.mod_watch for mine.update
2017-10-28 20:22:52,937 [salt.state       ][INFO    ][23636] {'ret': True}
2017-10-28 20:22:52,938 [salt.state       ][INFO    ][23636] Completed state [mine.update] at time 20:22:52.937504 duration_in_ms=538.373
2017-10-28 20:22:52,938 [salt.state       ][INFO    ][23636] Running state [ca-certificates] at time 20:22:52.938025
2017-10-28 20:22:52,938 [salt.state       ][INFO    ][23636] Executing state pkg.installed for ca-certificates
2017-10-28 20:22:52,949 [salt.state       ][INFO    ][23636] Package ca-certificates is already installed
2017-10-28 20:22:52,950 [salt.state       ][INFO    ][23636] Completed state [ca-certificates] at time 20:22:52.949665 duration_in_ms=11.641
2017-10-28 20:22:52,951 [salt.state       ][INFO    ][23636] Running state [update-ca-certificates] at time 20:22:52.950927
2017-10-28 20:22:52,951 [salt.state       ][INFO    ][23636] Executing state cmd.wait for update-ca-certificates
2017-10-28 20:22:52,951 [salt.state       ][INFO    ][23636] No changes made for update-ca-certificates
2017-10-28 20:22:52,952 [salt.state       ][INFO    ][23636] Completed state [update-ca-certificates] at time 20:22:52.951543 duration_in_ms=0.615
2017-10-28 20:22:52,952 [salt.state       ][INFO    ][23636] Running state [libvirt-dev] at time 20:22:52.951729
2017-10-28 20:22:52,952 [salt.state       ][INFO    ][23636] Executing state pkg.installed for libvirt-dev
2017-10-28 20:22:52,957 [salt.state       ][INFO    ][23636] Package libvirt-dev is already installed
2017-10-28 20:22:52,957 [salt.state       ][INFO    ][23636] Completed state [libvirt-dev] at time 20:22:52.956874 duration_in_ms=5.145
2017-10-28 20:22:52,957 [salt.state       ][INFO    ][23636] Running state [pkg-config] at time 20:22:52.957395
2017-10-28 20:22:52,958 [salt.state       ][INFO    ][23636] Executing state pkg.installed for pkg-config
2017-10-28 20:22:52,963 [salt.state       ][INFO    ][23636] Package pkg-config is already installed
2017-10-28 20:22:52,963 [salt.state       ][INFO    ][23636] Completed state [pkg-config] at time 20:22:52.962934 duration_in_ms=5.539
2017-10-28 20:22:52,963 [salt.state       ][INFO    ][23636] Running state [virtng.init] at time 20:22:52.963148
2017-10-28 20:22:52,963 [salt.state       ][INFO    ][23636] Executing state module.run for virtng.init
2017-10-28 20:22:53,047 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command ['git', '--version'] in directory '/root'
2017-10-28 20:22:53,117 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command 'virsh list --all --name| grep -E "^mdb02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-28 20:22:53,141 [salt.state       ][INFO    ][23636] unless execution succeeded
2017-10-28 20:22:53,142 [salt.state       ][INFO    ][23636] Completed state [virtng.init] at time 20:22:53.141034 duration_in_ms=177.885
2017-10-28 20:22:53,142 [salt.state       ][INFO    ][23636] Running state [virt.set_autostart] at time 20:22:53.142390
2017-10-28 20:22:53,143 [salt.state       ][INFO    ][23636] Executing state module.run for virt.set_autostart
2017-10-28 20:22:53,150 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command 'virsh list --autostart --name| grep -E "^mdb02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-28 20:22:53,183 [salt.state       ][INFO    ][23636] unless execution succeeded
2017-10-28 20:22:53,183 [salt.state       ][INFO    ][23636] Completed state [virt.set_autostart] at time 20:22:53.182760 duration_in_ms=40.37
2017-10-28 20:22:53,183 [salt.state       ][INFO    ][23636] Running state [virtng.init] at time 20:22:53.183315
2017-10-28 20:22:53,184 [salt.state       ][INFO    ][23636] Executing state module.run for virtng.init
2017-10-28 20:22:53,188 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command 'virsh list --all --name| grep -E "^dbs02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-28 20:22:53,218 [salt.state       ][INFO    ][23636] unless execution succeeded
2017-10-28 20:22:53,219 [salt.state       ][INFO    ][23636] Completed state [virtng.init] at time 20:22:53.218691 duration_in_ms=35.376
2017-10-28 20:22:53,219 [salt.state       ][INFO    ][23636] Running state [virt.set_autostart] at time 20:22:53.218954
2017-10-28 20:22:53,219 [salt.state       ][INFO    ][23636] Executing state module.run for virt.set_autostart
2017-10-28 20:22:53,224 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command 'virsh list --autostart --name| grep -E "^dbs02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-28 20:22:53,255 [salt.state       ][INFO    ][23636] unless execution succeeded
2017-10-28 20:22:53,256 [salt.state       ][INFO    ][23636] Completed state [virt.set_autostart] at time 20:22:53.255848 duration_in_ms=36.893
2017-10-28 20:22:53,256 [salt.state       ][INFO    ][23636] Running state [virtng.init] at time 20:22:53.256229
2017-10-28 20:22:53,257 [salt.state       ][INFO    ][23636] Executing state module.run for virtng.init
2017-10-28 20:22:53,260 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command 'virsh list --all --name| grep -E "^ctl02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-28 20:22:53,290 [salt.state       ][INFO    ][23636] unless execution succeeded
2017-10-28 20:22:53,291 [salt.state       ][INFO    ][23636] Completed state [virtng.init] at time 20:22:53.290820 duration_in_ms=34.59
2017-10-28 20:22:53,291 [salt.state       ][INFO    ][23636] Running state [virt.set_autostart] at time 20:22:53.291157
2017-10-28 20:22:53,291 [salt.state       ][INFO    ][23636] Executing state module.run for virt.set_autostart
2017-10-28 20:22:53,296 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command 'virsh list --autostart --name| grep -E "^ctl02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-28 20:22:53,324 [salt.state       ][INFO    ][23636] unless execution succeeded
2017-10-28 20:22:53,325 [salt.state       ][INFO    ][23636] Completed state [virt.set_autostart] at time 20:22:53.324714 duration_in_ms=33.557
2017-10-28 20:22:53,325 [salt.state       ][INFO    ][23636] Running state [virtng.init] at time 20:22:53.324993
2017-10-28 20:22:53,327 [salt.state       ][INFO    ][23636] Executing state module.run for virtng.init
2017-10-28 20:22:53,332 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command 'virsh list --all --name| grep -E "^msg02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-28 20:22:53,366 [salt.state       ][INFO    ][23636] unless execution succeeded
2017-10-28 20:22:53,366 [salt.state       ][INFO    ][23636] Completed state [virtng.init] at time 20:22:53.366270 duration_in_ms=41.276
2017-10-28 20:22:53,367 [salt.state       ][INFO    ][23636] Running state [virt.set_autostart] at time 20:22:53.366707
2017-10-28 20:22:53,367 [salt.state       ][INFO    ][23636] Executing state module.run for virt.set_autostart
2017-10-28 20:22:53,376 [salt.loaded.int.module.cmdmod][INFO    ][23636] Executing command 'virsh list --autostart --name| grep -E "^msg02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-10-28 20:22:53,409 [salt.state       ][INFO    ][23636] unless execution succeeded
2017-10-28 20:22:53,412 [salt.state       ][INFO    ][23636] Completed state [virt.set_autostart] at time 20:22:53.411738 duration_in_ms=45.031
2017-10-28 20:22:53,418 [salt.minion      ][INFO    ][23636] Returning information for job: 20171028202245256904
2017-10-28 20:23:19,639 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171028202319634503
2017-10-28 20:23:19,665 [salt.minion      ][INFO    ][23733] Starting a new job with PID 23733
2017-10-28 20:23:23,322 [salt.state       ][INFO    ][23733] Loading fresh modules for state activity
2017-10-28 20:23:24,048 [salt.state       ][ERROR   ][23733] No contents found in top file
2017-10-28 20:23:24,050 [salt.utils.extmods][INFO    ][23733] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-10-28 20:23:24,059 [salt.utils.extmods][INFO    ][23733] Syncing beacons for environment 'base'
2017-10-28 20:23:24,060 [salt.utils.extmods][INFO    ][23733] Loading cache from salt://_beacons, for base)
2017-10-28 20:23:24,061 [salt.fileclient  ][INFO    ][23733] Caching directory '_beacons/' for environment 'base'
2017-10-28 20:23:24,110 [salt.utils.extmods][INFO    ][23733] Syncing modules for environment 'base'
2017-10-28 20:23:24,110 [salt.utils.extmods][INFO    ][23733] Loading cache from salt://_modules, for base)
2017-10-28 20:23:24,110 [salt.fileclient  ][INFO    ][23733] Caching directory '_modules/' for environment 'base'
2017-10-28 20:23:24,180 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-10-28 20:23:24,326 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-10-28 20:23:24,348 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-10-28 20:23:24,360 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-10-28 20:23:24,375 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-10-28 20:23:24,396 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-10-28 20:23:24,453 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-10-28 20:23:24,482 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-10-28 20:23:24,507 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-10-28 20:23:24,528 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-10-28 20:23:24,555 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-10-28 20:23:24,577 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-10-28 20:23:24,632 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-10-28 20:23:24,661 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-10-28 20:23:24,679 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-10-28 20:23:24,702 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-10-28 20:23:24,714 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-10-28 20:23:24,733 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-10-28 20:23:24,752 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-10-28 20:23:24,766 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-10-28 20:23:24,788 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-10-28 20:23:24,810 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-10-28 20:23:24,839 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-10-28 20:23:24,860 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-10-28 20:23:24,878 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-10-28 20:23:24,896 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-10-28 20:23:24,910 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-10-28 20:23:24,951 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-10-28 20:23:24,971 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-10-28 20:23:24,990 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-10-28 20:23:25,004 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-10-28 20:23:25,022 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-10-28 20:23:25,032 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-10-28 20:23:25,032 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-10-28 20:23:25,033 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-10-28 20:23:25,034 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-10-28 20:23:25,034 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-10-28 20:23:25,034 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-10-28 20:23:25,035 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-10-28 20:23:25,035 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-10-28 20:23:25,036 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-10-28 20:23:25,036 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-10-28 20:23:25,037 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-10-28 20:23:25,037 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-10-28 20:23:25,037 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-10-28 20:23:25,038 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-10-28 20:23:25,038 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-10-28 20:23:25,039 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-10-28 20:23:25,039 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-10-28 20:23:25,039 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-10-28 20:23:25,040 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-10-28 20:23:25,040 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-10-28 20:23:25,040 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-10-28 20:23:25,041 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-10-28 20:23:25,041 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-10-28 20:23:25,041 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-10-28 20:23:25,042 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-10-28 20:23:25,042 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-10-28 20:23:25,042 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-10-28 20:23:25,043 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-10-28 20:23:25,043 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-10-28 20:23:25,044 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-10-28 20:23:25,044 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-10-28 20:23:25,044 [salt.utils.extmods][INFO    ][23733] 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-10-28 20:23:25,045 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-10-28 20:23:25,045 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-10-28 20:23:25,054 [salt.utils.extmods][INFO    ][23733] Syncing states for environment 'base'
2017-10-28 20:23:25,055 [salt.utils.extmods][INFO    ][23733] Loading cache from salt://_states, for base)
2017-10-28 20:23:25,055 [salt.fileclient  ][INFO    ][23733] Caching directory '_states/' for environment 'base'
2017-10-28 20:23:25,146 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-10-28 20:23:25,167 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-10-28 20:23:25,197 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-10-28 20:23:25,227 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-10-28 20:23:25,254 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-10-28 20:23:25,268 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-10-28 20:23:25,284 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-10-28 20:23:25,299 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-10-28 20:23:25,321 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-10-28 20:23:25,340 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-10-28 20:23:25,362 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-10-28 20:23:25,379 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-10-28 20:23:25,398 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-10-28 20:23:25,423 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-10-28 20:23:25,442 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-10-28 20:23:25,459 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-10-28 20:23:25,476 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-10-28 20:23:25,494 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-10-28 20:23:25,517 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-10-28 20:23:25,538 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-10-28 20:23:25,564 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-10-28 20:23:25,581 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-10-28 20:23:25,600 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-10-28 20:23:25,651 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-10-28 20:23:25,672 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-10-28 20:23:25,705 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-10-28 20:23:25,730 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-10-28 20:23:25,760 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-10-28 20:23:25,783 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-10-28 20:23:25,792 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-10-28 20:23:25,793 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-10-28 20:23:25,795 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-10-28 20:23:25,795 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-10-28 20:23:25,795 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-10-28 20:23:25,796 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-10-28 20:23:25,796 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-10-28 20:23:25,797 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-10-28 20:23:25,797 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-10-28 20:23:25,798 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-10-28 20:23:25,798 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-10-28 20:23:25,798 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-10-28 20:23:25,799 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-10-28 20:23:25,799 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-10-28 20:23:25,799 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-10-28 20:23:25,799 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-10-28 20:23:25,800 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-10-28 20:23:25,800 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-10-28 20:23:25,801 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-10-28 20:23:25,801 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-10-28 20:23:25,801 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-10-28 20:23:25,802 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-10-28 20:23:25,802 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-10-28 20:23:25,802 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-10-28 20:23:25,803 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-10-28 20:23:25,803 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-10-28 20:23:25,803 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-10-28 20:23:25,804 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-10-28 20:23:25,804 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-10-28 20:23:25,805 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-10-28 20:23:25,809 [salt.utils.extmods][INFO    ][23733] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-10-28 20:23:25,817 [salt.utils.extmods][INFO    ][23733] Syncing sdb for environment 'base'
2017-10-28 20:23:25,818 [salt.utils.extmods][INFO    ][23733] Loading cache from salt://_sdb, for base)
2017-10-28 20:23:25,818 [salt.fileclient  ][INFO    ][23733] Caching directory '_sdb/' for environment 'base'
2017-10-28 20:23:25,907 [salt.utils.extmods][INFO    ][23733] Syncing grains for environment 'base'
2017-10-28 20:23:25,907 [salt.utils.extmods][INFO    ][23733] Loading cache from salt://_grains, for base)
2017-10-28 20:23:25,907 [salt.fileclient  ][INFO    ][23733] Caching directory '_grains/' for environment 'base'
2017-10-28 20:23:25,989 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-10-28 20:23:26,007 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-10-28 20:23:26,028 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-10-28 20:23:26,076 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-10-28 20:23:26,105 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-10-28 20:23:26,128 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-10-28 20:23:26,139 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-10-28 20:23:26,203 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-10-28 20:23:26,223 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-10-28 20:23:26,279 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-10-28 20:23:26,295 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-10-28 20:23:26,311 [salt.fileclient  ][INFO    ][23733] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-10-28 20:23:26,320 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-10-28 20:23:26,320 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-10-28 20:23:26,320 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-10-28 20:23:26,321 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-10-28 20:23:26,321 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-10-28 20:23:26,321 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-10-28 20:23:26,322 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-10-28 20:23:26,322 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-10-28 20:23:26,322 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-10-28 20:23:26,323 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-10-28 20:23:26,323 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-10-28 20:23:26,323 [salt.utils.extmods][INFO    ][23733] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-10-28 20:23:26,326 [salt.utils.extmods][INFO    ][23733] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-10-28 20:23:26,337 [salt.utils.extmods][INFO    ][23733] Syncing renderers for environment 'base'
2017-10-28 20:23:26,337 [salt.utils.extmods][INFO    ][23733] Loading cache from salt://_renderers, for base)
2017-10-28 20:23:26,338 [salt.fileclient  ][INFO    ][23733] Caching directory '_renderers/' for environment 'base'
2017-10-28 20:23:26,439 [salt.utils.extmods][INFO    ][23733] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-10-28 20:23:26,443 [salt.utils.extmods][INFO    ][23733] Syncing returners for environment 'base'
2017-10-28 20:23:26,443 [salt.utils.extmods][INFO    ][23733] Loading cache from salt://_returners, for base)
2017-10-28 20:23:26,443 [salt.fileclient  ][INFO    ][23733] Caching directory '_returners/' for environment 'base'
2017-10-28 20:23:26,528 [salt.utils.extmods][INFO    ][23733] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-10-28 20:23:26,532 [salt.utils.extmods][INFO    ][23733] Syncing output for environment 'base'
2017-10-28 20:23:26,532 [salt.utils.extmods][INFO    ][23733] Loading cache from salt://_output, for base)
2017-10-28 20:23:26,532 [salt.fileclient  ][INFO    ][23733] Caching directory '_output/' for environment 'base'
2017-10-28 20:23:26,603 [salt.utils.extmods][INFO    ][23733] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-10-28 20:23:26,606 [salt.utils.extmods][INFO    ][23733] Syncing utils for environment 'base'
2017-10-28 20:23:26,606 [salt.utils.extmods][INFO    ][23733] Loading cache from salt://_utils, for base)
2017-10-28 20:23:26,607 [salt.fileclient  ][INFO    ][23733] Caching directory '_utils/' for environment 'base'
2017-10-28 20:23:26,650 [salt.utils.extmods][INFO    ][23733] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-10-28 20:23:26,654 [salt.utils.extmods][INFO    ][23733] Syncing log_handlers for environment 'base'
2017-10-28 20:23:26,654 [salt.utils.extmods][INFO    ][23733] Loading cache from salt://_log_handlers, for base)
2017-10-28 20:23:26,654 [salt.fileclient  ][INFO    ][23733] Caching directory '_log_handlers/' for environment 'base'
2017-10-28 20:23:26,701 [salt.utils.extmods][INFO    ][23733] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-10-28 20:23:26,705 [salt.utils.extmods][INFO    ][23733] Syncing proxy for environment 'base'
2017-10-28 20:23:26,706 [salt.utils.extmods][INFO    ][23733] Loading cache from salt://_proxy, for base)
2017-10-28 20:23:26,706 [salt.fileclient  ][INFO    ][23733] Caching directory '_proxy/' for environment 'base'
2017-10-28 20:23:26,754 [salt.utils.extmods][INFO    ][23733] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-10-28 20:23:26,758 [salt.utils.extmods][INFO    ][23733] Syncing engines for environment 'base'
2017-10-28 20:23:26,758 [salt.utils.extmods][INFO    ][23733] Loading cache from salt://_engines, for base)
2017-10-28 20:23:26,758 [salt.fileclient  ][INFO    ][23733] Caching directory '_engines/' for environment 'base'
2017-10-28 20:23:26,803 [salt.minion      ][INFO    ][23733] Returning information for job: 20171028202319634503
2017-10-28 20:24:47,312 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command test.ping with jid 20171028202447309089
2017-10-28 20:24:47,335 [salt.minion      ][INFO    ][23867] Starting a new job with PID 23867
2017-10-28 20:24:47,389 [salt.minion      ][INFO    ][23867] Returning information for job: 20171028202447309089
2017-10-28 20:26:32,866 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command state.sls with jid 20171028202632854118
2017-10-28 20:26:32,890 [salt.minion      ][INFO    ][23882] Starting a new job with PID 23882
2017-10-28 20:26:33,485 [salt.state       ][INFO    ][23882] Loading fresh modules for state activity
2017-10-28 20:26:33,538 [salt.fileclient  ][INFO    ][23882] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-10-28 20:26:33,566 [salt.fileclient  ][INFO    ][23882] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-10-28 20:26:33,642 [salt.fileclient  ][INFO    ][23882] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-28 20:26:34,035 [salt.state       ][INFO    ][23882] Running state [lsof] at time 20:26:34.035414
2017-10-28 20:26:34,036 [salt.state       ][INFO    ][23882] Executing state pkg.installed for lsof
2017-10-28 20:26:34,036 [salt.loaded.int.module.cmdmod][INFO    ][23882] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-28 20:26:34,353 [salt.state       ][INFO    ][23882] Package lsof is already installed
2017-10-28 20:26:34,353 [salt.state       ][INFO    ][23882] Completed state [lsof] at time 20:26:34.352902 duration_in_ms=317.475
2017-10-28 20:26:34,354 [salt.state       ][INFO    ][23882] Running state [keepalived] at time 20:26:34.353519
2017-10-28 20:26:34,354 [salt.state       ][INFO    ][23882] Executing state pkg.installed for keepalived
2017-10-28 20:26:34,377 [salt.loaded.int.module.cmdmod][INFO    ][23882] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-10-28 20:26:36,835 [salt.loaded.int.module.cmdmod][INFO    ][23882] 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-10-28 20:26:41,289 [salt.loaded.int.module.cmdmod][INFO    ][23882] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-28 20:26:41,340 [salt.state       ][INFO    ][23882] 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-10-28 20:26:41,351 [salt.state       ][INFO    ][23882] Loading fresh modules for state activity
2017-10-28 20:26:41,369 [salt.state       ][INFO    ][23882] Completed state [keepalived] at time 20:26:41.368627 duration_in_ms=7015.108
2017-10-28 20:26:41,371 [salt.state       ][INFO    ][23882] Running state [/etc/keepalived/keepalived.conf] at time 20:26:41.371359
2017-10-28 20:26:41,372 [salt.state       ][INFO    ][23882] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-10-28 20:26:41,406 [salt.fileclient  ][INFO    ][23882] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-10-28 20:26:41,456 [salt.fileclient  ][INFO    ][23882] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-28 20:26:41,471 [salt.state       ][INFO    ][23882] File changed:
New file
2017-10-28 20:26:41,472 [salt.state       ][INFO    ][23882] Completed state [/etc/keepalived/keepalived.conf] at time 20:26:41.471812 duration_in_ms=100.453
2017-10-28 20:26:41,596 [salt.state       ][INFO    ][23882] Running state [keepalived] at time 20:26:41.595964
2017-10-28 20:26:41,597 [salt.state       ][INFO    ][23882] Executing state service.running for keepalived
2017-10-28 20:26:41,600 [salt.loaded.int.module.cmdmod][INFO    ][23882] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-10-28 20:26:41,619 [salt.loaded.int.module.cmdmod][INFO    ][23882] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-28 20:26:41,637 [salt.loaded.int.module.cmdmod][INFO    ][23882] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-28 20:26:41,652 [salt.loaded.int.module.cmdmod][INFO    ][23882] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-28 20:26:41,672 [salt.loaded.int.module.cmdmod][INFO    ][23882] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-10-28 20:26:41,747 [salt.loaded.int.module.cmdmod][INFO    ][23882] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-28 20:26:41,767 [salt.loaded.int.module.cmdmod][INFO    ][23882] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-28 20:26:41,781 [salt.loaded.int.module.cmdmod][INFO    ][23882] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-28 20:26:41,798 [salt.state       ][INFO    ][23882] {'keepalived': True}
2017-10-28 20:26:41,799 [salt.state       ][INFO    ][23882] Completed state [keepalived] at time 20:26:41.798548 duration_in_ms=202.585
2017-10-28 20:26:41,800 [salt.minion      ][INFO    ][23882] Returning information for job: 20171028202632854118
2017-10-28 20:27:02,888 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command pillar.get with jid 20171028202702880926
2017-10-28 20:27:02,915 [salt.minion      ][INFO    ][25449] Starting a new job with PID 25449
2017-10-28 20:27:02,922 [salt.minion      ][INFO    ][25449] Returning information for job: 20171028202702880926
2017-10-28 20:32:28,212 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command state.sls with jid 20171028203228201835
2017-10-28 20:32:28,238 [salt.minion      ][INFO    ][25534] Starting a new job with PID 25534
2017-10-28 20:32:30,869 [salt.state       ][INFO    ][25534] Loading fresh modules for state activity
2017-10-28 20:32:30,915 [salt.fileclient  ][INFO    ][25534] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-28 20:32:30,948 [salt.fileclient  ][INFO    ][25534] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-28 20:32:31,311 [salt.state       ][INFO    ][25534] Running state [attr] at time 20:32:31.311293
2017-10-28 20:32:31,312 [salt.state       ][INFO    ][25534] Executing state pkg.installed for attr
2017-10-28 20:32:31,312 [salt.loaded.int.module.cmdmod][INFO    ][25534] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-28 20:32:31,621 [salt.loaded.int.module.cmdmod][INFO    ][25534] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-10-28 20:32:34,134 [salt.loaded.int.module.cmdmod][INFO    ][25534] 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-10-28 20:32:36,094 [salt.loaded.int.module.cmdmod][INFO    ][25534] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-28 20:32:36,145 [salt.state       ][INFO    ][25534] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-10-28 20:32:36,157 [salt.state       ][INFO    ][25534] Loading fresh modules for state activity
2017-10-28 20:32:36,177 [salt.state       ][INFO    ][25534] Completed state [attr] at time 20:32:36.176532 duration_in_ms=4865.24
2017-10-28 20:32:36,187 [salt.state       ][INFO    ][25534] Running state [glusterfs-server] at time 20:32:36.186935
2017-10-28 20:32:36,187 [salt.state       ][INFO    ][25534] Executing state pkg.installed for glusterfs-server
2017-10-28 20:32:36,523 [salt.loaded.int.module.cmdmod][INFO    ][25534] 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-10-28 20:32:38,333 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command saltutil.find_job with jid 20171028203238318620
2017-10-28 20:32:38,358 [salt.minion      ][INFO    ][26605] Starting a new job with PID 26605
2017-10-28 20:32:38,377 [salt.minion      ][INFO    ][26605] Returning information for job: 20171028203238318620
2017-10-28 20:32:47,378 [salt.loaded.int.module.cmdmod][INFO    ][25534] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-28 20:32:47,432 [salt.state       ][INFO    ][25534] 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-10-28 20:32:47,444 [salt.state       ][INFO    ][25534] Loading fresh modules for state activity
2017-10-28 20:32:47,465 [salt.state       ][INFO    ][25534] Completed state [glusterfs-server] at time 20:32:47.464618 duration_in_ms=11277.683
2017-10-28 20:32:47,471 [salt.state       ][INFO    ][25534] Running state [bc] at time 20:32:47.471080
2017-10-28 20:32:47,471 [salt.state       ][INFO    ][25534] Executing state pkg.installed for bc
2017-10-28 20:32:47,812 [salt.loaded.int.module.cmdmod][INFO    ][25534] 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-10-28 20:32:48,484 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command saltutil.find_job with jid 20171028203248470299
2017-10-28 20:32:48,507 [salt.minion      ][INFO    ][29903] Starting a new job with PID 29903
2017-10-28 20:32:48,524 [salt.minion      ][INFO    ][29903] Returning information for job: 20171028203248470299
2017-10-28 20:32:49,964 [salt.loaded.int.module.cmdmod][INFO    ][25534] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-28 20:32:50,020 [salt.state       ][INFO    ][25534] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-10-28 20:32:50,031 [salt.state       ][INFO    ][25534] Loading fresh modules for state activity
2017-10-28 20:32:50,071 [salt.state       ][INFO    ][25534] Completed state [bc] at time 20:32:50.071202 duration_in_ms=2600.121
2017-10-28 20:32:50,171 [salt.state       ][INFO    ][25534] Running state [glusterfs-server] at time 20:32:50.171361
2017-10-28 20:32:50,172 [salt.state       ][INFO    ][25534] Executing state service.running for glusterfs-server
2017-10-28 20:32:50,175 [salt.loaded.int.module.cmdmod][INFO    ][25534] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-28 20:32:50,192 [salt.loaded.int.module.cmdmod][INFO    ][25534] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-28 20:32:50,209 [salt.loaded.int.module.cmdmod][INFO    ][25534] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-28 20:32:50,229 [salt.state       ][INFO    ][25534] The service glusterfs-server is already running
2017-10-28 20:32:50,229 [salt.state       ][INFO    ][25534] Completed state [glusterfs-server] at time 20:32:50.229007 duration_in_ms=57.647
2017-10-28 20:32:50,233 [salt.state       ][INFO    ][25534] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 20:32:50.232880
2017-10-28 20:32:50,235 [salt.state       ][INFO    ][25534] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-28 20:32:50,248 [salt.state       ][INFO    ][25534] File changed:
New file
2017-10-28 20:32:50,248 [salt.state       ][INFO    ][25534] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 20:32:50.248246 duration_in_ms=15.366
2017-10-28 20:32:50,249 [salt.state       ][INFO    ][25534] Running state [/srv/glusterfs/glance] at time 20:32:50.248601
2017-10-28 20:32:50,251 [salt.state       ][INFO    ][25534] Executing state file.directory for /srv/glusterfs/glance
2017-10-28 20:32:50,252 [salt.state       ][INFO    ][25534] {'/srv/glusterfs/glance': 'New Dir'}
2017-10-28 20:32:50,252 [salt.state       ][INFO    ][25534] Completed state [/srv/glusterfs/glance] at time 20:32:50.251989 duration_in_ms=3.388
2017-10-28 20:32:50,252 [salt.state       ][INFO    ][25534] Running state [/srv/glusterfs/keystone-keys] at time 20:32:50.252313
2017-10-28 20:32:50,253 [salt.state       ][INFO    ][25534] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-28 20:32:50,255 [salt.state       ][INFO    ][25534] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-10-28 20:32:50,255 [salt.state       ][INFO    ][25534] Completed state [/srv/glusterfs/keystone-keys] at time 20:32:50.255086 duration_in_ms=2.772
2017-10-28 20:32:50,255 [salt.state       ][INFO    ][25534] Running state [/srv/glusterfs/nova_instances] at time 20:32:50.255408
2017-10-28 20:32:50,256 [salt.state       ][INFO    ][25534] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-28 20:32:50,257 [salt.state       ][INFO    ][25534] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-10-28 20:32:50,257 [salt.state       ][INFO    ][25534] Completed state [/srv/glusterfs/nova_instances] at time 20:32:50.256821 duration_in_ms=1.413
2017-10-28 20:32:50,259 [salt.state       ][INFO    ][25534] Running state [/srv/glusterfs/keystone-credential-keys] at time 20:32:50.258500
2017-10-28 20:32:50,259 [salt.state       ][INFO    ][25534] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-28 20:32:50,259 [salt.state       ][INFO    ][25534] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-10-28 20:32:50,259 [salt.state       ][INFO    ][25534] Completed state [/srv/glusterfs/keystone-credential-keys] at time 20:32:50.259373 duration_in_ms=0.872
2017-10-28 20:32:50,260 [salt.minion      ][INFO    ][25534] Returning information for job: 20171028203228201835
2017-10-28 20:32:51,350 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command test.ping with jid 20171028203251343200
2017-10-28 20:32:51,369 [salt.minion      ][INFO    ][30294] Starting a new job with PID 30294
2017-10-28 20:32:51,411 [salt.minion      ][INFO    ][30294] Returning information for job: 20171028203251343200
2017-10-28 20:32:51,546 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command state.sls with jid 20171028203251533684
2017-10-28 20:32:51,564 [salt.minion      ][INFO    ][30299] Starting a new job with PID 30299
2017-10-28 20:32:53,801 [salt.state       ][INFO    ][30299] Loading fresh modules for state activity
2017-10-28 20:32:53,831 [salt.fileclient  ][INFO    ][30299] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-10-28 20:32:53,879 [salt.fileclient  ][INFO    ][30299] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-28 20:32:53,979 [salt.fileclient  ][INFO    ][30299] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-28 20:32:54,010 [salt.fileclient  ][INFO    ][30299] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-28 20:32:54,365 [salt.state       ][INFO    ][30299] Running state [attr] at time 20:32:54.364782
2017-10-28 20:32:54,365 [salt.state       ][INFO    ][30299] Executing state pkg.installed for attr
2017-10-28 20:32:54,366 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-28 20:32:54,678 [salt.state       ][INFO    ][30299] Package attr is already installed
2017-10-28 20:32:54,678 [salt.state       ][INFO    ][30299] Completed state [attr] at time 20:32:54.678398 duration_in_ms=313.617
2017-10-28 20:32:54,679 [salt.state       ][INFO    ][30299] Running state [glusterfs-server] at time 20:32:54.678630
2017-10-28 20:32:54,679 [salt.state       ][INFO    ][30299] Executing state pkg.installed for glusterfs-server
2017-10-28 20:32:54,683 [salt.state       ][INFO    ][30299] Package glusterfs-server is already installed
2017-10-28 20:32:54,683 [salt.state       ][INFO    ][30299] Completed state [glusterfs-server] at time 20:32:54.682977 duration_in_ms=4.348
2017-10-28 20:32:54,683 [salt.state       ][INFO    ][30299] Running state [bc] at time 20:32:54.683138
2017-10-28 20:32:54,683 [salt.state       ][INFO    ][30299] Executing state pkg.installed for bc
2017-10-28 20:32:54,687 [salt.state       ][INFO    ][30299] Package bc is already installed
2017-10-28 20:32:54,687 [salt.state       ][INFO    ][30299] Completed state [bc] at time 20:32:54.686971 duration_in_ms=3.832
2017-10-28 20:32:54,688 [salt.state       ][INFO    ][30299] Running state [glusterfs-server] at time 20:32:54.687911
2017-10-28 20:32:54,688 [salt.state       ][INFO    ][30299] Executing state service.running for glusterfs-server
2017-10-28 20:32:54,689 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-28 20:32:54,708 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-10-28 20:32:54,803 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-28 20:32:54,833 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-28 20:32:54,847 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-28 20:32:54,869 [salt.state       ][INFO    ][30299] The service glusterfs-server is already running
2017-10-28 20:32:54,870 [salt.state       ][INFO    ][30299] Completed state [glusterfs-server] at time 20:32:54.869791 duration_in_ms=181.879
2017-10-28 20:32:54,874 [salt.state       ][INFO    ][30299] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 20:32:54.873037
2017-10-28 20:32:54,874 [salt.state       ][INFO    ][30299] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-28 20:32:54,878 [salt.state       ][INFO    ][30299] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-28 20:32:54,879 [salt.state       ][INFO    ][30299] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 20:32:54.878491 duration_in_ms=5.454
2017-10-28 20:32:54,879 [salt.state       ][INFO    ][30299] Running state [/srv/glusterfs/glance] at time 20:32:54.878815
2017-10-28 20:32:54,879 [salt.state       ][INFO    ][30299] Executing state file.directory for /srv/glusterfs/glance
2017-10-28 20:32:54,880 [salt.state       ][INFO    ][30299] Directory /srv/glusterfs/glance is in the correct state
2017-10-28 20:32:54,880 [salt.state       ][INFO    ][30299] Completed state [/srv/glusterfs/glance] at time 20:32:54.880394 duration_in_ms=1.579
2017-10-28 20:32:54,881 [salt.state       ][INFO    ][30299] Running state [/srv/glusterfs/keystone-keys] at time 20:32:54.880703
2017-10-28 20:32:54,881 [salt.state       ][INFO    ][30299] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-28 20:32:54,883 [salt.state       ][INFO    ][30299] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-28 20:32:54,884 [salt.state       ][INFO    ][30299] Completed state [/srv/glusterfs/keystone-keys] at time 20:32:54.883487 duration_in_ms=2.785
2017-10-28 20:32:54,884 [salt.state       ][INFO    ][30299] Running state [/srv/glusterfs/nova_instances] at time 20:32:54.884131
2017-10-28 20:32:54,884 [salt.state       ][INFO    ][30299] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-28 20:32:54,886 [salt.state       ][INFO    ][30299] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-28 20:32:54,886 [salt.state       ][INFO    ][30299] Completed state [/srv/glusterfs/nova_instances] at time 20:32:54.885643 duration_in_ms=1.513
2017-10-28 20:32:54,886 [salt.state       ][INFO    ][30299] Running state [/srv/glusterfs/keystone-credential-keys] at time 20:32:54.885849
2017-10-28 20:32:54,886 [salt.state       ][INFO    ][30299] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-28 20:32:54,886 [salt.state       ][INFO    ][30299] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-28 20:32:54,887 [salt.state       ][INFO    ][30299] Completed state [/srv/glusterfs/keystone-credential-keys] at time 20:32:54.886567 duration_in_ms=0.718
2017-10-28 20:32:54,964 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command ['git', '--version'] in directory '/root'
2017-10-28 20:32:55,088 [salt.state       ][INFO    ][30299] Running state [sleep 5] at time 20:32:55.088318
2017-10-28 20:32:55,089 [salt.state       ][INFO    ][30299] Executing state cmd.wait for sleep 5
2017-10-28 20:32:55,089 [salt.state       ][INFO    ][30299] No changes made for sleep 5
2017-10-28 20:32:55,089 [salt.state       ][INFO    ][30299] Completed state [sleep 5] at time 20:32:55.088946 duration_in_ms=0.628
2017-10-28 20:32:55,090 [salt.state       ][INFO    ][30299] Running state [192.168.10.143] at time 20:32:55.089810
2017-10-28 20:32:55,090 [salt.state       ][INFO    ][30299] Executing state glusterfs.peered for 192.168.10.143
2017-10-28 20:32:55,090 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:32:55,109 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:32:55,188 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:32:55,211 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:32:55,317 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:32:55,340 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:32:55,425 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:32:55,445 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:32:55,517 [salt.state       ][INFO    ][30299] {'new': {'192.168.10.143': []}, 'old': None}
2017-10-28 20:32:55,518 [salt.state       ][INFO    ][30299] Completed state [192.168.10.143] at time 20:32:55.518142 duration_in_ms=428.33
2017-10-28 20:32:55,525 [salt.state       ][INFO    ][30299] Running state [192.168.10.142] at time 20:32:55.524984
2017-10-28 20:32:55,527 [salt.state       ][INFO    ][30299] Executing state glusterfs.peered for 192.168.10.142
2017-10-28 20:32:55,527 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:32:55,544 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:32:55,623 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:32:55,641 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:32:55,724 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:32:55,743 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:32:55,823 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:32:55,844 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:32:55,925 [salt.state       ][INFO    ][30299] Peering with localhost is not needed
2017-10-28 20:32:55,926 [salt.state       ][INFO    ][30299] Completed state [192.168.10.142] at time 20:32:55.926194 duration_in_ms=401.209
2017-10-28 20:32:55,933 [salt.state       ][INFO    ][30299] Running state [192.168.10.141] at time 20:32:55.932939
2017-10-28 20:32:55,935 [salt.state       ][INFO    ][30299] Executing state glusterfs.peered for 192.168.10.141
2017-10-28 20:32:55,935 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:32:55,955 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:32:56,039 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:32:56,060 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:32:56,151 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:32:56,176 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:32:56,256 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:32:56,277 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:32:56,359 [salt.state       ][INFO    ][30299] {'new': {'192.168.10.143': [], '192.168.10.141': []}, 'old': {'192.168.10.143': []}}
2017-10-28 20:32:56,359 [salt.state       ][INFO    ][30299] Completed state [192.168.10.141] at time 20:32:56.359344 duration_in_ms=426.405
2017-10-28 20:32:56,363 [salt.state       ][INFO    ][30299] Running state [glance] at time 20:32:56.362705
2017-10-28 20:32:56,363 [salt.state       ][INFO    ][30299] Executing state glusterfs.created for glance
2017-10-28 20:32:56,364 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:32:56,382 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:32:56,459 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:32:56,476 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:32:56,597 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:32:56,617 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:32:57,122 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:32:57,145 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:32:57,224 [salt.state       ][INFO    ][30299] {'new': ['glance'], 'old': []}
2017-10-28 20:32:57,225 [salt.state       ][INFO    ][30299] Completed state [glance] at time 20:32:57.224874 duration_in_ms=862.169
2017-10-28 20:32:57,235 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 20:32:57.235362
2017-10-28 20:32:57,236 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-10-28 20:32:57,236 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-28 20:32:57,319 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-10-28 20:32:57,477 [salt.state       ][INFO    ][30299] {'pid': 30522, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:32:57,479 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 20:32:57.478741 duration_in_ms=243.378
2017-10-28 20:32:57,480 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 20:32:57.479870
2017-10-28 20:32:57,480 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-10-28 20:32:57,482 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-28 20:32:57,570 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'glance' 'network.remote-dio' 'True'' in directory '/root'
2017-10-28 20:32:57,725 [salt.state       ][INFO    ][30299] {'pid': 30540, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:32:57,726 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 20:32:57.725035 duration_in_ms=245.164
2017-10-28 20:32:57,728 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 20:32:57.727777
2017-10-28 20:32:57,728 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-10-28 20:32:57,730 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-10-28 20:32:57,816 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'glance' 'nfs.disable' 'True'' in directory '/root'
2017-10-28 20:32:59,982 [salt.state       ][INFO    ][30299] {'pid': 30557, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:32:59,982 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 20:32:59.982174 duration_in_ms=2254.396
2017-10-28 20:32:59,984 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 20:32:59.983596
2017-10-28 20:32:59,984 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-10-28 20:32:59,986 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-28 20:33:00,080 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-10-28 20:33:00,225 [salt.state       ][INFO    ][30299] {'pid': 30575, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:33:00,225 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 20:33:00.224937 duration_in_ms=241.341
2017-10-28 20:33:00,226 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 20:33:00.226429
2017-10-28 20:33:00,227 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-10-28 20:33:00,228 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-28 20:33:00,309 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'glance' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-10-28 20:33:00,455 [salt.state       ][INFO    ][30299] {'pid': 30592, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:33:00,455 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 20:33:00.455296 duration_in_ms=228.866
2017-10-28 20:33:00,460 [salt.state       ][INFO    ][30299] Running state [glance] at time 20:33:00.459971
2017-10-28 20:33:00,460 [salt.state       ][INFO    ][30299] Executing state glusterfs.started for glance
2017-10-28 20:33:00,461 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:00,477 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:00,542 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:00,557 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:00,648 [salt.state       ][INFO    ][30299] Volume glance is already started
2017-10-28 20:33:00,649 [salt.state       ][INFO    ][30299] Completed state [glance] at time 20:33:00.648814 duration_in_ms=188.843
2017-10-28 20:33:00,652 [salt.state       ][INFO    ][30299] Running state [keystone-keys] at time 20:33:00.652188
2017-10-28 20:33:00,654 [salt.state       ][INFO    ][30299] Executing state glusterfs.created for keystone-keys
2017-10-28 20:33:00,655 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:00,673 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:00,754 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:00,773 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:00,883 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:00,904 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:01,603 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command saltutil.find_job with jid 20171028203301593364
2017-10-28 20:33:01,625 [salt.minion      ][INFO    ][30673] Starting a new job with PID 30673
2017-10-28 20:33:01,644 [salt.minion      ][INFO    ][30673] Returning information for job: 20171028203301593364
2017-10-28 20:33:03,126 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:03,152 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:03,235 [salt.state       ][INFO    ][30299] {'new': ['glance', 'keystone-keys'], 'old': ['glance']}
2017-10-28 20:33:03,235 [salt.state       ][INFO    ][30299] Completed state [keystone-keys] at time 20:33:03.235224 duration_in_ms=2583.036
2017-10-28 20:33:03,246 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 20:33:03.245959
2017-10-28 20:33:03,247 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-10-28 20:33:03,248 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-28 20:33:03,331 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'keystone-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-10-28 20:33:03,496 [salt.state       ][INFO    ][30299] {'pid': 30713, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:33:03,496 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 20:33:03.496058 duration_in_ms=250.099
2017-10-28 20:33:03,499 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 20:33:03.498530
2017-10-28 20:33:03,499 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-28 20:33:03,500 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-28 20:33:03,590 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-10-28 20:33:03,742 [salt.state       ][INFO    ][30299] {'pid': 30730, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:33:03,743 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 20:33:03.742879 duration_in_ms=244.348
2017-10-28 20:33:03,744 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 20:33:03.744364
2017-10-28 20:33:03,745 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-28 20:33:03,747 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-28 20:33:03,831 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-10-28 20:33:03,996 [salt.state       ][INFO    ][30299] {'pid': 30748, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:33:03,997 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 20:33:03.996541 duration_in_ms=252.176
2017-10-28 20:33:03,998 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 20:33:03.997794
2017-10-28 20:33:03,998 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-10-28 20:33:03,999 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-28 20:33:04,087 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'keystone-keys' 'nfs.disable' 'True'' in directory '/root'
2017-10-28 20:33:06,252 [salt.state       ][INFO    ][30299] {'pid': 30765, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:33:06,253 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 20:33:06.252946 duration_in_ms=2255.151
2017-10-28 20:33:06,255 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 20:33:06.254418
2017-10-28 20:33:06,255 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-10-28 20:33:06,256 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-28 20:33:06,342 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-10-28 20:33:06,495 [salt.state       ][INFO    ][30299] {'pid': 30782, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:33:06,496 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 20:33:06.495961 duration_in_ms=241.543
2017-10-28 20:33:06,500 [salt.state       ][INFO    ][30299] Running state [keystone-keys] at time 20:33:06.500342
2017-10-28 20:33:06,501 [salt.state       ][INFO    ][30299] Executing state glusterfs.started for keystone-keys
2017-10-28 20:33:06,502 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:06,520 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:06,604 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:06,623 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:06,716 [salt.state       ][INFO    ][30299] Volume keystone-keys is already started
2017-10-28 20:33:06,716 [salt.state       ][INFO    ][30299] Completed state [keystone-keys] at time 20:33:06.716250 duration_in_ms=215.907
2017-10-28 20:33:06,720 [salt.state       ][INFO    ][30299] Running state [nova_instances] at time 20:33:06.719953
2017-10-28 20:33:06,721 [salt.state       ][INFO    ][30299] Executing state glusterfs.created for nova_instances
2017-10-28 20:33:06,722 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:06,739 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:06,817 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:06,832 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:06,946 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:06,966 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:09,187 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:09,212 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:09,297 [salt.state       ][INFO    ][30299] {'new': ['glance', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys']}
2017-10-28 20:33:09,298 [salt.state       ][INFO    ][30299] Completed state [nova_instances] at time 20:33:09.297040 duration_in_ms=2577.085
2017-10-28 20:33:09,308 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 20:33:09.307776
2017-10-28 20:33:09,308 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-10-28 20:33:09,309 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-28 20:33:09,391 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-10-28 20:33:09,551 [salt.state       ][INFO    ][30299] {'pid': 30896, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:33:09,552 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 20:33:09.551787 duration_in_ms=244.01
2017-10-28 20:33:09,554 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 20:33:09.554380
2017-10-28 20:33:09,555 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-10-28 20:33:09,556 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-10-28 20:33:09,647 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'' in directory '/root'
2017-10-28 20:33:09,813 [salt.state       ][INFO    ][30299] {'pid': 30914, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:33:09,814 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 20:33:09.813835 duration_in_ms=259.454
2017-10-28 20:33:09,815 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 20:33:09.815071
2017-10-28 20:33:09,816 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-10-28 20:33:09,817 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-10-28 20:33:09,908 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'nova_instances' 'network.remote-dio' 'On'' in directory '/root'
2017-10-28 20:33:10,076 [salt.state       ][INFO    ][30299] {'pid': 30931, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:33:10,077 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 20:33:10.076958 duration_in_ms=261.886
2017-10-28 20:33:10,079 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 20:33:10.079079
2017-10-28 20:33:10,080 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-10-28 20:33:10,081 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-10-28 20:33:10,176 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'nova_instances' 'nfs.disable' 'On'' in directory '/root'
2017-10-28 20:33:11,779 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command saltutil.find_job with jid 20171028203311769632
2017-10-28 20:33:11,801 [salt.minion      ][INFO    ][30958] Starting a new job with PID 30958
2017-10-28 20:33:11,820 [salt.minion      ][INFO    ][30958] Returning information for job: 20171028203311769632
2017-10-28 20:33:12,345 [salt.state       ][INFO    ][30299] {'pid': 30948, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:33:12,346 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 20:33:12.346217 duration_in_ms=2267.138
2017-10-28 20:33:12,347 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 20:33:12.347335
2017-10-28 20:33:12,348 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-10-28 20:33:12,349 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-28 20:33:12,439 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-10-28 20:33:12,596 [salt.state       ][INFO    ][30299] {'pid': 30971, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:33:12,597 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 20:33:12.596454 duration_in_ms=249.118
2017-10-28 20:33:12,600 [salt.state       ][INFO    ][30299] Running state [nova_instances] at time 20:33:12.599638
2017-10-28 20:33:12,600 [salt.state       ][INFO    ][30299] Executing state glusterfs.started for nova_instances
2017-10-28 20:33:12,602 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:12,622 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:12,703 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:12,722 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:12,809 [salt.state       ][INFO    ][30299] Volume nova_instances is already started
2017-10-28 20:33:12,810 [salt.state       ][INFO    ][30299] Completed state [nova_instances] at time 20:33:12.809127 duration_in_ms=209.488
2017-10-28 20:33:12,812 [salt.state       ][INFO    ][30299] Running state [keystone-credential-keys] at time 20:33:12.811699
2017-10-28 20:33:12,813 [salt.state       ][INFO    ][30299] Executing state glusterfs.created for keystone-credential-keys
2017-10-28 20:33:12,815 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:12,829 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:12,910 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:12,927 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:13,041 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:13,064 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:15,280 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:15,312 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:15,395 [salt.state       ][INFO    ][30299] {'new': ['glance', 'keystone-credential-keys', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys', 'nova_instances']}
2017-10-28 20:33:15,395 [salt.state       ][INFO    ][30299] Completed state [keystone-credential-keys] at time 20:33:15.395120 duration_in_ms=2583.42
2017-10-28 20:33:15,407 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 20:33:15.407068
2017-10-28 20:33:15,407 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-10-28 20:33:15,408 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-28 20:33:15,487 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-10-28 20:33:15,659 [salt.state       ][INFO    ][30299] {'pid': 31090, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:33:15,660 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 20:33:15.660014 duration_in_ms=252.943
2017-10-28 20:33:15,663 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 20:33:15.662532
2017-10-28 20:33:15,663 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-28 20:33:15,664 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-28 20:33:15,753 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-10-28 20:33:15,911 [salt.state       ][INFO    ][30299] {'pid': 31107, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:33:15,912 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 20:33:15.911185 duration_in_ms=248.651
2017-10-28 20:33:15,913 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 20:33:15.912736
2017-10-28 20:33:15,913 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-10-28 20:33:15,914 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-28 20:33:16,005 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'' in directory '/root'
2017-10-28 20:33:18,186 [salt.state       ][INFO    ][30299] {'pid': 31125, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:33:18,187 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 20:33:18.186530 duration_in_ms=2273.792
2017-10-28 20:33:18,188 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 20:33:18.187948
2017-10-28 20:33:18,189 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-28 20:33:18,190 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-28 20:33:18,277 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-10-28 20:33:18,432 [salt.state       ][INFO    ][30299] {'pid': 31143, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:33:18,434 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 20:33:18.434052 duration_in_ms=246.104
2017-10-28 20:33:18,435 [salt.state       ][INFO    ][30299] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 20:33:18.435364
2017-10-28 20:33:18,436 [salt.state       ][INFO    ][30299] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-10-28 20:33:18,437 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-28 20:33:18,529 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-10-28 20:33:18,687 [salt.state       ][INFO    ][30299] {'pid': 31160, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-28 20:33:18,687 [salt.state       ][INFO    ][30299] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 20:33:18.687014 duration_in_ms=251.64
2017-10-28 20:33:18,691 [salt.state       ][INFO    ][30299] Running state [keystone-credential-keys] at time 20:33:18.690760
2017-10-28 20:33:18,691 [salt.state       ][INFO    ][30299] Executing state glusterfs.started for keystone-credential-keys
2017-10-28 20:33:18,692 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:18,713 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:18,790 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --version' in directory '/root'
2017-10-28 20:33:18,808 [salt.loaded.int.module.cmdmod][INFO    ][30299] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 20:33:18,897 [salt.state       ][INFO    ][30299] Volume keystone-credential-keys is already started
2017-10-28 20:33:18,898 [salt.state       ][INFO    ][30299] Completed state [keystone-credential-keys] at time 20:33:18.897687 duration_in_ms=206.926
2017-10-28 20:33:18,903 [salt.minion      ][INFO    ][30299] Returning information for job: 20171028203251533684
2017-10-28 20:33:30,130 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command test.ping with jid 20171028203330120630
2017-10-28 20:33:30,154 [salt.minion      ][INFO    ][31190] Starting a new job with PID 31190
2017-10-28 20:33:30,199 [salt.minion      ][INFO    ][31190] Returning information for job: 20171028203330120630
2017-10-28 20:33:30,327 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command cmd.run with jid 20171028203330315642
2017-10-28 20:33:30,343 [salt.minion      ][INFO    ][31195] Starting a new job with PID 31195
2017-10-28 20:33:30,351 [salt.loaded.int.module.cmdmod][INFO    ][31195] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-10-28 20:33:30,527 [salt.minion      ][INFO    ][31195] Returning information for job: 20171028203330315642
2017-10-28 21:13:42,237 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command test.ping with jid 20171028211342229660
2017-10-28 21:13:42,261 [salt.minion      ][INFO    ][31793] Starting a new job with PID 31793
2017-10-28 21:13:42,320 [salt.minion      ][INFO    ][31793] Returning information for job: 20171028211342229660
2017-10-28 21:14:20,518 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command state.sls with jid 20171028211420510534
2017-10-28 21:14:20,536 [salt.minion      ][INFO    ][31802] Starting a new job with PID 31802
2017-10-28 21:14:21,160 [salt.state       ][INFO    ][31802] Loading fresh modules for state activity
2017-10-28 21:14:21,206 [salt.fileclient  ][INFO    ][31802] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-10-28 21:14:21,242 [salt.fileclient  ][INFO    ][31802] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-10-28 21:14:21,288 [salt.fileclient  ][INFO    ][31802] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-28 21:14:21,690 [salt.state       ][INFO    ][31802] Running state [lsof] at time 21:14:21.690370
2017-10-28 21:14:21,691 [salt.state       ][INFO    ][31802] Executing state pkg.installed for lsof
2017-10-28 21:14:21,691 [salt.loaded.int.module.cmdmod][INFO    ][31802] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-28 21:14:22,024 [salt.state       ][INFO    ][31802] Package lsof is already installed
2017-10-28 21:14:22,025 [salt.state       ][INFO    ][31802] Completed state [lsof] at time 21:14:22.024718 duration_in_ms=334.347
2017-10-28 21:14:22,025 [salt.state       ][INFO    ][31802] Running state [keepalived] at time 21:14:22.024959
2017-10-28 21:14:22,025 [salt.state       ][INFO    ][31802] Executing state pkg.installed for keepalived
2017-10-28 21:14:22,029 [salt.state       ][INFO    ][31802] Package keepalived is already installed
2017-10-28 21:14:22,029 [salt.state       ][INFO    ][31802] Completed state [keepalived] at time 21:14:22.029111 duration_in_ms=4.152
2017-10-28 21:14:22,031 [salt.state       ][INFO    ][31802] Running state [/etc/keepalived/keepalived.conf] at time 21:14:22.030993
2017-10-28 21:14:22,031 [salt.state       ][INFO    ][31802] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-10-28 21:14:22,065 [salt.fileclient  ][INFO    ][31802] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-10-28 21:14:22,124 [salt.fileclient  ][INFO    ][31802] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-28 21:14:22,137 [salt.state       ][INFO    ][31802] File /etc/keepalived/keepalived.conf is in the correct state
2017-10-28 21:14:22,138 [salt.state       ][INFO    ][31802] Completed state [/etc/keepalived/keepalived.conf] at time 21:14:22.137637 duration_in_ms=106.644
2017-10-28 21:14:22,139 [salt.state       ][INFO    ][31802] Running state [keepalived] at time 21:14:22.138893
2017-10-28 21:14:22,140 [salt.state       ][INFO    ][31802] Executing state service.running for keepalived
2017-10-28 21:14:22,140 [salt.loaded.int.module.cmdmod][INFO    ][31802] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-10-28 21:14:22,179 [salt.loaded.int.module.cmdmod][INFO    ][31802] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-28 21:14:22,196 [salt.loaded.int.module.cmdmod][INFO    ][31802] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-28 21:14:22,215 [salt.state       ][INFO    ][31802] The service keepalived is already running
2017-10-28 21:14:22,215 [salt.state       ][INFO    ][31802] Completed state [keepalived] at time 21:14:22.215086 duration_in_ms=76.191
2017-10-28 21:14:22,217 [salt.minion      ][INFO    ][31802] Returning information for job: 20171028211420510534
2017-10-28 21:14:26,052 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command pillar.get with jid 20171028211426044747
2017-10-28 21:14:26,072 [salt.minion      ][INFO    ][31827] Starting a new job with PID 31827
2017-10-28 21:14:26,086 [salt.minion      ][INFO    ][31827] Returning information for job: 20171028211426044747
2017-10-28 21:15:16,679 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command state.sls with jid 20171028211516670476
2017-10-28 21:15:16,709 [salt.minion      ][INFO    ][31834] Starting a new job with PID 31834
2017-10-28 21:15:19,335 [salt.state       ][INFO    ][31834] Loading fresh modules for state activity
2017-10-28 21:15:19,368 [salt.fileclient  ][INFO    ][31834] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-28 21:15:19,401 [salt.fileclient  ][INFO    ][31834] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-28 21:15:19,819 [salt.state       ][INFO    ][31834] Running state [attr] at time 21:15:19.818756
2017-10-28 21:15:19,819 [salt.state       ][INFO    ][31834] Executing state pkg.installed for attr
2017-10-28 21:15:19,820 [salt.loaded.int.module.cmdmod][INFO    ][31834] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-28 21:15:20,143 [salt.state       ][INFO    ][31834] Package attr is already installed
2017-10-28 21:15:20,143 [salt.state       ][INFO    ][31834] Completed state [attr] at time 21:15:20.142854 duration_in_ms=324.097
2017-10-28 21:15:20,143 [salt.state       ][INFO    ][31834] Running state [glusterfs-server] at time 21:15:20.143140
2017-10-28 21:15:20,143 [salt.state       ][INFO    ][31834] Executing state pkg.installed for glusterfs-server
2017-10-28 21:15:20,148 [salt.state       ][INFO    ][31834] Package glusterfs-server is already installed
2017-10-28 21:15:20,148 [salt.state       ][INFO    ][31834] Completed state [glusterfs-server] at time 21:15:20.148389 duration_in_ms=5.248
2017-10-28 21:15:20,149 [salt.state       ][INFO    ][31834] Running state [bc] at time 21:15:20.148592
2017-10-28 21:15:20,149 [salt.state       ][INFO    ][31834] Executing state pkg.installed for bc
2017-10-28 21:15:20,154 [salt.state       ][INFO    ][31834] Package bc is already installed
2017-10-28 21:15:20,154 [salt.state       ][INFO    ][31834] Completed state [bc] at time 21:15:20.153676 duration_in_ms=5.084
2017-10-28 21:15:20,155 [salt.state       ][INFO    ][31834] Running state [glusterfs-server] at time 21:15:20.154496
2017-10-28 21:15:20,155 [salt.state       ][INFO    ][31834] Executing state service.running for glusterfs-server
2017-10-28 21:15:20,156 [salt.loaded.int.module.cmdmod][INFO    ][31834] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-28 21:15:20,196 [salt.loaded.int.module.cmdmod][INFO    ][31834] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-28 21:15:20,221 [salt.loaded.int.module.cmdmod][INFO    ][31834] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-28 21:15:20,247 [salt.state       ][INFO    ][31834] The service glusterfs-server is already running
2017-10-28 21:15:20,248 [salt.state       ][INFO    ][31834] Completed state [glusterfs-server] at time 21:15:20.247755 duration_in_ms=93.257
2017-10-28 21:15:20,252 [salt.state       ][INFO    ][31834] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 21:15:20.251803
2017-10-28 21:15:20,252 [salt.state       ][INFO    ][31834] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-28 21:15:20,258 [salt.state       ][INFO    ][31834] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-28 21:15:20,259 [salt.state       ][INFO    ][31834] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 21:15:20.258691 duration_in_ms=6.889
2017-10-28 21:15:20,259 [salt.state       ][INFO    ][31834] Running state [/srv/glusterfs/glance] at time 21:15:20.259328
2017-10-28 21:15:20,260 [salt.state       ][INFO    ][31834] Executing state file.directory for /srv/glusterfs/glance
2017-10-28 21:15:20,261 [salt.state       ][INFO    ][31834] Directory /srv/glusterfs/glance is in the correct state
2017-10-28 21:15:20,261 [salt.state       ][INFO    ][31834] Completed state [/srv/glusterfs/glance] at time 21:15:20.260841 duration_in_ms=1.512
2017-10-28 21:15:20,263 [salt.state       ][INFO    ][31834] Running state [/srv/glusterfs/keystone-keys] at time 21:15:20.262600
2017-10-28 21:15:20,263 [salt.state       ][INFO    ][31834] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-28 21:15:20,264 [salt.state       ][INFO    ][31834] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-28 21:15:20,264 [salt.state       ][INFO    ][31834] Completed state [/srv/glusterfs/keystone-keys] at time 21:15:20.263706 duration_in_ms=1.106
2017-10-28 21:15:20,264 [salt.state       ][INFO    ][31834] Running state [/srv/glusterfs/nova_instances] at time 21:15:20.263860
2017-10-28 21:15:20,264 [salt.state       ][INFO    ][31834] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-28 21:15:20,264 [salt.state       ][INFO    ][31834] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-28 21:15:20,265 [salt.state       ][INFO    ][31834] Completed state [/srv/glusterfs/nova_instances] at time 21:15:20.264481 duration_in_ms=0.621
2017-10-28 21:15:20,265 [salt.state       ][INFO    ][31834] Running state [/srv/glusterfs/keystone-credential-keys] at time 21:15:20.264969
2017-10-28 21:15:20,266 [salt.state       ][INFO    ][31834] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-28 21:15:20,266 [salt.state       ][INFO    ][31834] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-28 21:15:20,267 [salt.state       ][INFO    ][31834] Completed state [/srv/glusterfs/keystone-credential-keys] at time 21:15:20.266610 duration_in_ms=1.641
2017-10-28 21:15:20,267 [salt.minion      ][INFO    ][31834] Returning information for job: 20171028211516670476
2017-10-28 21:15:21,287 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command test.ping with jid 20171028211521279686
2017-10-28 21:15:21,318 [salt.minion      ][INFO    ][31857] Starting a new job with PID 31857
2017-10-28 21:15:21,364 [salt.minion      ][INFO    ][31857] Returning information for job: 20171028211521279686
2017-10-28 21:15:21,500 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command state.sls with jid 20171028211521485787
2017-10-28 21:15:21,530 [salt.minion      ][INFO    ][31862] Starting a new job with PID 31862
2017-10-28 21:15:22,155 [salt.state       ][INFO    ][31862] Loading fresh modules for state activity
2017-10-28 21:15:22,196 [salt.fileclient  ][INFO    ][31862] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-10-28 21:15:22,257 [salt.fileclient  ][INFO    ][31862] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-28 21:15:22,363 [salt.fileclient  ][INFO    ][31862] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-28 21:15:22,399 [salt.fileclient  ][INFO    ][31862] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-28 21:15:22,809 [salt.state       ][INFO    ][31862] Running state [attr] at time 21:15:22.809082
2017-10-28 21:15:22,810 [salt.state       ][INFO    ][31862] Executing state pkg.installed for attr
2017-10-28 21:15:22,810 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-28 21:15:23,141 [salt.state       ][INFO    ][31862] Package attr is already installed
2017-10-28 21:15:23,142 [salt.state       ][INFO    ][31862] Completed state [attr] at time 21:15:23.141526 duration_in_ms=332.456
2017-10-28 21:15:23,142 [salt.state       ][INFO    ][31862] Running state [glusterfs-server] at time 21:15:23.141809
2017-10-28 21:15:23,142 [salt.state       ][INFO    ][31862] Executing state pkg.installed for glusterfs-server
2017-10-28 21:15:23,146 [salt.state       ][INFO    ][31862] Package glusterfs-server is already installed
2017-10-28 21:15:23,146 [salt.state       ][INFO    ][31862] Completed state [glusterfs-server] at time 21:15:23.146327 duration_in_ms=4.518
2017-10-28 21:15:23,147 [salt.state       ][INFO    ][31862] Running state [bc] at time 21:15:23.146522
2017-10-28 21:15:23,147 [salt.state       ][INFO    ][31862] Executing state pkg.installed for bc
2017-10-28 21:15:23,151 [salt.state       ][INFO    ][31862] Package bc is already installed
2017-10-28 21:15:23,151 [salt.state       ][INFO    ][31862] Completed state [bc] at time 21:15:23.151257 duration_in_ms=4.735
2017-10-28 21:15:23,152 [salt.state       ][INFO    ][31862] Running state [glusterfs-server] at time 21:15:23.152294
2017-10-28 21:15:23,153 [salt.state       ][INFO    ][31862] Executing state service.running for glusterfs-server
2017-10-28 21:15:23,153 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-28 21:15:23,190 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-28 21:15:23,216 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-28 21:15:23,243 [salt.state       ][INFO    ][31862] The service glusterfs-server is already running
2017-10-28 21:15:23,244 [salt.state       ][INFO    ][31862] Completed state [glusterfs-server] at time 21:15:23.243772 duration_in_ms=91.478
2017-10-28 21:15:23,247 [salt.state       ][INFO    ][31862] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 21:15:23.247113
2017-10-28 21:15:23,248 [salt.state       ][INFO    ][31862] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-28 21:15:23,255 [salt.state       ][INFO    ][31862] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-28 21:15:23,256 [salt.state       ][INFO    ][31862] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 21:15:23.255579 duration_in_ms=8.466
2017-10-28 21:15:23,256 [salt.state       ][INFO    ][31862] Running state [/srv/glusterfs/glance] at time 21:15:23.256306
2017-10-28 21:15:23,257 [salt.state       ][INFO    ][31862] Executing state file.directory for /srv/glusterfs/glance
2017-10-28 21:15:23,258 [salt.state       ][INFO    ][31862] Directory /srv/glusterfs/glance is in the correct state
2017-10-28 21:15:23,258 [salt.state       ][INFO    ][31862] Completed state [/srv/glusterfs/glance] at time 21:15:23.257893 duration_in_ms=1.588
2017-10-28 21:15:23,258 [salt.state       ][INFO    ][31862] Running state [/srv/glusterfs/keystone-keys] at time 21:15:23.258081
2017-10-28 21:15:23,258 [salt.state       ][INFO    ][31862] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-28 21:15:23,259 [salt.state       ][INFO    ][31862] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-28 21:15:23,259 [salt.state       ][INFO    ][31862] Completed state [/srv/glusterfs/keystone-keys] at time 21:15:23.258872 duration_in_ms=0.791
2017-10-28 21:15:23,259 [salt.state       ][INFO    ][31862] Running state [/srv/glusterfs/nova_instances] at time 21:15:23.259060
2017-10-28 21:15:23,259 [salt.state       ][INFO    ][31862] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-28 21:15:23,260 [salt.state       ][INFO    ][31862] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-28 21:15:23,260 [salt.state       ][INFO    ][31862] Completed state [/srv/glusterfs/nova_instances] at time 21:15:23.259781 duration_in_ms=0.722
2017-10-28 21:15:23,260 [salt.state       ][INFO    ][31862] Running state [/srv/glusterfs/keystone-credential-keys] at time 21:15:23.259955
2017-10-28 21:15:23,260 [salt.state       ][INFO    ][31862] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-28 21:15:23,261 [salt.state       ][INFO    ][31862] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-28 21:15:23,261 [salt.state       ][INFO    ][31862] Completed state [/srv/glusterfs/keystone-credential-keys] at time 21:15:23.260718 duration_in_ms=0.763
2017-10-28 21:15:23,334 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command ['git', '--version'] in directory '/root'
2017-10-28 21:15:23,450 [salt.state       ][INFO    ][31862] Running state [sleep 5] at time 21:15:23.449468
2017-10-28 21:15:23,450 [salt.state       ][INFO    ][31862] Executing state cmd.wait for sleep 5
2017-10-28 21:15:23,450 [salt.state       ][INFO    ][31862] No changes made for sleep 5
2017-10-28 21:15:23,451 [salt.state       ][INFO    ][31862] Completed state [sleep 5] at time 21:15:23.450518 duration_in_ms=1.05
2017-10-28 21:15:23,451 [salt.state       ][INFO    ][31862] Running state [192.168.10.143] at time 21:15:23.451100
2017-10-28 21:15:23,451 [salt.state       ][INFO    ][31862] Executing state glusterfs.peered for 192.168.10.143
2017-10-28 21:15:23,452 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:23,473 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:23,561 [salt.state       ][INFO    ][31862] Host 192.168.10.143 already peered
2017-10-28 21:15:23,562 [salt.state       ][INFO    ][31862] Completed state [192.168.10.143] at time 21:15:23.561436 duration_in_ms=110.037
2017-10-28 21:15:23,570 [salt.state       ][INFO    ][31862] Running state [192.168.10.142] at time 21:15:23.569984
2017-10-28 21:15:23,570 [salt.state       ][INFO    ][31862] Executing state glusterfs.peered for 192.168.10.142
2017-10-28 21:15:23,571 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:23,594 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:23,688 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:23,709 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:23,795 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:23,816 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:23,898 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:23,916 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:24,012 [salt.state       ][INFO    ][31862] Peering with localhost is not needed
2017-10-28 21:15:24,013 [salt.state       ][INFO    ][31862] Completed state [192.168.10.142] at time 21:15:24.012460 duration_in_ms=442.475
2017-10-28 21:15:24,023 [salt.state       ][INFO    ][31862] Running state [192.168.10.141] at time 21:15:24.023449
2017-10-28 21:15:24,024 [salt.state       ][INFO    ][31862] Executing state glusterfs.peered for 192.168.10.141
2017-10-28 21:15:24,024 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:24,042 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:24,124 [salt.state       ][INFO    ][31862] Host 192.168.10.141 already peered
2017-10-28 21:15:24,125 [salt.state       ][INFO    ][31862] Completed state [192.168.10.141] at time 21:15:24.124754 duration_in_ms=101.303
2017-10-28 21:15:24,128 [salt.state       ][INFO    ][31862] Running state [glance] at time 21:15:24.127461
2017-10-28 21:15:24,128 [salt.state       ][INFO    ][31862] Executing state glusterfs.created for glance
2017-10-28 21:15:24,129 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:24,148 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:24,241 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:24,263 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:24,360 [salt.state       ][INFO    ][31862] Volume glance already exists and is started.
2017-10-28 21:15:24,361 [salt.state       ][INFO    ][31862] Completed state [glance] at time 21:15:24.360905 duration_in_ms=233.443
2017-10-28 21:15:24,371 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 21:15:24.370982
2017-10-28 21:15:24,371 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-10-28 21:15:24,372 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-28 21:15:24,478 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:24,479 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 21:15:24.478679 duration_in_ms=107.695
2017-10-28 21:15:24,480 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 21:15:24.480019
2017-10-28 21:15:24,481 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-10-28 21:15:24,483 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-28 21:15:24,581 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:24,582 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 21:15:24.582126 duration_in_ms=102.107
2017-10-28 21:15:24,583 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 21:15:24.583295
2017-10-28 21:15:24,584 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-10-28 21:15:24,585 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-10-28 21:15:24,684 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:24,685 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 21:15:24.684539 duration_in_ms=101.243
2017-10-28 21:15:24,687 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 21:15:24.686794
2017-10-28 21:15:24,687 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-10-28 21:15:24,690 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-28 21:15:24,781 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:24,782 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 21:15:24.781931 duration_in_ms=95.137
2017-10-28 21:15:24,783 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 21:15:24.783237
2017-10-28 21:15:24,784 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-10-28 21:15:24,785 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-28 21:15:24,881 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:24,882 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 21:15:24.882036 duration_in_ms=98.799
2017-10-28 21:15:24,886 [salt.state       ][INFO    ][31862] Running state [glance] at time 21:15:24.885852
2017-10-28 21:15:24,886 [salt.state       ][INFO    ][31862] Executing state glusterfs.started for glance
2017-10-28 21:15:24,887 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:24,908 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:24,994 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:25,014 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:25,115 [salt.state       ][INFO    ][31862] Volume glance is already started
2017-10-28 21:15:25,115 [salt.state       ][INFO    ][31862] Completed state [glance] at time 21:15:25.114834 duration_in_ms=228.982
2017-10-28 21:15:25,116 [salt.state       ][INFO    ][31862] Running state [keystone-keys] at time 21:15:25.116306
2017-10-28 21:15:25,117 [salt.state       ][INFO    ][31862] Executing state glusterfs.created for keystone-keys
2017-10-28 21:15:25,118 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:25,140 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:25,241 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:25,260 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:25,356 [salt.state       ][INFO    ][31862] Volume keystone-keys already exists and is started.
2017-10-28 21:15:25,357 [salt.state       ][INFO    ][31862] Completed state [keystone-keys] at time 21:15:25.356689 duration_in_ms=240.381
2017-10-28 21:15:25,367 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 21:15:25.367074
2017-10-28 21:15:25,367 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-10-28 21:15:25,368 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-28 21:15:25,458 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:25,459 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 21:15:25.459291 duration_in_ms=92.216
2017-10-28 21:15:25,462 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 21:15:25.462114
2017-10-28 21:15:25,463 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-28 21:15:25,463 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-28 21:15:25,557 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:25,557 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 21:15:25.556964 duration_in_ms=94.849
2017-10-28 21:15:25,559 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 21:15:25.559342
2017-10-28 21:15:25,560 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-28 21:15:25,561 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-28 21:15:25,660 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:25,662 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 21:15:25.661595 duration_in_ms=102.252
2017-10-28 21:15:25,663 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 21:15:25.662941
2017-10-28 21:15:25,664 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-10-28 21:15:25,665 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-28 21:15:25,760 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:25,764 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 21:15:25.763575 duration_in_ms=100.634
2017-10-28 21:15:25,765 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 21:15:25.764795
2017-10-28 21:15:25,766 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-10-28 21:15:25,767 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-28 21:15:25,867 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:25,868 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 21:15:25.867826 duration_in_ms=103.031
2017-10-28 21:15:25,872 [salt.state       ][INFO    ][31862] Running state [keystone-keys] at time 21:15:25.871855
2017-10-28 21:15:25,872 [salt.state       ][INFO    ][31862] Executing state glusterfs.started for keystone-keys
2017-10-28 21:15:25,874 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:25,897 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:25,995 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:26,016 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:26,115 [salt.state       ][INFO    ][31862] Volume keystone-keys is already started
2017-10-28 21:15:26,116 [salt.state       ][INFO    ][31862] Completed state [keystone-keys] at time 21:15:26.115749 duration_in_ms=243.894
2017-10-28 21:15:26,119 [salt.state       ][INFO    ][31862] Running state [nova_instances] at time 21:15:26.119026
2017-10-28 21:15:26,119 [salt.state       ][INFO    ][31862] Executing state glusterfs.created for nova_instances
2017-10-28 21:15:26,120 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:26,142 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:26,234 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:26,256 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:26,354 [salt.state       ][INFO    ][31862] Volume nova_instances already exists and is started.
2017-10-28 21:15:26,354 [salt.state       ][INFO    ][31862] Completed state [nova_instances] at time 21:15:26.354381 duration_in_ms=235.353
2017-10-28 21:15:26,369 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 21:15:26.368715
2017-10-28 21:15:26,372 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-10-28 21:15:26,373 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-28 21:15:26,453 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:26,454 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 21:15:26.453750 duration_in_ms=85.036
2017-10-28 21:15:26,455 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 21:15:26.454837
2017-10-28 21:15:26,455 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-10-28 21:15:26,457 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-10-28 21:15:26,550 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:26,550 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 21:15:26.550358 duration_in_ms=95.521
2017-10-28 21:15:26,552 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 21:15:26.551528
2017-10-28 21:15:26,552 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-10-28 21:15:26,554 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-10-28 21:15:26,642 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:26,643 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 21:15:26.642851 duration_in_ms=91.31
2017-10-28 21:15:26,644 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 21:15:26.643939
2017-10-28 21:15:26,645 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-10-28 21:15:26,646 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-10-28 21:15:26,743 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:26,744 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 21:15:26.744239 duration_in_ms=100.3
2017-10-28 21:15:26,746 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 21:15:26.745806
2017-10-28 21:15:26,746 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-10-28 21:15:26,748 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-28 21:15:26,835 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:26,836 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 21:15:26.836009 duration_in_ms=90.203
2017-10-28 21:15:26,840 [salt.state       ][INFO    ][31862] Running state [nova_instances] at time 21:15:26.839853
2017-10-28 21:15:26,840 [salt.state       ][INFO    ][31862] Executing state glusterfs.started for nova_instances
2017-10-28 21:15:26,841 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:26,860 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:26,949 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:26,971 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:27,063 [salt.state       ][INFO    ][31862] Volume nova_instances is already started
2017-10-28 21:15:27,064 [salt.state       ][INFO    ][31862] Completed state [nova_instances] at time 21:15:27.063449 duration_in_ms=223.594
2017-10-28 21:15:27,068 [salt.state       ][INFO    ][31862] Running state [keystone-credential-keys] at time 21:15:27.067943
2017-10-28 21:15:27,069 [salt.state       ][INFO    ][31862] Executing state glusterfs.created for keystone-credential-keys
2017-10-28 21:15:27,070 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:27,095 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:27,184 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:27,205 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:27,298 [salt.state       ][INFO    ][31862] Volume keystone-credential-keys already exists and is started.
2017-10-28 21:15:27,298 [salt.state       ][INFO    ][31862] Completed state [keystone-credential-keys] at time 21:15:27.298154 duration_in_ms=230.21
2017-10-28 21:15:27,307 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 21:15:27.307450
2017-10-28 21:15:27,308 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-10-28 21:15:27,308 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-28 21:15:27,396 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:27,398 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 21:15:27.396981 duration_in_ms=89.529
2017-10-28 21:15:27,399 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 21:15:27.398757
2017-10-28 21:15:27,399 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-28 21:15:27,401 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-28 21:15:27,501 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:27,503 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 21:15:27.502908 duration_in_ms=104.151
2017-10-28 21:15:27,505 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 21:15:27.504497
2017-10-28 21:15:27,505 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-10-28 21:15:27,507 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-28 21:15:27,596 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:27,598 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 21:15:27.596699 duration_in_ms=92.202
2017-10-28 21:15:27,599 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 21:15:27.598787
2017-10-28 21:15:27,600 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-28 21:15:27,601 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-28 21:15:27,699 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:27,699 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 21:15:27.699321 duration_in_ms=100.533
2017-10-28 21:15:27,701 [salt.state       ][INFO    ][31862] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 21:15:27.700788
2017-10-28 21:15:27,702 [salt.state       ][INFO    ][31862] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-10-28 21:15:27,703 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-28 21:15:27,799 [salt.state       ][INFO    ][31862] unless execution succeeded
2017-10-28 21:15:27,801 [salt.state       ][INFO    ][31862] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 21:15:27.800614 duration_in_ms=99.826
2017-10-28 21:15:27,805 [salt.state       ][INFO    ][31862] Running state [keystone-credential-keys] at time 21:15:27.804787
2017-10-28 21:15:27,805 [salt.state       ][INFO    ][31862] Executing state glusterfs.started for keystone-credential-keys
2017-10-28 21:15:27,807 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:27,828 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:27,919 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --version' in directory '/root'
2017-10-28 21:15:27,940 [salt.loaded.int.module.cmdmod][INFO    ][31862] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-28 21:15:28,038 [salt.state       ][INFO    ][31862] Volume keystone-credential-keys is already started
2017-10-28 21:15:28,038 [salt.state       ][INFO    ][31862] Completed state [keystone-credential-keys] at time 21:15:28.037975 duration_in_ms=233.187
2017-10-28 21:15:28,043 [salt.minion      ][INFO    ][31862] Returning information for job: 20171028211521485787
2017-10-28 21:15:37,466 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command test.ping with jid 20171028211537459524
2017-10-28 21:15:37,495 [salt.minion      ][INFO    ][32245] Starting a new job with PID 32245
2017-10-28 21:15:37,539 [salt.minion      ][INFO    ][32245] Returning information for job: 20171028211537459524
2017-10-28 21:15:37,676 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command cmd.run with jid 20171028211537662149
2017-10-28 21:15:37,700 [salt.minion      ][INFO    ][32250] Starting a new job with PID 32250
2017-10-28 21:15:37,709 [salt.loaded.int.module.cmdmod][INFO    ][32250] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-10-28 21:15:37,891 [salt.minion      ][INFO    ][32250] Returning information for job: 20171028211537662149
2017-10-28 21:22:34,334 [salt.utils.schedule][INFO    ][23509] Running scheduled job: __mine_interval
2017-10-28 21:38:33,824 [salt.minion      ][INFO    ][23509] User sudo_ubuntu Executing command cp.push_dir with jid 20171028213833814110
2017-10-28 21:38:33,854 [salt.minion      ][INFO    ][32463] Starting a new job with PID 32463
