2017-11-10 07:01:09,567 [salt.cli.daemons ][WARNING ][1958] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-10 07:02:05,157 [salt.state       ][ERROR   ][3256] No contents found in top file
2017-11-10 07:05:25,538 [salt.utils.parsers][WARNING ][1958] Minion received a SIGTERM. Exiting.
2017-11-10 07:07:37,725 [salt.cli.daemons ][WARNING ][2821] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-10 07:10:09,492 [py.warnings      ][WARNING ][15794] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2017-11-10 07:15:47,405 [salt.loaded.int.module.cmdmod][ERROR   ][15794] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-10 07:15:47,406 [salt.loaded.int.module.cmdmod][ERROR   ][15794] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-10 07:15:53,335 [salt.loaded.int.module.cmdmod][ERROR   ][15794] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-10 07:15:53,335 [salt.loaded.int.module.cmdmod][ERROR   ][15794] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-10 07:16:01,089 [salt.loaded.int.module.cmdmod][ERROR   ][15794] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-10 07:16:01,089 [salt.loaded.int.module.cmdmod][ERROR   ][15794] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-10 07:16:10,225 [salt.loaded.int.module.cmdmod][ERROR   ][15794] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-10 07:16:10,226 [salt.loaded.int.module.cmdmod][ERROR   ][15794] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-10 07:16:18,194 [salt.loaded.int.module.cmdmod][ERROR   ][15794] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-10 07:16:18,194 [salt.loaded.int.module.cmdmod][ERROR   ][15794] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-10 07:16:24,393 [salt.loaded.int.module.cmdmod][ERROR   ][15794] Command 'while true; do salt-call saltutil.running|grep fun: && continue; salt-call --local service.restart salt-minion; break; done' failed with return code: None
2017-11-10 07:16:28,147 [salt.state       ][ERROR   ][23475] No contents found in top file
2017-11-10 07:16:34,212 [salt.loaded.int.module.cmdmod][INFO    ][23973] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-10 07:16:34,220 [salt.loaded.int.module.cmdmod][INFO    ][23973] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-10 07:16:34,232 [salt.loaded.int.module.cmdmod][INFO    ][23973] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-11-10 07:16:34,243 [salt.utils.parsers][WARNING ][2821] Minion received a SIGTERM. Exiting.
2017-11-10 07:16:34,512 [salt.cli.daemons ][INFO    ][24091] Setting up the Salt Minion "kvm03.baremetal-mcp-ocata-ovs-dpdk-ha.local"
2017-11-10 07:16:35,042 [salt.minion      ][INFO    ][24091] Creating minion process manager
2017-11-10 07:16:35,043 [salt.cli.daemons ][WARNING ][24091] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-10 07:16:35,043 [salt.cli.daemons ][INFO    ][24091] The Salt Minion is starting up
2017-11-10 07:16:35,043 [salt.minion      ][INFO    ][24091] Minion is starting as user 'root'
2017-11-10 07:16:35,043 [salt.utils.event ][INFO    ][24091] Starting pull socket on /var/run/salt/minion/minion_event_4fcafbd0f5_pull.ipc
2017-11-10 07:16:36,025 [salt.loaded.int.module.cmdmod][INFO    ][24091] Executing command ['date', '+%z'] in directory '/root'
2017-11-10 07:16:36,034 [salt.utils.schedule][INFO    ][24091] Updating job settings for scheduled job: __mine_interval
2017-11-10 07:16:36,037 [salt.minion      ][INFO    ][24091] Added mine.update to scheduler
2017-11-10 07:16:36,113 [salt.minion      ][INFO    ][24091] Minion is ready to receive requests!
2017-11-10 07:16:37,115 [salt.utils.schedule][INFO    ][24091] Running scheduled job: __mine_interval
2017-11-10 07:16:43,984 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command saltutil.find_job with jid 20171110071643970121
2017-11-10 07:16:43,997 [salt.minion      ][INFO    ][24221] Starting a new job with PID 24221
2017-11-10 07:16:44,008 [salt.minion      ][INFO    ][24221] Returning information for job: 20171110071643970121
2017-11-10 07:17:05,027 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command state.sls with jid 20171110071705012479
2017-11-10 07:17:05,039 [salt.minion      ][INFO    ][24237] Starting a new job with PID 24237
2017-11-10 07:17:07,490 [salt.state       ][INFO    ][24237] Loading fresh modules for state activity
2017-11-10 07:17:07,530 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-11-10 07:17:07,549 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-11-10 07:17:07,572 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-10 07:17:07,644 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-11-10 07:17:07,752 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-11-10 07:17:07,778 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-10 07:17:07,872 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-10 07:17:07,890 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-10 07:17:07,934 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-10 07:17:08,022 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-10 07:17:08,035 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-10 07:17:08,050 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-10 07:17:08,058 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-10 07:17:08,081 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-10 07:17:08,095 [py.warnings      ][WARNING ][24237] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2017-11-10 07:17:08,095 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command 'salt-minion --version' in directory '/root'
2017-11-10 07:17:08,358 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-10 07:17:08,376 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-10 07:17:08,387 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-10 07:17:08,412 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-10 07:17:08,498 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-10 07:17:08,533 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-10 07:17:08,591 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-11-10 07:17:08,615 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-10 07:17:08,667 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-10 07:17:08,684 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-10 07:17:08,726 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-10 07:17:08,743 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-10 07:17:08,756 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-10 07:17:08,840 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-10 07:17:08,847 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-10 07:17:08,868 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-10 07:17:08,883 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command 'salt-minion --version' in directory '/root'
2017-11-10 07:17:09,140 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-10 07:17:09,209 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-10 07:17:09,221 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-10 07:17:09,233 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-10 07:17:09,279 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-10 07:17:09,320 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-10 07:17:09,385 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-11-10 07:17:09,483 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-10 07:17:09,883 [salt.state       ][INFO    ][24237] Running state [salt-minion] at time 07:17:09.882441
2017-11-10 07:17:09,883 [salt.state       ][INFO    ][24237] Executing state pkg.installed for salt-minion
2017-11-10 07:17:09,883 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-10 07:17:10,156 [salt.state       ][INFO    ][24237] Package salt-minion is already installed
2017-11-10 07:17:10,156 [salt.state       ][INFO    ][24237] Completed state [salt-minion] at time 07:17:10.156398 duration_in_ms=273.957
2017-11-10 07:17:10,157 [salt.state       ][INFO    ][24237] Running state [salt_minion_dependency_packages] at time 07:17:10.156646
2017-11-10 07:17:10,157 [salt.state       ][INFO    ][24237] Executing state pkg.installed for salt_minion_dependency_packages
2017-11-10 07:17:10,159 [salt.state       ][INFO    ][24237] All specified packages are already installed
2017-11-10 07:17:10,160 [salt.state       ][INFO    ][24237] Completed state [salt_minion_dependency_packages] at time 07:17:10.159596 duration_in_ms=2.951
2017-11-10 07:17:10,161 [salt.state       ][INFO    ][24237] Running state [/etc/salt/minion.d/minion.conf] at time 07:17:10.161298
2017-11-10 07:17:10,161 [salt.state       ][INFO    ][24237] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-11-10 07:17:10,183 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-11-10 07:17:10,264 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-10 07:17:10,337 [salt.fileclient  ][INFO    ][24237] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-10 07:17:10,371 [salt.state       ][INFO    ][24237] File /etc/salt/minion.d/minion.conf is in the correct state
2017-11-10 07:17:10,371 [salt.state       ][INFO    ][24237] Completed state [/etc/salt/minion.d/minion.conf] at time 07:17:10.370758 duration_in_ms=209.46
2017-11-10 07:17:10,371 [salt.state       ][INFO    ][24237] Running state [/etc/salt/minion.d/_virt.conf] at time 07:17:10.371066
2017-11-10 07:17:10,371 [salt.state       ][INFO    ][24237] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-11-10 07:17:10,525 [salt.state       ][INFO    ][24237] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-11-10 07:17:10,525 [salt.state       ][INFO    ][24237] Completed state [/etc/salt/minion.d/_virt.conf] at time 07:17:10.525291 duration_in_ms=154.225
2017-11-10 07:17:10,527 [salt.state       ][INFO    ][24237] Running state [salt-minion] at time 07:17:10.527040
2017-11-10 07:17:10,527 [salt.state       ][INFO    ][24237] Executing state service.running for salt-minion
2017-11-10 07:17:10,528 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-10 07:17:10,544 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-11-10 07:17:10,552 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-10 07:17:10,558 [salt.state       ][INFO    ][24237] The service salt-minion is already running
2017-11-10 07:17:10,558 [salt.state       ][INFO    ][24237] Completed state [salt-minion] at time 07:17:10.558226 duration_in_ms=31.187
2017-11-10 07:17:10,559 [salt.state       ][INFO    ][24237] Running state [/etc/salt/grains.d] at time 07:17:10.559412
2017-11-10 07:17:10,560 [salt.state       ][INFO    ][24237] Executing state file.directory for /etc/salt/grains.d
2017-11-10 07:17:10,560 [salt.state       ][INFO    ][24237] Directory /etc/salt/grains.d is in the correct state
2017-11-10 07:17:10,560 [salt.state       ][INFO    ][24237] Completed state [/etc/salt/grains.d] at time 07:17:10.560260 duration_in_ms=0.849
2017-11-10 07:17:10,561 [salt.state       ][INFO    ][24237] Running state [/etc/salt/grains] at time 07:17:10.560691
2017-11-10 07:17:10,567 [salt.state       ][INFO    ][24237] Executing state file.managed for /etc/salt/grains
2017-11-10 07:17:10,567 [salt.state       ][INFO    ][24237] File /etc/salt/grains exists with proper permissions. No changes made.
2017-11-10 07:17:10,567 [salt.state       ][INFO    ][24237] Completed state [/etc/salt/grains] at time 07:17:10.567330 duration_in_ms=6.639
2017-11-10 07:17:10,568 [salt.state       ][INFO    ][24237] Running state [/etc/salt/grains.d/placeholder] at time 07:17:10.567581
2017-11-10 07:17:10,568 [salt.state       ][INFO    ][24237] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-11-10 07:17:10,568 [salt.state       ][INFO    ][24237] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-11-10 07:17:10,568 [salt.state       ][INFO    ][24237] Completed state [/etc/salt/grains.d/placeholder] at time 07:17:10.568114 duration_in_ms=0.534
2017-11-10 07:17:10,568 [salt.state       ][INFO    ][24237] Running state [/etc/salt/grains.d/sphinx] at time 07:17:10.568402
2017-11-10 07:17:10,569 [salt.state       ][INFO    ][24237] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-11-10 07:17:10,771 [salt.state       ][INFO    ][24237] File changed:
--- 
+++ 
@@ -49,7 +49,6 @@
               - 10.167.4.143
               - 127.0.0.1
               - 192.168.11.202
-              - 192.168.122.1
         system:
           name: System
           param:

2017-11-10 07:17:10,771 [salt.state       ][INFO    ][24237] Completed state [/etc/salt/grains.d/sphinx] at time 07:17:10.771394 duration_in_ms=202.991
2017-11-10 07:17:10,772 [salt.state       ][INFO    ][24237] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 07:17:10.772070
2017-11-10 07:17:10,772 [salt.state       ][INFO    ][24237] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-10 07:17:10,773 [salt.state       ][INFO    ][24237] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-10 07:17:10,773 [salt.state       ][INFO    ][24237] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 07:17:10.772599 duration_in_ms=0.53
2017-11-10 07:17:10,773 [salt.state       ][INFO    ][24237] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 07:17:10.772719
2017-11-10 07:17:10,773 [salt.state       ][INFO    ][24237] Executing state cmd.mod_watch for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-10 07:17:10,773 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command 'python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"' in directory '/root'
2017-11-10 07:17:10,862 [salt.state       ][INFO    ][24237] {'pid': 24285, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-10 07:17:10,862 [salt.state       ][INFO    ][24237] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 07:17:10.861880 duration_in_ms=89.16
2017-11-10 07:17:10,862 [salt.state       ][INFO    ][24237] Running state [/etc/salt/grains.d/dns_records] at time 07:17:10.862424
2017-11-10 07:17:10,863 [salt.state       ][INFO    ][24237] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-11-10 07:17:11,055 [salt.state       ][INFO    ][24237] File /etc/salt/grains.d/dns_records is in the correct state
2017-11-10 07:17:11,055 [salt.state       ][INFO    ][24237] Completed state [/etc/salt/grains.d/dns_records] at time 07:17:11.055420 duration_in_ms=193.008
2017-11-10 07:17:11,056 [salt.state       ][INFO    ][24237] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 07:17:11.056155
2017-11-10 07:17:11,056 [salt.state       ][INFO    ][24237] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-10 07:17:11,057 [salt.state       ][INFO    ][24237] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-10 07:17:11,057 [salt.state       ][INFO    ][24237] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 07:17:11.056812 duration_in_ms=0.658
2017-11-10 07:17:11,057 [salt.state       ][INFO    ][24237] Running state [/etc/salt/grains.d/salt] at time 07:17:11.057152
2017-11-10 07:17:11,057 [salt.state       ][INFO    ][24237] Executing state file.managed for /etc/salt/grains.d/salt
2017-11-10 07:17:11,143 [salt.state       ][INFO    ][24237] File /etc/salt/grains.d/salt is in the correct state
2017-11-10 07:17:11,143 [salt.state       ][INFO    ][24237] Completed state [/etc/salt/grains.d/salt] at time 07:17:11.142987 duration_in_ms=85.834
2017-11-10 07:17:11,144 [salt.state       ][INFO    ][24237] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 07:17:11.143697
2017-11-10 07:17:11,144 [salt.state       ][INFO    ][24237] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-10 07:17:11,144 [salt.state       ][INFO    ][24237] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-10 07:17:11,144 [salt.state       ][INFO    ][24237] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 07:17:11.144361 duration_in_ms=0.664
2017-11-10 07:17:11,146 [salt.state       ][INFO    ][24237] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 07:17:11.145708
2017-11-10 07:17:11,146 [salt.state       ][INFO    ][24237] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-10 07:17:11,146 [salt.state       ][INFO    ][24237] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-10 07:17:11,146 [salt.state       ][INFO    ][24237] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 07:17:11.146354 duration_in_ms=0.646
2017-11-10 07:17:11,147 [salt.state       ][INFO    ][24237] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 07:17:11.146519
2017-11-10 07:17:11,147 [salt.state       ][INFO    ][24237] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-10 07:17:11,148 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-11-10 07:17:11,157 [salt.state       ][INFO    ][24237] {'pid': 24287, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-10 07:17:11,157 [salt.state       ][INFO    ][24237] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 07:17:11.157446 duration_in_ms=10.928
2017-11-10 07:17:11,158 [salt.state       ][INFO    ][24237] Running state [mine.update] at time 07:17:11.158058
2017-11-10 07:17:11,158 [salt.state       ][INFO    ][24237] Executing state module.wait for mine.update
2017-11-10 07:17:11,159 [salt.state       ][INFO    ][24237] No changes made for mine.update
2017-11-10 07:17:11,159 [salt.state       ][INFO    ][24237] Completed state [mine.update] at time 07:17:11.158728 duration_in_ms=0.67
2017-11-10 07:17:11,159 [salt.state       ][INFO    ][24237] Running state [mine.update] at time 07:17:11.158892
2017-11-10 07:17:11,159 [salt.state       ][INFO    ][24237] Executing state module.mod_watch for mine.update
2017-11-10 07:17:11,679 [salt.state       ][INFO    ][24237] {'ret': True}
2017-11-10 07:17:11,679 [salt.state       ][INFO    ][24237] Completed state [mine.update] at time 07:17:11.679326 duration_in_ms=520.433
2017-11-10 07:17:11,680 [salt.state       ][INFO    ][24237] Running state [ca-certificates] at time 07:17:11.679615
2017-11-10 07:17:11,680 [salt.state       ][INFO    ][24237] Executing state pkg.installed for ca-certificates
2017-11-10 07:17:11,683 [salt.state       ][INFO    ][24237] Package ca-certificates is already installed
2017-11-10 07:17:11,684 [salt.state       ][INFO    ][24237] Completed state [ca-certificates] at time 07:17:11.683535 duration_in_ms=3.92
2017-11-10 07:17:11,684 [salt.state       ][INFO    ][24237] Running state [update-ca-certificates] at time 07:17:11.684183
2017-11-10 07:17:11,684 [salt.state       ][INFO    ][24237] Executing state cmd.wait for update-ca-certificates
2017-11-10 07:17:11,685 [salt.state       ][INFO    ][24237] No changes made for update-ca-certificates
2017-11-10 07:17:11,685 [salt.state       ][INFO    ][24237] Completed state [update-ca-certificates] at time 07:17:11.684795 duration_in_ms=0.612
2017-11-10 07:17:11,685 [salt.state       ][INFO    ][24237] Running state [libvirt-dev] at time 07:17:11.684986
2017-11-10 07:17:11,685 [salt.state       ][INFO    ][24237] Executing state pkg.installed for libvirt-dev
2017-11-10 07:17:11,688 [salt.state       ][INFO    ][24237] Package libvirt-dev is already installed
2017-11-10 07:17:11,688 [salt.state       ][INFO    ][24237] Completed state [libvirt-dev] at time 07:17:11.688176 duration_in_ms=3.19
2017-11-10 07:17:11,688 [salt.state       ][INFO    ][24237] Running state [pkg-config] at time 07:17:11.688369
2017-11-10 07:17:11,689 [salt.state       ][INFO    ][24237] Executing state pkg.installed for pkg-config
2017-11-10 07:17:11,691 [salt.state       ][INFO    ][24237] Package pkg-config is already installed
2017-11-10 07:17:11,692 [salt.state       ][INFO    ][24237] Completed state [pkg-config] at time 07:17:11.691469 duration_in_ms=3.1
2017-11-10 07:17:11,692 [salt.state       ][INFO    ][24237] Running state [virtng.init] at time 07:17:11.691654
2017-11-10 07:17:11,692 [salt.state       ][INFO    ][24237] Executing state module.run for virtng.init
2017-11-10 07:17:11,750 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command ['git', '--version'] in directory '/root'
2017-11-10 07:17:11,921 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command 'virsh list --all --name| grep -E "^mdb03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-10 07:17:11,940 [salt.state       ][INFO    ][24237] unless execution succeeded
2017-11-10 07:17:11,940 [salt.state       ][INFO    ][24237] Completed state [virtng.init] at time 07:17:11.939829 duration_in_ms=248.174
2017-11-10 07:17:11,940 [salt.state       ][INFO    ][24237] Running state [virt.set_autostart] at time 07:17:11.940065
2017-11-10 07:17:11,940 [salt.state       ][INFO    ][24237] Executing state module.run for virt.set_autostart
2017-11-10 07:17:11,942 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command 'virsh list --autostart --name| grep -E "^mdb03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-10 07:17:11,957 [salt.state       ][INFO    ][24237] unless execution succeeded
2017-11-10 07:17:11,957 [salt.state       ][INFO    ][24237] Completed state [virt.set_autostart] at time 07:17:11.957195 duration_in_ms=17.129
2017-11-10 07:17:11,957 [salt.state       ][INFO    ][24237] Running state [virtng.init] at time 07:17:11.957403
2017-11-10 07:17:11,958 [salt.state       ][INFO    ][24237] Executing state module.run for virtng.init
2017-11-10 07:17:11,960 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command 'virsh list --all --name| grep -E "^dbs03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-10 07:17:11,975 [salt.state       ][INFO    ][24237] unless execution succeeded
2017-11-10 07:17:12,054 [salt.state       ][INFO    ][24237] Completed state [virtng.init] at time 07:17:12.053537 duration_in_ms=96.134
2017-11-10 07:17:12,054 [salt.state       ][INFO    ][24237] Running state [virt.set_autostart] at time 07:17:12.053812
2017-11-10 07:17:12,054 [salt.state       ][INFO    ][24237] Executing state module.run for virt.set_autostart
2017-11-10 07:17:12,056 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command 'virsh list --autostart --name| grep -E "^dbs03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-10 07:17:12,072 [salt.state       ][INFO    ][24237] unless execution succeeded
2017-11-10 07:17:12,072 [salt.state       ][INFO    ][24237] Completed state [virt.set_autostart] at time 07:17:12.071922 duration_in_ms=18.11
2017-11-10 07:17:12,072 [salt.state       ][INFO    ][24237] Running state [virtng.init] at time 07:17:12.072151
2017-11-10 07:17:12,072 [salt.state       ][INFO    ][24237] Executing state module.run for virtng.init
2017-11-10 07:17:12,074 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command 'virsh list --all --name| grep -E "^prx02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-10 07:17:12,088 [salt.state       ][INFO    ][24237] unless execution succeeded
2017-11-10 07:17:12,088 [salt.state       ][INFO    ][24237] Completed state [virtng.init] at time 07:17:12.088066 duration_in_ms=15.916
2017-11-10 07:17:12,088 [salt.state       ][INFO    ][24237] Running state [virt.set_autostart] at time 07:17:12.088319
2017-11-10 07:17:12,089 [salt.state       ][INFO    ][24237] Executing state module.run for virt.set_autostart
2017-11-10 07:17:12,091 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command 'virsh list --autostart --name| grep -E "^prx02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-10 07:17:12,106 [salt.state       ][INFO    ][24237] unless execution succeeded
2017-11-10 07:17:12,106 [salt.state       ][INFO    ][24237] Completed state [virt.set_autostart] at time 07:17:12.105966 duration_in_ms=17.646
2017-11-10 07:17:12,106 [salt.state       ][INFO    ][24237] Running state [virtng.init] at time 07:17:12.106205
2017-11-10 07:17:12,106 [salt.state       ][INFO    ][24237] Executing state module.run for virtng.init
2017-11-10 07:17:12,108 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command 'virsh list --all --name| grep -E "^msg03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-10 07:17:12,123 [salt.state       ][INFO    ][24237] unless execution succeeded
2017-11-10 07:17:12,123 [salt.state       ][INFO    ][24237] Completed state [virtng.init] at time 07:17:12.123411 duration_in_ms=17.205
2017-11-10 07:17:12,124 [salt.state       ][INFO    ][24237] Running state [virt.set_autostart] at time 07:17:12.123688
2017-11-10 07:17:12,124 [salt.state       ][INFO    ][24237] Executing state module.run for virt.set_autostart
2017-11-10 07:17:12,126 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command 'virsh list --autostart --name| grep -E "^msg03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-10 07:17:12,143 [salt.state       ][INFO    ][24237] unless execution succeeded
2017-11-10 07:17:12,143 [salt.state       ][INFO    ][24237] Completed state [virt.set_autostart] at time 07:17:12.142731 duration_in_ms=19.042
2017-11-10 07:17:12,143 [salt.state       ][INFO    ][24237] Running state [virtng.init] at time 07:17:12.142970
2017-11-10 07:17:12,143 [salt.state       ][INFO    ][24237] Executing state module.run for virtng.init
2017-11-10 07:17:12,145 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command 'virsh list --all --name| grep -E "^ctl03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-10 07:17:12,159 [salt.state       ][INFO    ][24237] unless execution succeeded
2017-11-10 07:17:12,160 [salt.state       ][INFO    ][24237] Completed state [virtng.init] at time 07:17:12.159677 duration_in_ms=16.706
2017-11-10 07:17:12,160 [salt.state       ][INFO    ][24237] Running state [virt.set_autostart] at time 07:17:12.159904
2017-11-10 07:17:12,160 [salt.state       ][INFO    ][24237] Executing state module.run for virt.set_autostart
2017-11-10 07:17:12,162 [salt.loaded.int.module.cmdmod][INFO    ][24237] Executing command 'virsh list --autostart --name| grep -E "^ctl03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-10 07:17:12,176 [salt.state       ][INFO    ][24237] unless execution succeeded
2017-11-10 07:17:12,176 [salt.state       ][INFO    ][24237] Completed state [virt.set_autostart] at time 07:17:12.176039 duration_in_ms=16.135
2017-11-10 07:17:12,178 [salt.minion      ][INFO    ][24237] Returning information for job: 20171110071705012479
2017-11-10 07:17:50,596 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command test.ping with jid 20171110071750576244
2017-11-10 07:17:50,613 [salt.minion      ][INFO    ][24343] Starting a new job with PID 24343
2017-11-10 07:17:50,641 [salt.minion      ][INFO    ][24343] Returning information for job: 20171110071750576244
2017-11-10 07:17:57,607 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171110071757594465
2017-11-10 07:17:57,629 [salt.minion      ][INFO    ][24348] Starting a new job with PID 24348
2017-11-10 07:18:00,924 [salt.state       ][INFO    ][24348] Loading fresh modules for state activity
2017-11-10 07:18:00,945 [salt.state       ][ERROR   ][24348] No contents found in top file
2017-11-10 07:18:00,946 [salt.utils.extmods][INFO    ][24348] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-11-10 07:18:00,948 [salt.utils.extmods][INFO    ][24348] Syncing beacons for environment 'base'
2017-11-10 07:18:00,948 [salt.utils.extmods][INFO    ][24348] Loading cache from salt://_beacons, for base)
2017-11-10 07:18:00,948 [salt.fileclient  ][INFO    ][24348] Caching directory '_beacons/' for environment 'base'
2017-11-10 07:18:00,969 [salt.utils.extmods][INFO    ][24348] Syncing modules for environment 'base'
2017-11-10 07:18:00,969 [salt.utils.extmods][INFO    ][24348] Loading cache from salt://_modules, for base)
2017-11-10 07:18:00,969 [salt.fileclient  ][INFO    ][24348] Caching directory '_modules/' for environment 'base'
2017-11-10 07:18:01,383 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-11-10 07:18:01,410 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-11-10 07:18:01,443 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-11-10 07:18:01,458 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-11-10 07:18:01,500 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-11-10 07:18:01,512 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-11-10 07:18:01,572 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-11-10 07:18:01,605 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-11-10 07:18:01,614 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-11-10 07:18:01,634 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-11-10 07:18:01,672 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-11-10 07:18:01,684 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-11-10 07:18:01,708 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-11-10 07:18:01,731 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-11-10 07:18:01,860 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-11-10 07:18:01,941 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-11-10 07:18:01,966 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-11-10 07:18:02,002 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-11-10 07:18:02,029 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-11-10 07:18:02,055 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-11-10 07:18:02,109 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-11-10 07:18:02,129 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-11-10 07:18:02,193 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-11-10 07:18:02,202 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-11-10 07:18:02,233 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-11-10 07:18:02,265 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-11-10 07:18:02,280 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-11-10 07:18:02,294 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-11-10 07:18:02,333 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-11-10 07:18:02,340 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-11-10 07:18:02,350 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-11-10 07:18:02,358 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-11-10 07:18:02,407 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-11-10 07:18:02,410 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-11-10 07:18:02,410 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-11-10 07:18:02,410 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-11-10 07:18:02,410 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-11-10 07:18:02,411 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-11-10 07:18:02,411 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-11-10 07:18:02,411 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-11-10 07:18:02,411 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-11-10 07:18:02,411 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-11-10 07:18:02,412 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-11-10 07:18:02,412 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-11-10 07:18:02,412 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-11-10 07:18:02,412 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-11-10 07:18:02,412 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-11-10 07:18:02,413 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-11-10 07:18:02,413 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-11-10 07:18:02,413 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-11-10 07:18:02,413 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-11-10 07:18:02,413 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-11-10 07:18:02,414 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-11-10 07:18:02,414 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-11-10 07:18:02,414 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-11-10 07:18:02,414 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-11-10 07:18:02,415 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-11-10 07:18:02,415 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-11-10 07:18:02,415 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-11-10 07:18:02,415 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-11-10 07:18:02,416 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-11-10 07:18:02,416 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-11-10 07:18:02,416 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-11-10 07:18:02,416 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-11-10 07:18:02,416 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-11-10 07:18:02,416 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/testing/django_client_proxy.py' to '/var/cache/salt/minion/extmods/modules/testing/django_client_proxy.py'
2017-11-10 07:18:02,417 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-11-10 07:18:02,417 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-11-10 07:18:02,421 [salt.utils.extmods][INFO    ][24348] Syncing states for environment 'base'
2017-11-10 07:18:02,421 [salt.utils.extmods][INFO    ][24348] Loading cache from salt://_states, for base)
2017-11-10 07:18:02,422 [salt.fileclient  ][INFO    ][24348] Caching directory '_states/' for environment 'base'
2017-11-10 07:18:02,484 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-11-10 07:18:02,517 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-11-10 07:18:02,524 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-11-10 07:18:02,553 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-11-10 07:18:02,584 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-11-10 07:18:02,630 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-11-10 07:18:02,645 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-11-10 07:18:02,654 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-11-10 07:18:02,711 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-11-10 07:18:02,789 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-11-10 07:18:02,797 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-11-10 07:18:02,828 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-11-10 07:18:02,865 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-11-10 07:18:02,878 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-11-10 07:18:02,887 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-11-10 07:18:02,893 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-11-10 07:18:02,913 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-11-10 07:18:02,945 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-11-10 07:18:02,967 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-11-10 07:18:02,983 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-11-10 07:18:02,993 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-11-10 07:18:03,004 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-11-10 07:18:03,038 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-11-10 07:18:03,057 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-11-10 07:18:03,068 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-11-10 07:18:03,078 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-11-10 07:18:03,100 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-11-10 07:18:03,110 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-11-10 07:18:03,140 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-11-10 07:18:03,142 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-11-10 07:18:03,143 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-11-10 07:18:03,143 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-11-10 07:18:03,143 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-11-10 07:18:03,143 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-11-10 07:18:03,144 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-11-10 07:18:03,144 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-11-10 07:18:03,144 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-11-10 07:18:03,144 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-11-10 07:18:03,145 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-11-10 07:18:03,145 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-11-10 07:18:03,145 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-11-10 07:18:03,145 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-11-10 07:18:03,145 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-11-10 07:18:03,146 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-11-10 07:18:03,146 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-11-10 07:18:03,146 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-11-10 07:18:03,146 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-11-10 07:18:03,146 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-11-10 07:18:03,146 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-11-10 07:18:03,147 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-11-10 07:18:03,147 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-11-10 07:18:03,147 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-11-10 07:18:03,147 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-11-10 07:18:03,147 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-11-10 07:18:03,148 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-11-10 07:18:03,148 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-11-10 07:18:03,148 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-11-10 07:18:03,148 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-11-10 07:18:03,149 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-11-10 07:18:03,150 [salt.utils.extmods][INFO    ][24348] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-11-10 07:18:03,153 [salt.utils.extmods][INFO    ][24348] Syncing sdb for environment 'base'
2017-11-10 07:18:03,153 [salt.utils.extmods][INFO    ][24348] Loading cache from salt://_sdb, for base)
2017-11-10 07:18:03,153 [salt.fileclient  ][INFO    ][24348] Caching directory '_sdb/' for environment 'base'
2017-11-10 07:18:03,174 [salt.utils.extmods][INFO    ][24348] Syncing grains for environment 'base'
2017-11-10 07:18:03,175 [salt.utils.extmods][INFO    ][24348] Loading cache from salt://_grains, for base)
2017-11-10 07:18:03,175 [salt.fileclient  ][INFO    ][24348] Caching directory '_grains/' for environment 'base'
2017-11-10 07:18:03,221 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-11-10 07:18:03,236 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-11-10 07:18:03,245 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-11-10 07:18:03,620 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-11-10 07:18:03,654 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-11-10 07:18:03,672 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-11-10 07:18:03,681 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-11-10 07:18:03,689 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-11-10 07:18:03,698 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-11-10 07:18:03,705 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-11-10 07:18:03,714 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-11-10 07:18:03,845 [salt.fileclient  ][INFO    ][24348] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-11-10 07:18:03,848 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-11-10 07:18:03,848 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-11-10 07:18:03,848 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-11-10 07:18:03,849 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-11-10 07:18:03,849 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-11-10 07:18:03,849 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-11-10 07:18:03,849 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-11-10 07:18:03,849 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-11-10 07:18:03,850 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-11-10 07:18:03,850 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-11-10 07:18:03,850 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-11-10 07:18:03,850 [salt.utils.extmods][INFO    ][24348] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-11-10 07:18:03,851 [salt.utils.extmods][INFO    ][24348] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-11-10 07:18:03,854 [salt.utils.extmods][INFO    ][24348] Syncing renderers for environment 'base'
2017-11-10 07:18:03,854 [salt.utils.extmods][INFO    ][24348] Loading cache from salt://_renderers, for base)
2017-11-10 07:18:03,854 [salt.fileclient  ][INFO    ][24348] Caching directory '_renderers/' for environment 'base'
2017-11-10 07:18:03,880 [salt.utils.extmods][INFO    ][24348] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-11-10 07:18:03,882 [salt.utils.extmods][INFO    ][24348] Syncing returners for environment 'base'
2017-11-10 07:18:03,882 [salt.utils.extmods][INFO    ][24348] Loading cache from salt://_returners, for base)
2017-11-10 07:18:03,882 [salt.fileclient  ][INFO    ][24348] Caching directory '_returners/' for environment 'base'
2017-11-10 07:18:03,913 [salt.utils.extmods][INFO    ][24348] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-11-10 07:18:03,916 [salt.utils.extmods][INFO    ][24348] Syncing output for environment 'base'
2017-11-10 07:18:03,916 [salt.utils.extmods][INFO    ][24348] Loading cache from salt://_output, for base)
2017-11-10 07:18:03,916 [salt.fileclient  ][INFO    ][24348] Caching directory '_output/' for environment 'base'
2017-11-10 07:18:03,951 [salt.utils.extmods][INFO    ][24348] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-11-10 07:18:03,953 [salt.utils.extmods][INFO    ][24348] Syncing utils for environment 'base'
2017-11-10 07:18:03,953 [salt.utils.extmods][INFO    ][24348] Loading cache from salt://_utils, for base)
2017-11-10 07:18:03,954 [salt.fileclient  ][INFO    ][24348] Caching directory '_utils/' for environment 'base'
2017-11-10 07:18:03,972 [salt.utils.extmods][INFO    ][24348] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-11-10 07:18:03,975 [salt.utils.extmods][INFO    ][24348] Syncing log_handlers for environment 'base'
2017-11-10 07:18:03,975 [salt.utils.extmods][INFO    ][24348] Loading cache from salt://_log_handlers, for base)
2017-11-10 07:18:03,975 [salt.fileclient  ][INFO    ][24348] Caching directory '_log_handlers/' for environment 'base'
2017-11-10 07:18:03,994 [salt.utils.extmods][INFO    ][24348] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-11-10 07:18:03,996 [salt.utils.extmods][INFO    ][24348] Syncing proxy for environment 'base'
2017-11-10 07:18:03,996 [salt.utils.extmods][INFO    ][24348] Loading cache from salt://_proxy, for base)
2017-11-10 07:18:03,996 [salt.fileclient  ][INFO    ][24348] Caching directory '_proxy/' for environment 'base'
2017-11-10 07:18:04,020 [salt.utils.extmods][INFO    ][24348] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-11-10 07:18:04,022 [salt.utils.extmods][INFO    ][24348] Syncing engines for environment 'base'
2017-11-10 07:18:04,022 [salt.utils.extmods][INFO    ][24348] Loading cache from salt://_engines, for base)
2017-11-10 07:18:04,022 [salt.fileclient  ][INFO    ][24348] Caching directory '_engines/' for environment 'base'
2017-11-10 07:18:04,484 [salt.minion      ][INFO    ][24348] Returning information for job: 20171110071757594465
2017-11-10 07:25:55,875 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command test.ping with jid 20171110072555870104
2017-11-10 07:25:55,890 [salt.minion      ][INFO    ][24572] Starting a new job with PID 24572
2017-11-10 07:25:55,921 [salt.minion      ][INFO    ][24572] Returning information for job: 20171110072555870104
2017-11-10 07:29:36,264 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command state.sls with jid 20171110072936251962
2017-11-10 07:29:36,275 [salt.minion      ][INFO    ][24644] Starting a new job with PID 24644
2017-11-10 07:29:36,749 [salt.state       ][INFO    ][24644] Loading fresh modules for state activity
2017-11-10 07:29:36,773 [salt.fileclient  ][INFO    ][24644] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-10 07:29:36,789 [salt.fileclient  ][INFO    ][24644] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-10 07:29:36,811 [salt.fileclient  ][INFO    ][24644] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-10 07:29:37,106 [salt.state       ][INFO    ][24644] Running state [lsof] at time 07:29:37.106284
2017-11-10 07:29:37,107 [salt.state       ][INFO    ][24644] Executing state pkg.installed for lsof
2017-11-10 07:29:37,107 [salt.loaded.int.module.cmdmod][INFO    ][24644] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-10 07:29:37,375 [salt.state       ][INFO    ][24644] Package lsof is already installed
2017-11-10 07:29:37,375 [salt.state       ][INFO    ][24644] Completed state [lsof] at time 07:29:37.375192 duration_in_ms=268.908
2017-11-10 07:29:37,375 [salt.state       ][INFO    ][24644] Running state [keepalived] at time 07:29:37.375404
2017-11-10 07:29:37,376 [salt.state       ][INFO    ][24644] Executing state pkg.installed for keepalived
2017-11-10 07:29:37,390 [salt.loaded.int.module.cmdmod][INFO    ][24644] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-10 07:29:39,804 [salt.loaded.int.module.cmdmod][INFO    ][24644] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'keepalived'] in directory '/root'
2017-11-10 07:29:46,308 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command saltutil.find_job with jid 20171110072946295795
2017-11-10 07:29:46,316 [salt.minion      ][INFO    ][26034] Starting a new job with PID 26034
2017-11-10 07:29:46,329 [salt.minion      ][INFO    ][26034] Returning information for job: 20171110072946295795
2017-11-10 07:29:47,864 [salt.loaded.int.module.cmdmod][INFO    ][24644] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-10 07:29:47,887 [salt.state       ][INFO    ][24644] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4'
'libsensors4' changed from 'absent' to '1:3.4.0-2'
'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'
'libnl-genl-3-200' changed from 'absent' to '3.2.27-1ubuntu0.16.04.1'

2017-11-10 07:29:47,896 [salt.state       ][INFO    ][24644] Loading fresh modules for state activity
2017-11-10 07:29:47,907 [salt.state       ][INFO    ][24644] Completed state [keepalived] at time 07:29:47.907429 duration_in_ms=10532.025
2017-11-10 07:29:47,910 [salt.state       ][INFO    ][24644] Running state [/etc/keepalived/keepalived.conf] at time 07:29:47.909523
2017-11-10 07:29:47,910 [salt.state       ][INFO    ][24644] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-10 07:29:47,936 [salt.fileclient  ][INFO    ][24644] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-10 07:29:47,968 [salt.fileclient  ][INFO    ][24644] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-10 07:29:47,974 [salt.state       ][INFO    ][24644] File changed:
New file
2017-11-10 07:29:47,974 [salt.state       ][INFO    ][24644] Completed state [/etc/keepalived/keepalived.conf] at time 07:29:47.974007 duration_in_ms=64.484
2017-11-10 07:29:48,033 [salt.state       ][INFO    ][24644] Running state [keepalived] at time 07:29:48.032636
2017-11-10 07:29:48,033 [salt.state       ][INFO    ][24644] Executing state service.running for keepalived
2017-11-10 07:29:48,034 [salt.loaded.int.module.cmdmod][INFO    ][24644] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-10 07:29:48,042 [salt.loaded.int.module.cmdmod][INFO    ][24644] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-10 07:29:48,049 [salt.loaded.int.module.cmdmod][INFO    ][24644] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-10 07:29:48,056 [salt.loaded.int.module.cmdmod][INFO    ][24644] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-10 07:29:48,063 [salt.loaded.int.module.cmdmod][INFO    ][24644] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-11-10 07:29:48,111 [salt.loaded.int.module.cmdmod][INFO    ][24644] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-10 07:29:48,119 [salt.loaded.int.module.cmdmod][INFO    ][24644] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-10 07:29:48,126 [salt.loaded.int.module.cmdmod][INFO    ][24644] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-10 07:29:48,131 [salt.state       ][INFO    ][24644] {'keepalived': True}
2017-11-10 07:29:48,132 [salt.state       ][INFO    ][24644] Completed state [keepalived] at time 07:29:48.131566 duration_in_ms=98.93
2017-11-10 07:29:48,132 [salt.minion      ][INFO    ][24644] Returning information for job: 20171110072936251962
2017-11-10 07:30:01,159 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command pillar.get with jid 20171110073001146273
2017-11-10 07:30:01,180 [salt.minion      ][INFO    ][26270] Starting a new job with PID 26270
2017-11-10 07:30:01,187 [salt.minion      ][INFO    ][26270] Returning information for job: 20171110073001146273
2017-11-10 07:35:12,192 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command state.sls with jid 20171110073512185049
2017-11-10 07:35:12,205 [salt.minion      ][INFO    ][26363] Starting a new job with PID 26363
2017-11-10 07:35:13,598 [salt.state       ][INFO    ][26363] Loading fresh modules for state activity
2017-11-10 07:35:13,620 [salt.fileclient  ][INFO    ][26363] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-10 07:35:13,640 [salt.fileclient  ][INFO    ][26363] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-10 07:35:13,913 [salt.state       ][INFO    ][26363] Running state [attr] at time 07:35:13.913431
2017-11-10 07:35:13,914 [salt.state       ][INFO    ][26363] Executing state pkg.installed for attr
2017-11-10 07:35:13,914 [salt.loaded.int.module.cmdmod][INFO    ][26363] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-10 07:35:14,196 [salt.loaded.int.module.cmdmod][INFO    ][26363] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-10 07:35:16,445 [salt.loaded.int.module.cmdmod][INFO    ][26363] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'attr'] in directory '/root'
2017-11-10 07:35:18,784 [salt.loaded.int.module.cmdmod][INFO    ][26363] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-10 07:35:18,808 [salt.state       ][INFO    ][26363] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-11-10 07:35:18,818 [salt.state       ][INFO    ][26363] Loading fresh modules for state activity
2017-11-10 07:35:18,831 [salt.state       ][INFO    ][26363] Completed state [attr] at time 07:35:18.830568 duration_in_ms=4917.137
2017-11-10 07:35:18,836 [salt.state       ][INFO    ][26363] Running state [glusterfs-server] at time 07:35:18.836004
2017-11-10 07:35:18,836 [salt.state       ][INFO    ][26363] Executing state pkg.installed for glusterfs-server
2017-11-10 07:35:19,021 [salt.loaded.int.module.cmdmod][INFO    ][26363] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'glusterfs-server'] in directory '/root'
2017-11-10 07:35:22,283 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command saltutil.find_job with jid 20171110073522270797
2017-11-10 07:35:22,295 [salt.minion      ][INFO    ][27422] Starting a new job with PID 27422
2017-11-10 07:35:22,310 [salt.minion      ][INFO    ][27422] Returning information for job: 20171110073522270797
2017-11-10 07:35:32,489 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command saltutil.find_job with jid 20171110073532475331
2017-11-10 07:35:32,502 [salt.minion      ][INFO    ][30286] Starting a new job with PID 30286
2017-11-10 07:35:32,521 [salt.minion      ][INFO    ][30286] Returning information for job: 20171110073532475331
2017-11-10 07:35:35,625 [salt.loaded.int.module.cmdmod][INFO    ][26363] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-10 07:35:35,650 [salt.state       ][INFO    ][26363] Made the following changes:
'keyutils' changed from 'absent' to '1.5.9-8ubuntu1'
'nfs-common' changed from 'absent' to '1:1.2.8-9ubuntu12.1'
'rpcbind' changed from 'absent' to '0.2.3-0.2'
'glusterfs-common' changed from 'absent' to '3.7.6-1ubuntu1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'libnfsidmap2' changed from 'absent' to '0.25-5'
'portmap' changed from 'absent' to '1'
'libattr1-dev' changed from 'absent' to '1:2.4.47-2'
'attr-dev' changed from 'absent' to '1'
'acl-dev' changed from 'absent' to '1'
'glusterfs-server' changed from 'absent' to '3.7.6-1ubuntu1'
'glusterfs-client' changed from 'absent' to '3.7.6-1ubuntu1'
'libpython2.7' changed from 'absent' to '2.7.12-1ubuntu0~16.04.1'
'nfs-client' changed from 'absent' to '1'
'liburcu4' changed from 'absent' to '0.9.1-3'
'libacl1-dev' changed from 'absent' to '2.2.52-3'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libtirpc1' changed from 'absent' to '0.2.5-1'

2017-11-10 07:35:35,657 [salt.state       ][INFO    ][26363] Loading fresh modules for state activity
2017-11-10 07:35:35,670 [salt.state       ][INFO    ][26363] Completed state [glusterfs-server] at time 07:35:35.669481 duration_in_ms=16833.475
2017-11-10 07:35:35,674 [salt.state       ][INFO    ][26363] Running state [bc] at time 07:35:35.674138
2017-11-10 07:35:35,674 [salt.state       ][INFO    ][26363] Executing state pkg.installed for bc
2017-11-10 07:35:35,914 [salt.loaded.int.module.cmdmod][INFO    ][26363] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'bc'] in directory '/root'
2017-11-10 07:35:38,603 [salt.loaded.int.module.cmdmod][INFO    ][26363] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-10 07:35:38,634 [salt.state       ][INFO    ][26363] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-11-10 07:35:38,644 [salt.state       ][INFO    ][26363] Loading fresh modules for state activity
2017-11-10 07:35:38,658 [salt.state       ][INFO    ][26363] Completed state [bc] at time 07:35:38.657731 duration_in_ms=2983.593
2017-11-10 07:35:38,724 [salt.state       ][INFO    ][26363] Running state [glusterfs-server] at time 07:35:38.723830
2017-11-10 07:35:38,724 [salt.state       ][INFO    ][26363] Executing state service.running for glusterfs-server
2017-11-10 07:35:38,726 [salt.loaded.int.module.cmdmod][INFO    ][26363] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-10 07:35:38,734 [salt.loaded.int.module.cmdmod][INFO    ][26363] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-10 07:35:38,741 [salt.loaded.int.module.cmdmod][INFO    ][26363] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-10 07:35:38,749 [salt.state       ][INFO    ][26363] The service glusterfs-server is already running
2017-11-10 07:35:38,750 [salt.state       ][INFO    ][26363] Completed state [glusterfs-server] at time 07:35:38.749674 duration_in_ms=25.844
2017-11-10 07:35:38,751 [salt.state       ][INFO    ][26363] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 07:35:38.751088
2017-11-10 07:35:38,751 [salt.state       ][INFO    ][26363] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-10 07:35:38,761 [salt.state       ][INFO    ][26363] File changed:
New file
2017-11-10 07:35:38,762 [salt.state       ][INFO    ][26363] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 07:35:38.761573 duration_in_ms=10.485
2017-11-10 07:35:38,762 [salt.state       ][INFO    ][26363] Running state [/srv/glusterfs/glance] at time 07:35:38.761743
2017-11-10 07:35:38,762 [salt.state       ][INFO    ][26363] Executing state file.directory for /srv/glusterfs/glance
2017-11-10 07:35:38,763 [salt.state       ][INFO    ][26363] {'/srv/glusterfs/glance': 'New Dir'}
2017-11-10 07:35:38,763 [salt.state       ][INFO    ][26363] Completed state [/srv/glusterfs/glance] at time 07:35:38.762663 duration_in_ms=0.92
2017-11-10 07:35:38,763 [salt.state       ][INFO    ][26363] Running state [/srv/glusterfs/keystone-keys] at time 07:35:38.762832
2017-11-10 07:35:38,763 [salt.state       ][INFO    ][26363] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-10 07:35:38,763 [salt.state       ][INFO    ][26363] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-11-10 07:35:38,764 [salt.state       ][INFO    ][26363] Completed state [/srv/glusterfs/keystone-keys] at time 07:35:38.763563 duration_in_ms=0.73
2017-11-10 07:35:38,764 [salt.state       ][INFO    ][26363] Running state [/srv/glusterfs/nova_instances] at time 07:35:38.763728
2017-11-10 07:35:38,764 [salt.state       ][INFO    ][26363] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-10 07:35:38,764 [salt.state       ][INFO    ][26363] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-11-10 07:35:38,764 [salt.state       ][INFO    ][26363] Completed state [/srv/glusterfs/nova_instances] at time 07:35:38.764432 duration_in_ms=0.704
2017-11-10 07:35:38,765 [salt.state       ][INFO    ][26363] Running state [/srv/glusterfs/keystone-credential-keys] at time 07:35:38.764597
2017-11-10 07:35:38,765 [salt.state       ][INFO    ][26363] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-10 07:35:38,765 [salt.state       ][INFO    ][26363] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-11-10 07:35:38,765 [salt.state       ][INFO    ][26363] Completed state [/srv/glusterfs/keystone-credential-keys] at time 07:35:38.765338 duration_in_ms=0.742
2017-11-10 07:35:38,766 [salt.minion      ][INFO    ][26363] Returning information for job: 20171110073512185049
2017-11-10 07:35:41,639 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command test.ping with jid 20171110073541628457
2017-11-10 07:35:41,648 [salt.minion      ][INFO    ][31162] Starting a new job with PID 31162
2017-11-10 07:35:41,680 [salt.minion      ][INFO    ][31162] Returning information for job: 20171110073541628457
2017-11-10 07:35:41,815 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command state.sls with jid 20171110073541804025
2017-11-10 07:35:41,826 [salt.minion      ][INFO    ][31167] Starting a new job with PID 31167
2017-11-10 07:35:43,473 [salt.state       ][INFO    ][31167] Loading fresh modules for state activity
2017-11-10 07:35:43,494 [salt.fileclient  ][INFO    ][31167] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-10 07:35:43,527 [salt.fileclient  ][INFO    ][31167] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-10 07:35:43,591 [salt.fileclient  ][INFO    ][31167] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-10 07:35:43,608 [salt.fileclient  ][INFO    ][31167] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-10 07:35:43,872 [salt.state       ][INFO    ][31167] Running state [attr] at time 07:35:43.871583
2017-11-10 07:35:43,872 [salt.state       ][INFO    ][31167] Executing state pkg.installed for attr
2017-11-10 07:35:43,872 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-10 07:35:44,142 [salt.state       ][INFO    ][31167] Package attr is already installed
2017-11-10 07:35:44,142 [salt.state       ][INFO    ][31167] Completed state [attr] at time 07:35:44.141980 duration_in_ms=270.396
2017-11-10 07:35:44,142 [salt.state       ][INFO    ][31167] Running state [glusterfs-server] at time 07:35:44.142214
2017-11-10 07:35:44,142 [salt.state       ][INFO    ][31167] Executing state pkg.installed for glusterfs-server
2017-11-10 07:35:44,145 [salt.state       ][INFO    ][31167] Package glusterfs-server is already installed
2017-11-10 07:35:44,145 [salt.state       ][INFO    ][31167] Completed state [glusterfs-server] at time 07:35:44.145089 duration_in_ms=2.874
2017-11-10 07:35:44,145 [salt.state       ][INFO    ][31167] Running state [bc] at time 07:35:44.145260
2017-11-10 07:35:44,145 [salt.state       ][INFO    ][31167] Executing state pkg.installed for bc
2017-11-10 07:35:44,148 [salt.state       ][INFO    ][31167] Package bc is already installed
2017-11-10 07:35:44,148 [salt.state       ][INFO    ][31167] Completed state [bc] at time 07:35:44.148102 duration_in_ms=2.842
2017-11-10 07:35:44,149 [salt.state       ][INFO    ][31167] Running state [glusterfs-server] at time 07:35:44.148936
2017-11-10 07:35:44,149 [salt.state       ][INFO    ][31167] Executing state service.running for glusterfs-server
2017-11-10 07:35:44,150 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-10 07:35:44,159 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-11-10 07:35:44,211 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-10 07:35:44,224 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-10 07:35:44,230 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-10 07:35:44,237 [salt.state       ][INFO    ][31167] The service glusterfs-server is already running
2017-11-10 07:35:44,237 [salt.state       ][INFO    ][31167] Completed state [glusterfs-server] at time 07:35:44.236921 duration_in_ms=87.985
2017-11-10 07:35:44,238 [salt.state       ][INFO    ][31167] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 07:35:44.238460
2017-11-10 07:35:44,239 [salt.state       ][INFO    ][31167] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-10 07:35:44,248 [salt.state       ][INFO    ][31167] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-10 07:35:44,248 [salt.state       ][INFO    ][31167] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 07:35:44.247856 duration_in_ms=9.396
2017-11-10 07:35:44,248 [salt.state       ][INFO    ][31167] Running state [/srv/glusterfs/glance] at time 07:35:44.248030
2017-11-10 07:35:44,248 [salt.state       ][INFO    ][31167] Executing state file.directory for /srv/glusterfs/glance
2017-11-10 07:35:44,249 [salt.state       ][INFO    ][31167] Directory /srv/glusterfs/glance is in the correct state
2017-11-10 07:35:44,249 [salt.state       ][INFO    ][31167] Completed state [/srv/glusterfs/glance] at time 07:35:44.248762 duration_in_ms=0.733
2017-11-10 07:35:44,249 [salt.state       ][INFO    ][31167] Running state [/srv/glusterfs/keystone-keys] at time 07:35:44.248932
2017-11-10 07:35:44,249 [salt.state       ][INFO    ][31167] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-10 07:35:44,250 [salt.state       ][INFO    ][31167] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-10 07:35:44,250 [salt.state       ][INFO    ][31167] Completed state [/srv/glusterfs/keystone-keys] at time 07:35:44.249623 duration_in_ms=0.691
2017-11-10 07:35:44,250 [salt.state       ][INFO    ][31167] Running state [/srv/glusterfs/nova_instances] at time 07:35:44.249783
2017-11-10 07:35:44,250 [salt.state       ][INFO    ][31167] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-10 07:35:44,250 [salt.state       ][INFO    ][31167] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-10 07:35:44,251 [salt.state       ][INFO    ][31167] Completed state [/srv/glusterfs/nova_instances] at time 07:35:44.250480 duration_in_ms=0.696
2017-11-10 07:35:44,251 [salt.state       ][INFO    ][31167] Running state [/srv/glusterfs/keystone-credential-keys] at time 07:35:44.250647
2017-11-10 07:35:44,251 [salt.state       ][INFO    ][31167] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-10 07:35:44,251 [salt.state       ][INFO    ][31167] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-10 07:35:44,251 [salt.state       ][INFO    ][31167] Completed state [/srv/glusterfs/keystone-credential-keys] at time 07:35:44.251320 duration_in_ms=0.673
2017-11-10 07:35:44,306 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command ['git', '--version'] in directory '/root'
2017-11-10 07:35:44,387 [salt.state       ][INFO    ][31167] Running state [sleep 5] at time 07:35:44.386884
2017-11-10 07:35:44,387 [salt.state       ][INFO    ][31167] Executing state cmd.wait for sleep 5
2017-11-10 07:35:44,387 [salt.state       ][INFO    ][31167] No changes made for sleep 5
2017-11-10 07:35:44,387 [salt.state       ][INFO    ][31167] Completed state [sleep 5] at time 07:35:44.387451 duration_in_ms=0.569
2017-11-10 07:35:44,388 [salt.state       ][INFO    ][31167] Running state [10.167.4.141] at time 07:35:44.387815
2017-11-10 07:35:44,388 [salt.state       ][INFO    ][31167] Executing state glusterfs.peered for 10.167.4.141
2017-11-10 07:35:44,388 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:44,397 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:44,446 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:44,455 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:44,790 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:44,802 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:44,886 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:44,895 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:45,049 [salt.state       ][INFO    ][31167] {'new': {'10.167.4.141': []}, 'old': None}
2017-11-10 07:35:45,049 [salt.state       ][INFO    ][31167] Completed state [10.167.4.141] at time 07:35:45.049050 duration_in_ms=661.234
2017-11-10 07:35:45,051 [salt.state       ][INFO    ][31167] Running state [10.167.4.142] at time 07:35:45.051446
2017-11-10 07:35:45,052 [salt.state       ][INFO    ][31167] Executing state glusterfs.peered for 10.167.4.142
2017-11-10 07:35:45,052 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:45,058 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:45,111 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:45,117 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:45,409 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:45,416 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:45,478 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:45,488 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:45,544 [salt.state       ][INFO    ][31167] {'new': {'10.167.4.141': [], '10.167.4.142': []}, 'old': {'10.167.4.141': []}}
2017-11-10 07:35:45,544 [salt.state       ][INFO    ][31167] Completed state [10.167.4.142] at time 07:35:45.544426 duration_in_ms=492.979
2017-11-10 07:35:45,547 [salt.state       ][INFO    ][31167] Running state [10.167.4.143] at time 07:35:45.546888
2017-11-10 07:35:45,547 [salt.state       ][INFO    ][31167] Executing state glusterfs.peered for 10.167.4.143
2017-11-10 07:35:45,548 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:45,555 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:45,675 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:45,685 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:45,740 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:45,748 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:45,801 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:45,808 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:45,866 [salt.state       ][INFO    ][31167] Peering with localhost is not needed
2017-11-10 07:35:45,867 [salt.state       ][INFO    ][31167] Completed state [10.167.4.143] at time 07:35:45.866455 duration_in_ms=319.566
2017-11-10 07:35:45,867 [salt.state       ][INFO    ][31167] Running state [glance] at time 07:35:45.867368
2017-11-10 07:35:45,868 [salt.state       ][INFO    ][31167] Executing state glusterfs.created for glance
2017-11-10 07:35:45,868 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:45,876 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:45,925 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:45,932 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:46,607 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:46,620 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:47,839 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:47,852 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:47,908 [salt.state       ][INFO    ][31167] {'new': ['glance'], 'old': []}
2017-11-10 07:35:47,908 [salt.state       ][INFO    ][31167] Completed state [glance] at time 07:35:47.908413 duration_in_ms=2041.044
2017-11-10 07:35:47,912 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 07:35:47.911471
2017-11-10 07:35:47,912 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-10 07:35:47,912 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-10 07:35:47,966 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-11-10 07:35:48,725 [salt.state       ][INFO    ][31167] {'pid': 31389, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:35:48,726 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 07:35:48.725787 duration_in_ms=814.315
2017-11-10 07:35:48,726 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 07:35:48.726418
2017-11-10 07:35:48,727 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-10 07:35:48,727 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-10 07:35:48,785 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'glance' 'network.remote-dio' 'True'' in directory '/root'
2017-11-10 07:35:49,392 [salt.state       ][INFO    ][31167] {'pid': 31407, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:35:49,392 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 07:35:49.392373 duration_in_ms=665.954
2017-11-10 07:35:49,393 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 07:35:49.392893
2017-11-10 07:35:49,393 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-10 07:35:49,394 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-10 07:35:49,453 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'glance' 'nfs.disable' 'True'' in directory '/root'
2017-11-10 07:35:51,860 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command saltutil.find_job with jid 20171110073551847458
2017-11-10 07:35:51,871 [salt.minion      ][INFO    ][31436] Starting a new job with PID 31436
2017-11-10 07:35:51,886 [salt.minion      ][INFO    ][31436] Returning information for job: 20171110073551847458
2017-11-10 07:35:52,174 [salt.state       ][INFO    ][31167] {'pid': 31424, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:35:52,174 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 07:35:52.174045 duration_in_ms=2781.15
2017-11-10 07:35:52,175 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 07:35:52.174626
2017-11-10 07:35:52,175 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-10 07:35:52,176 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-10 07:35:52,233 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-11-10 07:35:52,886 [salt.state       ][INFO    ][31167] {'pid': 31451, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:35:52,886 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 07:35:52.886341 duration_in_ms=711.714
2017-11-10 07:35:52,887 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 07:35:52.886917
2017-11-10 07:35:52,887 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-10 07:35:52,888 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-10 07:35:52,947 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'glance' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-11-10 07:35:53,769 [salt.state       ][INFO    ][31167] {'pid': 31468, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:35:53,769 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 07:35:53.769341 duration_in_ms=882.423
2017-11-10 07:35:53,770 [salt.state       ][INFO    ][31167] Running state [glance] at time 07:35:53.770390
2017-11-10 07:35:53,771 [salt.state       ][INFO    ][31167] Executing state glusterfs.started for glance
2017-11-10 07:35:53,771 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:53,783 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:53,841 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:53,851 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:53,908 [salt.state       ][INFO    ][31167] Volume glance is already started
2017-11-10 07:35:53,909 [salt.state       ][INFO    ][31167] Completed state [glance] at time 07:35:53.908650 duration_in_ms=138.259
2017-11-10 07:35:53,909 [salt.state       ][INFO    ][31167] Running state [keystone-keys] at time 07:35:53.909465
2017-11-10 07:35:53,910 [salt.state       ][INFO    ][31167] Executing state glusterfs.created for keystone-keys
2017-11-10 07:35:53,910 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:53,920 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:53,971 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:53,981 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:54,667 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:54,679 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:57,439 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:35:57,453 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:35:57,510 [salt.state       ][INFO    ][31167] {'new': ['glance', 'keystone-keys'], 'old': ['glance']}
2017-11-10 07:35:57,510 [salt.state       ][INFO    ][31167] Completed state [keystone-keys] at time 07:35:57.509968 duration_in_ms=3600.503
2017-11-10 07:35:57,514 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 07:35:57.513940
2017-11-10 07:35:57,514 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-10 07:35:57,515 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-10 07:35:57,573 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'keystone-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-11-10 07:35:58,228 [salt.state       ][INFO    ][31167] {'pid': 31578, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:35:58,229 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 07:35:58.228602 duration_in_ms=714.661
2017-11-10 07:35:58,229 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 07:35:58.229156
2017-11-10 07:35:58,229 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-10 07:35:58,230 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-10 07:35:58,288 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-11-10 07:35:58,905 [salt.state       ][INFO    ][31167] {'pid': 31596, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:35:58,905 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 07:35:58.905389 duration_in_ms=676.232
2017-11-10 07:35:58,906 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 07:35:58.906086
2017-11-10 07:35:58,907 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-10 07:35:58,907 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-10 07:35:58,966 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-11-10 07:35:59,567 [salt.state       ][INFO    ][31167] {'pid': 31615, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:35:59,567 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 07:35:59.567320 duration_in_ms=661.234
2017-11-10 07:35:59,568 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 07:35:59.568070
2017-11-10 07:35:59,569 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-10 07:35:59,569 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-10 07:35:59,627 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'keystone-keys' 'nfs.disable' 'True'' in directory '/root'
2017-11-10 07:36:01,1000 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command saltutil.find_job with jid 20171110073601988730
2017-11-10 07:36:02,015 [salt.minion      ][INFO    ][31645] Starting a new job with PID 31645
2017-11-10 07:36:02,027 [salt.minion      ][INFO    ][31645] Returning information for job: 20171110073601988730
2017-11-10 07:36:02,318 [salt.state       ][INFO    ][31167] {'pid': 31632, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:36:02,319 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 07:36:02.318466 duration_in_ms=2750.396
2017-11-10 07:36:02,319 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 07:36:02.319352
2017-11-10 07:36:02,320 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-10 07:36:02,321 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-10 07:36:02,379 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-11-10 07:36:03,026 [salt.state       ][INFO    ][31167] {'pid': 31657, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:36:03,027 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 07:36:03.026713 duration_in_ms=707.36
2017-11-10 07:36:03,028 [salt.state       ][INFO    ][31167] Running state [keystone-keys] at time 07:36:03.027772
2017-11-10 07:36:03,028 [salt.state       ][INFO    ][31167] Executing state glusterfs.started for keystone-keys
2017-11-10 07:36:03,029 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:36:03,040 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:36:03,099 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:36:03,108 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:36:03,167 [salt.state       ][INFO    ][31167] Volume keystone-keys is already started
2017-11-10 07:36:03,167 [salt.state       ][INFO    ][31167] Completed state [keystone-keys] at time 07:36:03.167127 duration_in_ms=139.353
2017-11-10 07:36:03,168 [salt.state       ][INFO    ][31167] Running state [nova_instances] at time 07:36:03.168030
2017-11-10 07:36:03,168 [salt.state       ][INFO    ][31167] Executing state glusterfs.created for nova_instances
2017-11-10 07:36:03,169 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:36:03,178 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:36:03,230 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:36:03,239 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:36:03,888 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:36:03,899 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:36:06,739 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:36:06,752 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:36:06,809 [salt.state       ][INFO    ][31167] {'new': ['glance', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys']}
2017-11-10 07:36:06,810 [salt.state       ][INFO    ][31167] Completed state [nova_instances] at time 07:36:06.809496 duration_in_ms=3641.464
2017-11-10 07:36:06,814 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 07:36:06.813509
2017-11-10 07:36:06,814 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-10 07:36:06,814 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-10 07:36:06,868 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-11-10 07:36:07,483 [salt.state       ][INFO    ][31167] {'pid': 31770, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:36:07,483 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 07:36:07.483181 duration_in_ms=669.671
2017-11-10 07:36:07,484 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 07:36:07.483809
2017-11-10 07:36:07,484 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-11-10 07:36:07,485 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-11-10 07:36:07,542 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'' in directory '/root'
2017-11-10 07:36:08,425 [salt.state       ][INFO    ][31167] {'pid': 31788, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:36:08,426 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 07:36:08.425715 duration_in_ms=941.905
2017-11-10 07:36:08,426 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 07:36:08.426252
2017-11-10 07:36:08,427 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-11-10 07:36:08,428 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-11-10 07:36:08,486 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'nova_instances' 'network.remote-dio' 'On'' in directory '/root'
2017-11-10 07:36:09,108 [salt.state       ][INFO    ][31167] {'pid': 31805, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:36:09,109 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 07:36:09.108634 duration_in_ms=682.381
2017-11-10 07:36:09,110 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 07:36:09.109571
2017-11-10 07:36:09,110 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-11-10 07:36:09,111 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-11-10 07:36:09,169 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'nova_instances' 'nfs.disable' 'On'' in directory '/root'
2017-11-10 07:36:11,777 [salt.state       ][INFO    ][31167] {'pid': 31823, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:36:11,777 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 07:36:11.776791 duration_in_ms=2667.22
2017-11-10 07:36:11,777 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 07:36:11.777359
2017-11-10 07:36:11,778 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-10 07:36:11,779 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-10 07:36:11,838 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-11-10 07:36:12,144 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command saltutil.find_job with jid 20171110073612131573
2017-11-10 07:36:12,154 [salt.minion      ][INFO    ][31852] Starting a new job with PID 31852
2017-11-10 07:36:12,166 [salt.minion      ][INFO    ][31852] Returning information for job: 20171110073612131573
2017-11-10 07:36:12,447 [salt.state       ][INFO    ][31167] {'pid': 31842, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:36:12,447 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 07:36:12.446800 duration_in_ms=669.44
2017-11-10 07:36:12,448 [salt.state       ][INFO    ][31167] Running state [nova_instances] at time 07:36:12.447850
2017-11-10 07:36:12,448 [salt.state       ][INFO    ][31167] Executing state glusterfs.started for nova_instances
2017-11-10 07:36:12,449 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:36:12,461 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:36:12,519 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:36:12,529 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:36:12,591 [salt.state       ][INFO    ][31167] Volume nova_instances is already started
2017-11-10 07:36:12,591 [salt.state       ][INFO    ][31167] Completed state [nova_instances] at time 07:36:12.591104 duration_in_ms=143.254
2017-11-10 07:36:12,592 [salt.state       ][INFO    ][31167] Running state [keystone-credential-keys] at time 07:36:12.592057
2017-11-10 07:36:12,592 [salt.state       ][INFO    ][31167] Executing state glusterfs.created for keystone-credential-keys
2017-11-10 07:36:12,593 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:36:12,602 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:36:12,653 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:36:12,662 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:36:13,412 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:36:13,425 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:36:16,337 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:36:16,350 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:36:16,409 [salt.state       ][INFO    ][31167] {'new': ['glance', 'keystone-credential-keys', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys', 'nova_instances']}
2017-11-10 07:36:16,409 [salt.state       ][INFO    ][31167] Completed state [keystone-credential-keys] at time 07:36:16.409383 duration_in_ms=3817.325
2017-11-10 07:36:16,413 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 07:36:16.412675
2017-11-10 07:36:16,413 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-10 07:36:16,413 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-10 07:36:16,468 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-11-10 07:36:17,100 [salt.state       ][INFO    ][31167] {'pid': 31964, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:36:17,100 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 07:36:17.099949 duration_in_ms=687.274
2017-11-10 07:36:17,101 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 07:36:17.100502
2017-11-10 07:36:17,101 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-10 07:36:17,101 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-10 07:36:17,159 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-11-10 07:36:17,787 [salt.state       ][INFO    ][31167] {'pid': 31981, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:36:17,787 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 07:36:17.787048 duration_in_ms=686.545
2017-11-10 07:36:17,788 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 07:36:17.787627
2017-11-10 07:36:17,788 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-10 07:36:17,789 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-10 07:36:17,848 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'' in directory '/root'
2017-11-10 07:36:20,507 [salt.state       ][INFO    ][31167] {'pid': 32000, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:36:20,507 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 07:36:20.507085 duration_in_ms=2719.457
2017-11-10 07:36:20,508 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 07:36:20.507690
2017-11-10 07:36:20,508 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-10 07:36:20,509 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-10 07:36:20,567 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-11-10 07:36:21,159 [salt.state       ][INFO    ][31167] {'pid': 32019, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:36:21,159 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 07:36:21.158875 duration_in_ms=651.184
2017-11-10 07:36:21,159 [salt.state       ][INFO    ][31167] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 07:36:21.159460
2017-11-10 07:36:21,160 [salt.state       ][INFO    ][31167] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-10 07:36:21,160 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-10 07:36:21,219 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-11-10 07:36:21,852 [salt.state       ][INFO    ][31167] {'pid': 32038, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-10 07:36:21,852 [salt.state       ][INFO    ][31167] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 07:36:21.852082 duration_in_ms=692.621
2017-11-10 07:36:21,853 [salt.state       ][INFO    ][31167] Running state [keystone-credential-keys] at time 07:36:21.853243
2017-11-10 07:36:21,854 [salt.state       ][INFO    ][31167] Executing state glusterfs.started for keystone-credential-keys
2017-11-10 07:36:21,854 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:36:21,867 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:36:21,926 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --version' in directory '/root'
2017-11-10 07:36:21,936 [salt.loaded.int.module.cmdmod][INFO    ][31167] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-10 07:36:21,997 [salt.state       ][INFO    ][31167] Volume keystone-credential-keys is already started
2017-11-10 07:36:21,997 [salt.state       ][INFO    ][31167] Completed state [keystone-credential-keys] at time 07:36:21.996996 duration_in_ms=143.752
2017-11-10 07:36:21,999 [salt.minion      ][INFO    ][31167] Returning information for job: 20171110073541804025
2017-11-10 07:36:32,126 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command test.ping with jid 20171110073632118392
2017-11-10 07:36:32,139 [salt.minion      ][INFO    ][32068] Starting a new job with PID 32068
2017-11-10 07:36:32,167 [salt.minion      ][INFO    ][32068] Returning information for job: 20171110073632118392
2017-11-10 07:36:32,317 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command cmd.run with jid 20171110073632285191
2017-11-10 07:36:32,328 [salt.minion      ][INFO    ][32073] Starting a new job with PID 32073
2017-11-10 07:36:32,331 [salt.loaded.int.module.cmdmod][INFO    ][32073] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-10 07:36:32,451 [salt.minion      ][INFO    ][32073] Returning information for job: 20171110073632285191
2017-11-10 08:16:37,115 [salt.utils.schedule][INFO    ][24091] Running scheduled job: __mine_interval
2017-11-10 09:06:55,225 [salt.minion      ][INFO    ][24091] User sudo_ubuntu Executing command cp.push_dir with jid 20171110090655213823
2017-11-10 09:06:55,242 [salt.minion      ][INFO    ][990] Starting a new job with PID 990
