2017-09-20 10:53:34,346 [salt.cli.daemons ][WARNING ][2486] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-20 11:26:25,503 [salt.state       ][ERROR   ][3830] No contents found in top file
2017-09-20 11:26:42,857 [salt.utils.parsers][WARNING ][2486] Minion received a SIGTERM. Exiting.
2017-09-20 11:28:55,018 [salt.cli.daemons ][WARNING ][3152] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-20 11:32:04,346 [py.warnings      ][WARNING ][16281] /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-09-20 11:37:39,406 [salt.loaded.int.module.cmdmod][ERROR   ][16281] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-20 11:37:39,406 [salt.loaded.int.module.cmdmod][ERROR   ][16281] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-20 11:37:46,561 [salt.loaded.int.module.cmdmod][ERROR   ][16281] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-20 11:37:46,562 [salt.loaded.int.module.cmdmod][ERROR   ][16281] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-20 11:37:55,350 [salt.loaded.int.module.cmdmod][ERROR   ][16281] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-20 11:37:55,351 [salt.loaded.int.module.cmdmod][ERROR   ][16281] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-20 11:38:05,139 [salt.loaded.int.module.cmdmod][ERROR   ][16281] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-20 11:38:05,139 [salt.loaded.int.module.cmdmod][ERROR   ][16281] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-20 11:38:15,231 [salt.loaded.int.module.cmdmod][ERROR   ][16281] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-20 11:38:15,231 [salt.loaded.int.module.cmdmod][ERROR   ][16281] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-20 11:38:22,112 [salt.loaded.int.module.cmdmod][ERROR   ][16281] 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-09-20 11:38:24,208 [salt.loaded.int.module.cmdmod][INFO    ][24060] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-09-20 11:38:24,214 [salt.loaded.int.module.cmdmod][INFO    ][24060] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-09-20 11:38:24,221 [salt.loaded.int.module.cmdmod][INFO    ][24060] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-09-20 11:38:24,228 [salt.utils.parsers][WARNING ][3152] Minion received a SIGTERM. Exiting.
2017-09-20 11:38:24,485 [salt.cli.daemons ][INFO    ][24138] Setting up the Salt Minion "kvm02.baremetal-mcp-ocata-odl-ha.local"
2017-09-20 11:38:24,982 [salt.minion      ][INFO    ][24138] Creating minion process manager
2017-09-20 11:38:24,983 [salt.cli.daemons ][WARNING ][24138] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-20 11:38:24,983 [salt.cli.daemons ][INFO    ][24138] The Salt Minion is starting up
2017-09-20 11:38:24,983 [salt.minion      ][INFO    ][24138] Minion is starting as user 'root'
2017-09-20 11:38:24,983 [salt.utils.event ][INFO    ][24138] Starting pull socket on /var/run/salt/minion/minion_event_0607a05f39_pull.ipc
2017-09-20 11:38:25,861 [salt.loaded.int.module.cmdmod][INFO    ][24138] Executing command ['date', '+%z'] in directory '/root'
2017-09-20 11:38:25,869 [salt.utils.schedule][INFO    ][24138] Updating job settings for scheduled job: __mine_interval
2017-09-20 11:38:25,871 [salt.minion      ][INFO    ][24138] Added mine.update to scheduler
2017-09-20 11:38:26,104 [salt.minion      ][INFO    ][24138] Minion is ready to receive requests!
2017-09-20 11:38:27,207 [salt.utils.schedule][INFO    ][24138] Running scheduled job: __mine_interval
2017-09-20 11:38:34,633 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command saltutil.find_job with jid 20170920113834618045
2017-09-20 11:38:34,648 [salt.minion      ][INFO    ][24250] Starting a new job with PID 24250
2017-09-20 11:38:34,655 [salt.minion      ][INFO    ][24250] Returning information for job: 20170920113834618045
2017-09-20 11:38:44,749 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command saltutil.find_job with jid 20170920113844723113
2017-09-20 11:38:44,792 [salt.minion      ][INFO    ][24257] Starting a new job with PID 24257
2017-09-20 11:38:44,800 [salt.minion      ][INFO    ][24257] Returning information for job: 20170920113844723113
2017-09-20 11:38:55,738 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command state.sls with jid 20170920113855726405
2017-09-20 11:38:55,751 [salt.minion      ][INFO    ][24262] Starting a new job with PID 24262
2017-09-20 11:38:57,358 [salt.state       ][INFO    ][24262] Loading fresh modules for state activity
2017-09-20 11:38:57,382 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-09-20 11:38:57,401 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-09-20 11:38:57,425 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-20 11:38:57,522 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-09-20 11:38:57,537 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-09-20 11:38:57,561 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-20 11:38:57,646 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-09-20 11:38:57,663 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-20 11:38:57,786 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-09-20 11:38:57,846 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-09-20 11:38:57,866 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-20 11:38:57,880 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-09-20 11:38:57,887 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-09-20 11:38:57,980 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-09-20 11:38:57,993 [py.warnings      ][WARNING ][24262] /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-09-20 11:38:58,065 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command 'salt-minion --version' in directory '/root'
2017-09-20 11:38:58,322 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-09-20 11:38:58,344 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-09-20 11:38:58,356 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-09-20 11:38:58,367 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-09-20 11:38:58,410 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-09-20 11:38:58,446 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-09-20 11:38:58,544 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-09-20 11:38:58,564 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-20 11:38:58,615 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-09-20 11:38:58,632 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-20 11:38:58,672 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-09-20 11:38:58,689 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-09-20 11:38:58,702 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-20 11:38:58,716 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-09-20 11:38:58,722 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-09-20 11:38:58,746 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-09-20 11:38:58,760 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command 'salt-minion --version' in directory '/root'
2017-09-20 11:38:59,015 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-09-20 11:38:59,033 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-09-20 11:38:59,045 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-09-20 11:38:59,057 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-09-20 11:38:59,102 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-09-20 11:38:59,141 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-09-20 11:38:59,217 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-09-20 11:38:59,273 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-20 11:38:59,560 [salt.state       ][INFO    ][24262] Running state [salt-minion] at time 11:38:59.560285
2017-09-20 11:38:59,561 [salt.state       ][INFO    ][24262] Executing state pkg.installed for salt-minion
2017-09-20 11:38:59,561 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-20 11:38:59,862 [salt.state       ][INFO    ][24262] Package salt-minion is already installed
2017-09-20 11:38:59,862 [salt.state       ][INFO    ][24262] Completed state [salt-minion] at time 11:38:59.862453 duration_in_ms=302.168
2017-09-20 11:38:59,863 [salt.state       ][INFO    ][24262] Running state [python-yaml] at time 11:38:59.862652
2017-09-20 11:38:59,863 [salt.state       ][INFO    ][24262] Executing state pkg.installed for python-yaml
2017-09-20 11:38:59,865 [salt.state       ][INFO    ][24262] Package python-yaml is already installed
2017-09-20 11:38:59,866 [salt.state       ][INFO    ][24262] Completed state [python-yaml] at time 11:38:59.865569 duration_in_ms=2.918
2017-09-20 11:38:59,866 [salt.state       ][INFO    ][24262] Running state [python-psutil] at time 11:38:59.865718
2017-09-20 11:38:59,866 [salt.state       ][INFO    ][24262] Executing state pkg.installed for python-psutil
2017-09-20 11:38:59,868 [salt.state       ][INFO    ][24262] Package python-psutil is already installed
2017-09-20 11:38:59,869 [salt.state       ][INFO    ][24262] Completed state [python-psutil] at time 11:38:59.868490 duration_in_ms=2.771
2017-09-20 11:38:59,869 [salt.state       ][INFO    ][24262] Running state [python-m2crypto] at time 11:38:59.868626
2017-09-20 11:38:59,869 [salt.state       ][INFO    ][24262] Executing state pkg.installed for python-m2crypto
2017-09-20 11:38:59,871 [salt.state       ][INFO    ][24262] Package python-m2crypto is already installed
2017-09-20 11:38:59,871 [salt.state       ][INFO    ][24262] Completed state [python-m2crypto] at time 11:38:59.871415 duration_in_ms=2.789
2017-09-20 11:38:59,873 [salt.state       ][INFO    ][24262] Running state [/etc/salt/minion.d/minion.conf] at time 11:38:59.872940
2017-09-20 11:38:59,873 [salt.state       ][INFO    ][24262] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-09-20 11:38:59,937 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-09-20 11:39:00,029 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-20 11:39:00,119 [salt.fileclient  ][INFO    ][24262] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-20 11:39:00,151 [salt.state       ][INFO    ][24262] File /etc/salt/minion.d/minion.conf is in the correct state
2017-09-20 11:39:00,152 [salt.state       ][INFO    ][24262] Completed state [/etc/salt/minion.d/minion.conf] at time 11:39:00.151494 duration_in_ms=278.554
2017-09-20 11:39:00,152 [salt.state       ][INFO    ][24262] Running state [/etc/salt/minion.d/_virt.conf] at time 11:39:00.151803
2017-09-20 11:39:00,152 [salt.state       ][INFO    ][24262] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-09-20 11:39:00,292 [salt.state       ][INFO    ][24262] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-09-20 11:39:00,293 [salt.state       ][INFO    ][24262] Completed state [/etc/salt/minion.d/_virt.conf] at time 11:39:00.292560 duration_in_ms=140.756
2017-09-20 11:39:00,294 [salt.state       ][INFO    ][24262] Running state [salt-minion] at time 11:39:00.293754
2017-09-20 11:39:00,294 [salt.state       ][INFO    ][24262] Executing state service.running for salt-minion
2017-09-20 11:39:00,294 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-09-20 11:39:00,310 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-09-20 11:39:00,402 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-09-20 11:39:00,410 [salt.state       ][INFO    ][24262] The service salt-minion is already running
2017-09-20 11:39:00,410 [salt.state       ][INFO    ][24262] Completed state [salt-minion] at time 11:39:00.409855 duration_in_ms=116.101
2017-09-20 11:39:00,411 [salt.state       ][INFO    ][24262] Running state [/etc/salt/grains.d] at time 11:39:00.411018
2017-09-20 11:39:00,411 [salt.state       ][INFO    ][24262] Executing state file.directory for /etc/salt/grains.d
2017-09-20 11:39:00,412 [salt.state       ][INFO    ][24262] Directory /etc/salt/grains.d is in the correct state
2017-09-20 11:39:00,412 [salt.state       ][INFO    ][24262] Completed state [/etc/salt/grains.d] at time 11:39:00.411889 duration_in_ms=0.872
2017-09-20 11:39:00,412 [salt.state       ][INFO    ][24262] Running state [/etc/salt/grains] at time 11:39:00.412328
2017-09-20 11:39:00,413 [salt.state       ][INFO    ][24262] Executing state file.managed for /etc/salt/grains
2017-09-20 11:39:00,413 [salt.state       ][INFO    ][24262] File /etc/salt/grains exists with proper permissions. No changes made.
2017-09-20 11:39:00,413 [salt.state       ][INFO    ][24262] Completed state [/etc/salt/grains] at time 11:39:00.412986 duration_in_ms=0.657
2017-09-20 11:39:00,413 [salt.state       ][INFO    ][24262] Running state [/etc/salt/grains.d/placeholder] at time 11:39:00.413248
2017-09-20 11:39:00,413 [salt.state       ][INFO    ][24262] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-09-20 11:39:00,414 [salt.state       ][INFO    ][24262] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-09-20 11:39:00,414 [salt.state       ][INFO    ][24262] Completed state [/etc/salt/grains.d/placeholder] at time 11:39:00.413809 duration_in_ms=0.561
2017-09-20 11:39:00,414 [salt.state       ][INFO    ][24262] Running state [/etc/salt/grains.d/sphinx] at time 11:39:00.414071
2017-09-20 11:39:00,414 [salt.state       ][INFO    ][24262] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-09-20 11:39:00,537 [salt.state       ][INFO    ][24262] File /etc/salt/grains.d/sphinx is in the correct state
2017-09-20 11:39:00,537 [salt.state       ][INFO    ][24262] Completed state [/etc/salt/grains.d/sphinx] at time 11:39:00.537020 duration_in_ms=122.948
2017-09-20 11:39:00,538 [salt.state       ][INFO    ][24262] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 11:39:00.537502
2017-09-20 11:39:00,538 [salt.state       ][INFO    ][24262] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-09-20 11:39:00,538 [salt.state       ][INFO    ][24262] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-09-20 11:39:00,538 [salt.state       ][INFO    ][24262] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 11:39:00.538067 duration_in_ms=0.564
2017-09-20 11:39:00,538 [salt.state       ][INFO    ][24262] Running state [/etc/salt/grains.d/dns_records] at time 11:39:00.538377
2017-09-20 11:39:00,539 [salt.state       ][INFO    ][24262] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-09-20 11:39:00,733 [salt.state       ][INFO    ][24262] File /etc/salt/grains.d/dns_records is in the correct state
2017-09-20 11:39:00,733 [salt.state       ][INFO    ][24262] Completed state [/etc/salt/grains.d/dns_records] at time 11:39:00.733192 duration_in_ms=194.815
2017-09-20 11:39:00,734 [salt.state       ][INFO    ][24262] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 11:39:00.733800
2017-09-20 11:39:00,734 [salt.state       ][INFO    ][24262] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-09-20 11:39:00,734 [salt.state       ][INFO    ][24262] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-09-20 11:39:00,734 [salt.state       ][INFO    ][24262] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 11:39:00.734313 duration_in_ms=0.513
2017-09-20 11:39:00,735 [salt.state       ][INFO    ][24262] Running state [/etc/salt/grains.d/salt] at time 11:39:00.734573
2017-09-20 11:39:00,735 [salt.state       ][INFO    ][24262] Executing state file.managed for /etc/salt/grains.d/salt
2017-09-20 11:39:00,806 [salt.state       ][INFO    ][24262] File /etc/salt/grains.d/salt is in the correct state
2017-09-20 11:39:00,807 [salt.state       ][INFO    ][24262] Completed state [/etc/salt/grains.d/salt] at time 11:39:00.806746 duration_in_ms=72.172
2017-09-20 11:39:00,807 [salt.state       ][INFO    ][24262] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 11:39:00.807339
2017-09-20 11:39:00,808 [salt.state       ][INFO    ][24262] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-09-20 11:39:00,808 [salt.state       ][INFO    ][24262] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-09-20 11:39:00,808 [salt.state       ][INFO    ][24262] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 11:39:00.807878 duration_in_ms=0.539
2017-09-20 11:39:00,809 [salt.state       ][INFO    ][24262] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 11:39:00.809095
2017-09-20 11:39:00,809 [salt.state       ][INFO    ][24262] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-09-20 11:39:00,809 [salt.state       ][INFO    ][24262] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-09-20 11:39:00,810 [salt.state       ][INFO    ][24262] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 11:39:00.809564 duration_in_ms=0.468
2017-09-20 11:39:00,810 [salt.state       ][INFO    ][24262] Running state [mine.update] at time 11:39:00.809958
2017-09-20 11:39:00,810 [salt.state       ][INFO    ][24262] Executing state module.wait for mine.update
2017-09-20 11:39:00,810 [salt.state       ][INFO    ][24262] No changes made for mine.update
2017-09-20 11:39:00,810 [salt.state       ][INFO    ][24262] Completed state [mine.update] at time 11:39:00.810372 duration_in_ms=0.415
2017-09-20 11:39:00,811 [salt.state       ][INFO    ][24262] Running state [ca-certificates] at time 11:39:00.810505
2017-09-20 11:39:00,811 [salt.state       ][INFO    ][24262] Executing state pkg.installed for ca-certificates
2017-09-20 11:39:00,814 [salt.state       ][INFO    ][24262] Package ca-certificates is already installed
2017-09-20 11:39:00,814 [salt.state       ][INFO    ][24262] Completed state [ca-certificates] at time 11:39:00.813860 duration_in_ms=3.355
2017-09-20 11:39:00,814 [salt.state       ][INFO    ][24262] Running state [update-ca-certificates] at time 11:39:00.814252
2017-09-20 11:39:00,814 [salt.state       ][INFO    ][24262] Executing state cmd.wait for update-ca-certificates
2017-09-20 11:39:00,815 [salt.state       ][INFO    ][24262] No changes made for update-ca-certificates
2017-09-20 11:39:00,815 [salt.state       ][INFO    ][24262] Completed state [update-ca-certificates] at time 11:39:00.814674 duration_in_ms=0.423
2017-09-20 11:39:00,815 [salt.state       ][INFO    ][24262] Running state [libvirt-dev] at time 11:39:00.814805
2017-09-20 11:39:00,815 [salt.state       ][INFO    ][24262] Executing state pkg.installed for libvirt-dev
2017-09-20 11:39:00,817 [salt.state       ][INFO    ][24262] Package libvirt-dev is already installed
2017-09-20 11:39:00,818 [salt.state       ][INFO    ][24262] Completed state [libvirt-dev] at time 11:39:00.817592 duration_in_ms=2.788
2017-09-20 11:39:00,818 [salt.state       ][INFO    ][24262] Running state [pkg-config] at time 11:39:00.817726
2017-09-20 11:39:00,818 [salt.state       ][INFO    ][24262] Executing state pkg.installed for pkg-config
2017-09-20 11:39:00,820 [salt.state       ][INFO    ][24262] Package pkg-config is already installed
2017-09-20 11:39:00,821 [salt.state       ][INFO    ][24262] Completed state [pkg-config] at time 11:39:00.820485 duration_in_ms=2.76
2017-09-20 11:39:00,821 [salt.state       ][INFO    ][24262] Running state [virtng.init] at time 11:39:00.820620
2017-09-20 11:39:00,821 [salt.state       ][INFO    ][24262] Executing state module.run for virtng.init
2017-09-20 11:39:00,858 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command ['git', '--version'] in directory '/root'
2017-09-20 11:39:01,016 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command 'virsh list --all --name| grep -E "^mdb02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:01,032 [salt.state       ][INFO    ][24262] unless execution succeeded
2017-09-20 11:39:01,033 [salt.state       ][INFO    ][24262] Completed state [virtng.init] at time 11:39:01.032704 duration_in_ms=212.083
2017-09-20 11:39:01,033 [salt.state       ][INFO    ][24262] Running state [virt.set_autostart] at time 11:39:01.032953
2017-09-20 11:39:01,033 [salt.state       ][INFO    ][24262] Executing state module.run for virt.set_autostart
2017-09-20 11:39:01,035 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command 'virsh list --autostart --name| grep -E "^mdb02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:01,051 [salt.state       ][INFO    ][24262] unless execution succeeded
2017-09-20 11:39:01,051 [salt.state       ][INFO    ][24262] Completed state [virt.set_autostart] at time 11:39:01.050736 duration_in_ms=17.782
2017-09-20 11:39:01,051 [salt.state       ][INFO    ][24262] Running state [virtng.init] at time 11:39:01.050944
2017-09-20 11:39:01,051 [salt.state       ][INFO    ][24262] Executing state module.run for virtng.init
2017-09-20 11:39:01,053 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command 'virsh list --all --name| grep -E "^dbs02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:01,242 [salt.state       ][INFO    ][24262] unless execution succeeded
2017-09-20 11:39:01,243 [salt.state       ][INFO    ][24262] Completed state [virtng.init] at time 11:39:01.242704 duration_in_ms=191.759
2017-09-20 11:39:01,243 [salt.state       ][INFO    ][24262] Running state [virt.set_autostart] at time 11:39:01.242946
2017-09-20 11:39:01,243 [salt.state       ][INFO    ][24262] Executing state module.run for virt.set_autostart
2017-09-20 11:39:01,245 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command 'virsh list --autostart --name| grep -E "^dbs02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:01,261 [salt.state       ][INFO    ][24262] unless execution succeeded
2017-09-20 11:39:01,261 [salt.state       ][INFO    ][24262] Completed state [virt.set_autostart] at time 11:39:01.261163 duration_in_ms=18.217
2017-09-20 11:39:01,261 [salt.state       ][INFO    ][24262] Running state [virtng.init] at time 11:39:01.261383
2017-09-20 11:39:01,262 [salt.state       ][INFO    ][24262] Executing state module.run for virtng.init
2017-09-20 11:39:01,263 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command 'virsh list --all --name| grep -E "^prx02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:01,279 [salt.state       ][INFO    ][24262] unless execution succeeded
2017-09-20 11:39:01,279 [salt.state       ][INFO    ][24262] Completed state [virtng.init] at time 11:39:01.278707 duration_in_ms=17.323
2017-09-20 11:39:01,279 [salt.state       ][INFO    ][24262] Running state [virt.set_autostart] at time 11:39:01.278925
2017-09-20 11:39:01,279 [salt.state       ][INFO    ][24262] Executing state module.run for virt.set_autostart
2017-09-20 11:39:01,281 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command 'virsh list --autostart --name| grep -E "^prx02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:01,298 [salt.state       ][INFO    ][24262] unless execution succeeded
2017-09-20 11:39:01,298 [salt.state       ][INFO    ][24262] Completed state [virt.set_autostart] at time 11:39:01.298018 duration_in_ms=19.092
2017-09-20 11:39:01,298 [salt.state       ][INFO    ][24262] Running state [virtng.init] at time 11:39:01.298227
2017-09-20 11:39:01,298 [salt.state       ][INFO    ][24262] Executing state module.run for virtng.init
2017-09-20 11:39:01,300 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command 'virsh list --all --name| grep -E "^ctl02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:01,315 [salt.state       ][INFO    ][24262] unless execution succeeded
2017-09-20 11:39:01,315 [salt.state       ][INFO    ][24262] Completed state [virtng.init] at time 11:39:01.315119 duration_in_ms=16.892
2017-09-20 11:39:01,315 [salt.state       ][INFO    ][24262] Running state [virt.set_autostart] at time 11:39:01.315338
2017-09-20 11:39:01,316 [salt.state       ][INFO    ][24262] Executing state module.run for virt.set_autostart
2017-09-20 11:39:01,317 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command 'virsh list --autostart --name| grep -E "^ctl02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:01,332 [salt.state       ][INFO    ][24262] unless execution succeeded
2017-09-20 11:39:01,332 [salt.state       ][INFO    ][24262] Completed state [virt.set_autostart] at time 11:39:01.332035 duration_in_ms=16.696
2017-09-20 11:39:01,332 [salt.state       ][INFO    ][24262] Running state [virtng.init] at time 11:39:01.332240
2017-09-20 11:39:01,332 [salt.state       ][INFO    ][24262] Executing state module.run for virtng.init
2017-09-20 11:39:01,334 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command 'virsh list --all --name| grep -E "^msg02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:01,349 [salt.state       ][INFO    ][24262] unless execution succeeded
2017-09-20 11:39:01,349 [salt.state       ][INFO    ][24262] Completed state [virtng.init] at time 11:39:01.348876 duration_in_ms=16.634
2017-09-20 11:39:01,349 [salt.state       ][INFO    ][24262] Running state [virt.set_autostart] at time 11:39:01.349081
2017-09-20 11:39:01,349 [salt.state       ][INFO    ][24262] Executing state module.run for virt.set_autostart
2017-09-20 11:39:01,351 [salt.loaded.int.module.cmdmod][INFO    ][24262] Executing command 'virsh list --autostart --name| grep -E "^msg02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:01,365 [salt.state       ][INFO    ][24262] unless execution succeeded
2017-09-20 11:39:01,365 [salt.state       ][INFO    ][24262] Completed state [virt.set_autostart] at time 11:39:01.365162 duration_in_ms=16.08
2017-09-20 11:39:01,367 [salt.minion      ][INFO    ][24262] Returning information for job: 20170920113855726405
2017-09-20 11:40:08,824 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command saltutil.sync_all with jid 20170920114008809665
2017-09-20 11:40:08,840 [salt.minion      ][INFO    ][24375] Starting a new job with PID 24375
2017-09-20 11:40:12,042 [salt.state       ][INFO    ][24375] Loading fresh modules for state activity
2017-09-20 11:40:12,351 [salt.state       ][ERROR   ][24375] No contents found in top file
2017-09-20 11:40:12,352 [salt.utils.extmods][INFO    ][24375] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-09-20 11:40:12,355 [salt.utils.extmods][INFO    ][24375] Syncing beacons for environment 'base'
2017-09-20 11:40:12,355 [salt.utils.extmods][INFO    ][24375] Loading cache from salt://_beacons, for base)
2017-09-20 11:40:12,355 [salt.fileclient  ][INFO    ][24375] Caching directory '_beacons/' for environment 'base'
2017-09-20 11:40:12,379 [salt.utils.extmods][INFO    ][24375] Syncing modules for environment 'base'
2017-09-20 11:40:12,379 [salt.utils.extmods][INFO    ][24375] Loading cache from salt://_modules, for base)
2017-09-20 11:40:12,379 [salt.fileclient  ][INFO    ][24375] Caching directory '_modules/' for environment 'base'
2017-09-20 11:40:12,419 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-09-20 11:40:12,427 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-09-20 11:40:12,442 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-09-20 11:40:12,504 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-09-20 11:40:12,618 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-09-20 11:40:12,730 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-09-20 11:40:13,490 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-09-20 11:40:13,535 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-09-20 11:40:13,634 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-09-20 11:40:13,709 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-09-20 11:40:13,776 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-09-20 11:40:13,816 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-09-20 11:40:13,844 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-09-20 11:40:13,901 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-09-20 11:40:13,978 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-09-20 11:40:13,998 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-09-20 11:40:14,018 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-09-20 11:40:14,044 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-09-20 11:40:14,058 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-09-20 11:40:14,071 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-09-20 11:40:14,097 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-09-20 11:40:14,117 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-09-20 11:40:14,129 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-09-20 11:40:14,152 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-09-20 11:40:14,182 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-09-20 11:40:14,214 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-09-20 11:40:14,242 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-09-20 11:40:14,286 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-09-20 11:40:14,308 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-09-20 11:40:14,369 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-09-20 11:40:14,378 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-09-20 11:40:14,386 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-09-20 11:40:14,388 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-09-20 11:40:14,389 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-09-20 11:40:14,389 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-09-20 11:40:14,389 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-09-20 11:40:14,390 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-09-20 11:40:14,390 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-09-20 11:40:14,396 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-09-20 11:40:14,396 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-09-20 11:40:14,396 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-09-20 11:40:14,397 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-09-20 11:40:14,397 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-09-20 11:40:14,398 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-09-20 11:40:14,398 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-09-20 11:40:14,398 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-09-20 11:40:14,399 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-09-20 11:40:14,399 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-09-20 11:40:14,399 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-09-20 11:40:14,400 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-09-20 11:40:14,400 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-09-20 11:40:14,401 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-09-20 11:40:14,401 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-09-20 11:40:14,401 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-09-20 11:40:14,409 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-09-20 11:40:14,410 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-09-20 11:40:14,410 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-09-20 11:40:14,410 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-09-20 11:40:14,411 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-09-20 11:40:14,411 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-09-20 11:40:14,412 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-09-20 11:40:14,412 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-09-20 11:40:14,412 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-09-20 11:40:14,413 [salt.utils.extmods][INFO    ][24375] 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-09-20 11:40:14,418 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-09-20 11:40:14,418 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-09-20 11:40:14,424 [salt.utils.extmods][INFO    ][24375] Syncing states for environment 'base'
2017-09-20 11:40:14,424 [salt.utils.extmods][INFO    ][24375] Loading cache from salt://_states, for base)
2017-09-20 11:40:14,424 [salt.fileclient  ][INFO    ][24375] Caching directory '_states/' for environment 'base'
2017-09-20 11:40:14,497 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-09-20 11:40:14,580 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-09-20 11:40:14,598 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-09-20 11:40:14,611 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-09-20 11:40:14,618 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-09-20 11:40:14,627 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-09-20 11:40:14,788 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-09-20 11:40:14,814 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-09-20 11:40:14,916 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-09-20 11:40:14,932 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-09-20 11:40:14,963 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-09-20 11:40:15,054 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-09-20 11:40:15,113 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-09-20 11:40:15,210 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-09-20 11:40:15,221 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-09-20 11:40:15,256 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-09-20 11:40:15,270 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-09-20 11:40:15,325 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-09-20 11:40:15,349 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-09-20 11:40:15,361 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-09-20 11:40:15,378 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-09-20 11:40:15,389 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-09-20 11:40:15,401 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-09-20 11:40:15,436 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-09-20 11:40:15,453 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-09-20 11:40:15,461 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-09-20 11:40:15,491 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-09-20 11:40:15,509 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-09-20 11:40:15,527 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-09-20 11:40:15,529 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-09-20 11:40:15,529 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-09-20 11:40:15,530 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-09-20 11:40:15,530 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-09-20 11:40:15,530 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-09-20 11:40:15,530 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-09-20 11:40:15,531 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-09-20 11:40:15,531 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-09-20 11:40:15,531 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-09-20 11:40:15,531 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-09-20 11:40:15,531 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-09-20 11:40:15,532 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-09-20 11:40:15,532 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-09-20 11:40:15,532 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-09-20 11:40:15,532 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-09-20 11:40:15,533 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-09-20 11:40:15,533 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-09-20 11:40:15,533 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-09-20 11:40:15,533 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-09-20 11:40:15,534 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-09-20 11:40:15,534 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-09-20 11:40:15,534 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-09-20 11:40:15,534 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-09-20 11:40:15,535 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-09-20 11:40:15,535 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-09-20 11:40:15,535 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-09-20 11:40:15,536 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-09-20 11:40:15,536 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-09-20 11:40:15,536 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-09-20 11:40:15,536 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-09-20 11:40:15,538 [salt.utils.extmods][INFO    ][24375] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-09-20 11:40:15,541 [salt.utils.extmods][INFO    ][24375] Syncing sdb for environment 'base'
2017-09-20 11:40:15,541 [salt.utils.extmods][INFO    ][24375] Loading cache from salt://_sdb, for base)
2017-09-20 11:40:15,542 [salt.fileclient  ][INFO    ][24375] Caching directory '_sdb/' for environment 'base'
2017-09-20 11:40:15,640 [salt.utils.extmods][INFO    ][24375] Syncing grains for environment 'base'
2017-09-20 11:40:15,640 [salt.utils.extmods][INFO    ][24375] Loading cache from salt://_grains, for base)
2017-09-20 11:40:15,640 [salt.fileclient  ][INFO    ][24375] Caching directory '_grains/' for environment 'base'
2017-09-20 11:40:16,473 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-09-20 11:40:16,849 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-09-20 11:40:16,863 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-09-20 11:40:16,872 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-09-20 11:40:17,058 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-09-20 11:40:17,473 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-09-20 11:40:17,480 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-09-20 11:40:17,499 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-09-20 11:40:17,506 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-09-20 11:40:17,532 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-09-20 11:40:17,798 [salt.fileclient  ][INFO    ][24375] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-09-20 11:40:17,801 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-09-20 11:40:17,801 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-09-20 11:40:17,802 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-09-20 11:40:17,802 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-09-20 11:40:17,802 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-09-20 11:40:17,803 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-09-20 11:40:17,803 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-09-20 11:40:17,804 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-09-20 11:40:17,804 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-09-20 11:40:17,804 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-09-20 11:40:17,805 [salt.utils.extmods][INFO    ][24375] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-09-20 11:40:17,807 [salt.utils.extmods][INFO    ][24375] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-09-20 11:40:17,810 [salt.utils.extmods][INFO    ][24375] Syncing renderers for environment 'base'
2017-09-20 11:40:17,810 [salt.utils.extmods][INFO    ][24375] Loading cache from salt://_renderers, for base)
2017-09-20 11:40:17,810 [salt.fileclient  ][INFO    ][24375] Caching directory '_renderers/' for environment 'base'
2017-09-20 11:40:17,833 [salt.utils.extmods][INFO    ][24375] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-09-20 11:40:17,835 [salt.utils.extmods][INFO    ][24375] Syncing returners for environment 'base'
2017-09-20 11:40:17,835 [salt.utils.extmods][INFO    ][24375] Loading cache from salt://_returners, for base)
2017-09-20 11:40:17,835 [salt.fileclient  ][INFO    ][24375] Caching directory '_returners/' for environment 'base'
2017-09-20 11:40:17,852 [salt.utils.extmods][INFO    ][24375] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-09-20 11:40:17,854 [salt.utils.extmods][INFO    ][24375] Syncing output for environment 'base'
2017-09-20 11:40:17,854 [salt.utils.extmods][INFO    ][24375] Loading cache from salt://_output, for base)
2017-09-20 11:40:17,854 [salt.fileclient  ][INFO    ][24375] Caching directory '_output/' for environment 'base'
2017-09-20 11:40:17,874 [salt.utils.extmods][INFO    ][24375] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-09-20 11:40:17,876 [salt.utils.extmods][INFO    ][24375] Syncing utils for environment 'base'
2017-09-20 11:40:17,877 [salt.utils.extmods][INFO    ][24375] Loading cache from salt://_utils, for base)
2017-09-20 11:40:17,877 [salt.fileclient  ][INFO    ][24375] Caching directory '_utils/' for environment 'base'
2017-09-20 11:40:17,893 [salt.utils.extmods][INFO    ][24375] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-09-20 11:40:17,896 [salt.utils.extmods][INFO    ][24375] Syncing log_handlers for environment 'base'
2017-09-20 11:40:17,896 [salt.utils.extmods][INFO    ][24375] Loading cache from salt://_log_handlers, for base)
2017-09-20 11:40:17,896 [salt.fileclient  ][INFO    ][24375] Caching directory '_log_handlers/' for environment 'base'
2017-09-20 11:40:17,912 [salt.utils.extmods][INFO    ][24375] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-09-20 11:40:17,915 [salt.utils.extmods][INFO    ][24375] Syncing proxy for environment 'base'
2017-09-20 11:40:17,915 [salt.utils.extmods][INFO    ][24375] Loading cache from salt://_proxy, for base)
2017-09-20 11:40:17,915 [salt.fileclient  ][INFO    ][24375] Caching directory '_proxy/' for environment 'base'
2017-09-20 11:40:17,931 [salt.utils.extmods][INFO    ][24375] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-09-20 11:40:17,933 [salt.utils.extmods][INFO    ][24375] Syncing engines for environment 'base'
2017-09-20 11:40:17,934 [salt.utils.extmods][INFO    ][24375] Loading cache from salt://_engines, for base)
2017-09-20 11:40:17,934 [salt.fileclient  ][INFO    ][24375] Caching directory '_engines/' for environment 'base'
2017-09-20 11:40:17,961 [salt.minion      ][INFO    ][24375] Returning information for job: 20170920114008809665
2017-09-20 12:08:50,020 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command test.ping with jid 20170920120850007187
2017-09-20 12:08:50,043 [salt.minion      ][INFO    ][24994] Starting a new job with PID 24994
2017-09-20 12:08:50,070 [salt.minion      ][INFO    ][24994] Returning information for job: 20170920120850007187
2017-09-20 12:11:32,575 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command state.sls with jid 20170920121132561512
2017-09-20 12:11:32,591 [salt.minion      ][INFO    ][25047] Starting a new job with PID 25047
2017-09-20 12:11:33,996 [salt.state       ][INFO    ][25047] Loading fresh modules for state activity
2017-09-20 12:11:34,062 [salt.fileclient  ][INFO    ][25047] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-09-20 12:11:34,075 [salt.fileclient  ][INFO    ][25047] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-09-20 12:11:34,095 [salt.fileclient  ][INFO    ][25047] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-20 12:11:34,386 [salt.state       ][INFO    ][25047] Running state [lsof] at time 12:11:34.385898
2017-09-20 12:11:34,386 [salt.state       ][INFO    ][25047] Executing state pkg.installed for lsof
2017-09-20 12:11:34,386 [salt.loaded.int.module.cmdmod][INFO    ][25047] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-20 12:11:34,632 [salt.state       ][INFO    ][25047] Package lsof is already installed
2017-09-20 12:11:34,633 [salt.state       ][INFO    ][25047] Completed state [lsof] at time 12:11:34.632678 duration_in_ms=246.781
2017-09-20 12:11:34,633 [salt.state       ][INFO    ][25047] Running state [keepalived] at time 12:11:34.632884
2017-09-20 12:11:34,633 [salt.state       ][INFO    ][25047] Executing state pkg.installed for keepalived
2017-09-20 12:11:34,647 [salt.loaded.int.module.cmdmod][INFO    ][25047] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-20 12:11:37,920 [salt.loaded.int.module.cmdmod][INFO    ][25047] 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-09-20 12:11:42,619 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command saltutil.find_job with jid 20170920121142604564
2017-09-20 12:11:42,632 [salt.minion      ][INFO    ][26341] Starting a new job with PID 26341
2017-09-20 12:11:42,644 [salt.minion      ][INFO    ][26341] Returning information for job: 20170920121142604564
2017-09-20 12:11:45,600 [salt.loaded.int.module.cmdmod][INFO    ][25047] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-20 12:11:45,623 [salt.state       ][INFO    ][25047] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4'
'keepalived' changed from 'absent' to '1:1.2.19-1ubuntu0.2'
'ipvsadm' changed from 'absent' to '1:1.28-3'
'libsensors4' changed from 'absent' to '1:3.4.0-2'

2017-09-20 12:11:45,631 [salt.state       ][INFO    ][25047] Loading fresh modules for state activity
2017-09-20 12:11:45,643 [salt.state       ][INFO    ][25047] Completed state [keepalived] at time 12:11:45.642955 duration_in_ms=11010.07
2017-09-20 12:11:45,645 [salt.state       ][INFO    ][25047] Running state [/etc/keepalived/keepalived.conf] at time 12:11:45.644618
2017-09-20 12:11:45,645 [salt.state       ][INFO    ][25047] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-09-20 12:11:45,666 [salt.fileclient  ][INFO    ][25047] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-09-20 12:11:45,694 [salt.fileclient  ][INFO    ][25047] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-20 12:11:45,719 [salt.state       ][INFO    ][25047] File changed:
New file
2017-09-20 12:11:45,720 [salt.state       ][INFO    ][25047] Completed state [/etc/keepalived/keepalived.conf] at time 12:11:45.719624 duration_in_ms=75.006
2017-09-20 12:11:45,778 [salt.state       ][INFO    ][25047] Running state [keepalived] at time 12:11:45.778402
2017-09-20 12:11:45,779 [salt.state       ][INFO    ][25047] Executing state service.running for keepalived
2017-09-20 12:11:45,780 [salt.loaded.int.module.cmdmod][INFO    ][25047] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-09-20 12:11:45,789 [salt.loaded.int.module.cmdmod][INFO    ][25047] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-09-20 12:11:45,795 [salt.loaded.int.module.cmdmod][INFO    ][25047] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-20 12:11:45,801 [salt.loaded.int.module.cmdmod][INFO    ][25047] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-20 12:11:45,807 [salt.loaded.int.module.cmdmod][INFO    ][25047] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-09-20 12:11:45,860 [salt.loaded.int.module.cmdmod][INFO    ][25047] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-09-20 12:11:45,868 [salt.loaded.int.module.cmdmod][INFO    ][25047] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-20 12:11:45,874 [salt.loaded.int.module.cmdmod][INFO    ][25047] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-20 12:11:45,879 [salt.state       ][INFO    ][25047] {'keepalived': True}
2017-09-20 12:11:45,880 [salt.state       ][INFO    ][25047] Completed state [keepalived] at time 12:11:45.879518 duration_in_ms=101.117
2017-09-20 12:11:45,880 [salt.minion      ][INFO    ][25047] Returning information for job: 20170920121132561512
2017-09-20 12:13:31,097 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command pillar.get with jid 20170920121331083934
2017-09-20 12:13:31,115 [salt.minion      ][INFO    ][26681] Starting a new job with PID 26681
2017-09-20 12:13:31,119 [salt.minion      ][INFO    ][26681] Returning information for job: 20170920121331083934
2017-09-20 12:19:53,681 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command state.sls with jid 20170920121953673551
2017-09-20 12:19:53,697 [salt.minion      ][INFO    ][26806] Starting a new job with PID 26806
2017-09-20 12:19:54,982 [salt.state       ][INFO    ][26806] Loading fresh modules for state activity
2017-09-20 12:19:54,1000 [salt.fileclient  ][INFO    ][26806] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-09-20 12:19:55,015 [salt.fileclient  ][INFO    ][26806] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-20 12:19:55,282 [salt.state       ][INFO    ][26806] Running state [attr] at time 12:19:55.281703
2017-09-20 12:19:55,282 [salt.state       ][INFO    ][26806] Executing state pkg.installed for attr
2017-09-20 12:19:55,282 [salt.loaded.int.module.cmdmod][INFO    ][26806] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-20 12:19:55,575 [salt.loaded.int.module.cmdmod][INFO    ][26806] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-20 12:19:57,604 [salt.loaded.int.module.cmdmod][INFO    ][26806] 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-09-20 12:20:00,041 [salt.loaded.int.module.cmdmod][INFO    ][26806] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-20 12:20:00,064 [salt.state       ][INFO    ][26806] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-09-20 12:20:00,072 [salt.state       ][INFO    ][26806] Loading fresh modules for state activity
2017-09-20 12:20:00,084 [salt.state       ][INFO    ][26806] Completed state [attr] at time 12:20:00.084054 duration_in_ms=4802.35
2017-09-20 12:20:00,089 [salt.state       ][INFO    ][26806] Running state [glusterfs-server] at time 12:20:00.088643
2017-09-20 12:20:00,089 [salt.state       ][INFO    ][26806] Executing state pkg.installed for glusterfs-server
2017-09-20 12:20:00,270 [salt.loaded.int.module.cmdmod][INFO    ][26806] 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-09-20 12:20:03,772 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command saltutil.find_job with jid 20170920122003757704
2017-09-20 12:20:03,785 [salt.minion      ][INFO    ][27849] Starting a new job with PID 27849
2017-09-20 12:20:03,800 [salt.minion      ][INFO    ][27849] Returning information for job: 20170920122003757704
2017-09-20 12:20:13,992 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command saltutil.find_job with jid 20170920122013978480
2017-09-20 12:20:14,005 [salt.minion      ][INFO    ][30690] Starting a new job with PID 30690
2017-09-20 12:20:14,016 [salt.minion      ][INFO    ][30690] Returning information for job: 20170920122013978480
2017-09-20 12:20:17,651 [salt.loaded.int.module.cmdmod][INFO    ][26806] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-20 12:20:17,676 [salt.state       ][INFO    ][26806] 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-09-20 12:20:17,683 [salt.state       ][INFO    ][26806] Loading fresh modules for state activity
2017-09-20 12:20:17,696 [salt.state       ][INFO    ][26806] Completed state [glusterfs-server] at time 12:20:17.695525 duration_in_ms=17606.882
2017-09-20 12:20:17,700 [salt.state       ][INFO    ][26806] Running state [bc] at time 12:20:17.700340
2017-09-20 12:20:17,701 [salt.state       ][INFO    ][26806] Executing state pkg.installed for bc
2017-09-20 12:20:17,947 [salt.loaded.int.module.cmdmod][INFO    ][26806] 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-09-20 12:20:20,750 [salt.loaded.int.module.cmdmod][INFO    ][26806] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-20 12:20:20,775 [salt.state       ][INFO    ][26806] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-09-20 12:20:20,782 [salt.state       ][INFO    ][26806] Loading fresh modules for state activity
2017-09-20 12:20:20,794 [salt.state       ][INFO    ][26806] Completed state [bc] at time 12:20:20.794287 duration_in_ms=3093.947
2017-09-20 12:20:20,858 [salt.state       ][INFO    ][26806] Running state [glusterfs-server] at time 12:20:20.857635
2017-09-20 12:20:20,858 [salt.state       ][INFO    ][26806] Executing state service.running for glusterfs-server
2017-09-20 12:20:20,859 [salt.loaded.int.module.cmdmod][INFO    ][26806] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-20 12:20:20,868 [salt.loaded.int.module.cmdmod][INFO    ][26806] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-09-20 12:20:20,875 [salt.loaded.int.module.cmdmod][INFO    ][26806] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-09-20 12:20:20,885 [salt.state       ][INFO    ][26806] The service glusterfs-server is already running
2017-09-20 12:20:20,885 [salt.state       ][INFO    ][26806] Completed state [glusterfs-server] at time 12:20:20.885034 duration_in_ms=27.4
2017-09-20 12:20:20,886 [salt.state       ][INFO    ][26806] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 12:20:20.886454
2017-09-20 12:20:20,887 [salt.state       ][INFO    ][26806] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-09-20 12:20:20,897 [salt.state       ][INFO    ][26806] File changed:
New file
2017-09-20 12:20:20,897 [salt.state       ][INFO    ][26806] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 12:20:20.896717 duration_in_ms=10.263
2017-09-20 12:20:20,897 [salt.state       ][INFO    ][26806] Running state [/srv/glusterfs/glance] at time 12:20:20.896959
2017-09-20 12:20:20,897 [salt.state       ][INFO    ][26806] Executing state file.directory for /srv/glusterfs/glance
2017-09-20 12:20:20,898 [salt.state       ][INFO    ][26806] {'/srv/glusterfs/glance': 'New Dir'}
2017-09-20 12:20:20,898 [salt.state       ][INFO    ][26806] Completed state [/srv/glusterfs/glance] at time 12:20:20.897800 duration_in_ms=0.841
2017-09-20 12:20:20,898 [salt.state       ][INFO    ][26806] Running state [/srv/glusterfs/keystone-keys] at time 12:20:20.897946
2017-09-20 12:20:20,898 [salt.state       ][INFO    ][26806] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-09-20 12:20:20,898 [salt.state       ][INFO    ][26806] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-09-20 12:20:20,899 [salt.state       ][INFO    ][26806] Completed state [/srv/glusterfs/keystone-keys] at time 12:20:20.898569 duration_in_ms=0.623
2017-09-20 12:20:20,899 [salt.state       ][INFO    ][26806] Running state [/srv/glusterfs/nova_instances] at time 12:20:20.898711
2017-09-20 12:20:20,899 [salt.state       ][INFO    ][26806] Executing state file.directory for /srv/glusterfs/nova_instances
2017-09-20 12:20:20,899 [salt.state       ][INFO    ][26806] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-09-20 12:20:20,899 [salt.state       ][INFO    ][26806] Completed state [/srv/glusterfs/nova_instances] at time 12:20:20.899354 duration_in_ms=0.642
2017-09-20 12:20:20,900 [salt.state       ][INFO    ][26806] Running state [/srv/glusterfs/keystone-credential-keys] at time 12:20:20.899500
2017-09-20 12:20:20,900 [salt.state       ][INFO    ][26806] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-09-20 12:20:20,900 [salt.state       ][INFO    ][26806] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-09-20 12:20:20,900 [salt.state       ][INFO    ][26806] Completed state [/srv/glusterfs/keystone-credential-keys] at time 12:20:20.900130 duration_in_ms=0.63
2017-09-20 12:20:20,901 [salt.minion      ][INFO    ][26806] Returning information for job: 20170920121953673551
2017-09-20 12:20:30,230 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command test.ping with jid 20170920122030221285
2017-09-20 12:20:30,244 [salt.minion      ][INFO    ][31539] Starting a new job with PID 31539
2017-09-20 12:20:30,272 [salt.minion      ][INFO    ][31539] Returning information for job: 20170920122030221285
2017-09-20 12:20:30,440 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command state.sls with jid 20170920122030427058
2017-09-20 12:20:30,454 [salt.minion      ][INFO    ][31544] Starting a new job with PID 31544
2017-09-20 12:20:32,007 [salt.state       ][INFO    ][31544] Loading fresh modules for state activity
2017-09-20 12:20:32,028 [salt.fileclient  ][INFO    ][31544] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-09-20 12:20:32,060 [salt.fileclient  ][INFO    ][31544] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-20 12:20:32,121 [salt.fileclient  ][INFO    ][31544] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-09-20 12:20:32,140 [salt.fileclient  ][INFO    ][31544] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-20 12:20:32,394 [salt.state       ][INFO    ][31544] Running state [attr] at time 12:20:32.394034
2017-09-20 12:20:32,394 [salt.state       ][INFO    ][31544] Executing state pkg.installed for attr
2017-09-20 12:20:32,395 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-20 12:20:32,646 [salt.state       ][INFO    ][31544] Package attr is already installed
2017-09-20 12:20:32,646 [salt.state       ][INFO    ][31544] Completed state [attr] at time 12:20:32.646139 duration_in_ms=252.106
2017-09-20 12:20:32,646 [salt.state       ][INFO    ][31544] Running state [glusterfs-server] at time 12:20:32.646337
2017-09-20 12:20:32,647 [salt.state       ][INFO    ][31544] Executing state pkg.installed for glusterfs-server
2017-09-20 12:20:32,649 [salt.state       ][INFO    ][31544] Package glusterfs-server is already installed
2017-09-20 12:20:32,649 [salt.state       ][INFO    ][31544] Completed state [glusterfs-server] at time 12:20:32.649170 duration_in_ms=2.832
2017-09-20 12:20:32,649 [salt.state       ][INFO    ][31544] Running state [bc] at time 12:20:32.649304
2017-09-20 12:20:32,649 [salt.state       ][INFO    ][31544] Executing state pkg.installed for bc
2017-09-20 12:20:32,652 [salt.state       ][INFO    ][31544] Package bc is already installed
2017-09-20 12:20:32,652 [salt.state       ][INFO    ][31544] Completed state [bc] at time 12:20:32.652065 duration_in_ms=2.761
2017-09-20 12:20:32,653 [salt.state       ][INFO    ][31544] Running state [glusterfs-server] at time 12:20:32.652829
2017-09-20 12:20:32,653 [salt.state       ][INFO    ][31544] Executing state service.running for glusterfs-server
2017-09-20 12:20:32,653 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-20 12:20:32,663 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-09-20 12:20:32,706 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-20 12:20:32,720 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-09-20 12:20:32,727 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-09-20 12:20:32,734 [salt.state       ][INFO    ][31544] The service glusterfs-server is already running
2017-09-20 12:20:32,734 [salt.state       ][INFO    ][31544] Completed state [glusterfs-server] at time 12:20:32.734349 duration_in_ms=81.519
2017-09-20 12:20:32,736 [salt.state       ][INFO    ][31544] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 12:20:32.735684
2017-09-20 12:20:32,736 [salt.state       ][INFO    ][31544] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-09-20 12:20:32,741 [salt.state       ][INFO    ][31544] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-09-20 12:20:32,741 [salt.state       ][INFO    ][31544] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 12:20:32.741229 duration_in_ms=5.545
2017-09-20 12:20:32,741 [salt.state       ][INFO    ][31544] Running state [/srv/glusterfs/glance] at time 12:20:32.741416
2017-09-20 12:20:32,742 [salt.state       ][INFO    ][31544] Executing state file.directory for /srv/glusterfs/glance
2017-09-20 12:20:32,742 [salt.state       ][INFO    ][31544] Directory /srv/glusterfs/glance is in the correct state
2017-09-20 12:20:32,742 [salt.state       ][INFO    ][31544] Completed state [/srv/glusterfs/glance] at time 12:20:32.742057 duration_in_ms=0.641
2017-09-20 12:20:32,742 [salt.state       ][INFO    ][31544] Running state [/srv/glusterfs/keystone-keys] at time 12:20:32.742209
2017-09-20 12:20:32,742 [salt.state       ][INFO    ][31544] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-09-20 12:20:32,743 [salt.state       ][INFO    ][31544] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-09-20 12:20:32,743 [salt.state       ][INFO    ][31544] Completed state [/srv/glusterfs/keystone-keys] at time 12:20:32.742782 duration_in_ms=0.574
2017-09-20 12:20:32,743 [salt.state       ][INFO    ][31544] Running state [/srv/glusterfs/nova_instances] at time 12:20:32.742934
2017-09-20 12:20:32,743 [salt.state       ][INFO    ][31544] Executing state file.directory for /srv/glusterfs/nova_instances
2017-09-20 12:20:32,743 [salt.state       ][INFO    ][31544] Directory /srv/glusterfs/nova_instances is in the correct state
2017-09-20 12:20:32,744 [salt.state       ][INFO    ][31544] Completed state [/srv/glusterfs/nova_instances] at time 12:20:32.743536 duration_in_ms=0.602
2017-09-20 12:20:32,744 [salt.state       ][INFO    ][31544] Running state [/srv/glusterfs/keystone-credential-keys] at time 12:20:32.743690
2017-09-20 12:20:32,744 [salt.state       ][INFO    ][31544] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-09-20 12:20:32,744 [salt.state       ][INFO    ][31544] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-09-20 12:20:32,744 [salt.state       ][INFO    ][31544] Completed state [/srv/glusterfs/keystone-credential-keys] at time 12:20:32.744279 duration_in_ms=0.589
2017-09-20 12:20:32,792 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command ['git', '--version'] in directory '/root'
2017-09-20 12:20:32,845 [salt.state       ][INFO    ][31544] Running state [sleep 5] at time 12:20:32.844702
2017-09-20 12:20:32,845 [salt.state       ][INFO    ][31544] Executing state cmd.wait for sleep 5
2017-09-20 12:20:32,845 [salt.state       ][INFO    ][31544] No changes made for sleep 5
2017-09-20 12:20:32,845 [salt.state       ][INFO    ][31544] Completed state [sleep 5] at time 12:20:32.845362 duration_in_ms=0.66
2017-09-20 12:20:32,846 [salt.state       ][INFO    ][31544] Running state [10.167.4.141] at time 12:20:32.845753
2017-09-20 12:20:32,846 [salt.state       ][INFO    ][31544] Executing state glusterfs.peered for 10.167.4.141
2017-09-20 12:20:32,846 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:32,855 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:32,902 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:32,912 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:33,271 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:33,281 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:33,342 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:33,351 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:33,405 [salt.state       ][INFO    ][31544] {'new': {'10.167.4.141': []}, 'old': None}
2017-09-20 12:20:33,405 [salt.state       ][INFO    ][31544] Completed state [10.167.4.141] at time 12:20:33.405344 duration_in_ms=559.59
2017-09-20 12:20:33,408 [salt.state       ][INFO    ][31544] Running state [10.167.4.142] at time 12:20:33.407762
2017-09-20 12:20:33,408 [salt.state       ][INFO    ][31544] Executing state glusterfs.peered for 10.167.4.142
2017-09-20 12:20:33,409 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:33,418 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:33,581 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:33,592 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:33,646 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:33,714 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:33,765 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:33,775 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:33,826 [salt.state       ][INFO    ][31544] Peering with localhost is not needed
2017-09-20 12:20:33,826 [salt.state       ][INFO    ][31544] Completed state [10.167.4.142] at time 12:20:33.826044 duration_in_ms=418.281
2017-09-20 12:20:33,828 [salt.state       ][INFO    ][31544] Running state [10.167.4.143] at time 12:20:33.828291
2017-09-20 12:20:33,829 [salt.state       ][INFO    ][31544] Executing state glusterfs.peered for 10.167.4.143
2017-09-20 12:20:33,829 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:33,838 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:33,887 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:33,896 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:34,166 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:34,179 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:34,239 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:34,249 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:34,310 [salt.state       ][INFO    ][31544] {'new': {'10.167.4.141': [], '10.167.4.143': []}, 'old': {'10.167.4.141': []}}
2017-09-20 12:20:34,310 [salt.state       ][INFO    ][31544] Completed state [10.167.4.143] at time 12:20:34.310293 duration_in_ms=482.002
2017-09-20 12:20:34,311 [salt.state       ][INFO    ][31544] Running state [glance] at time 12:20:34.311106
2017-09-20 12:20:34,311 [salt.state       ][INFO    ][31544] Executing state glusterfs.created for glance
2017-09-20 12:20:34,312 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:34,322 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:34,457 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:34,468 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:35,272 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:35,284 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:36,422 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:36,433 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:36,488 [salt.state       ][INFO    ][31544] {'new': ['glance'], 'old': []}
2017-09-20 12:20:36,488 [salt.state       ][INFO    ][31544] Completed state [glance] at time 12:20:36.488083 duration_in_ms=2176.976
2017-09-20 12:20:36,491 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 12:20:36.490884
2017-09-20 12:20:36,491 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-09-20 12:20:36,492 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-20 12:20:36,544 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-09-20 12:20:37,385 [salt.state       ][INFO    ][31544] {'pid': 31769, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:20:37,385 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 12:20:37.385353 duration_in_ms=894.469
2017-09-20 12:20:37,386 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 12:20:37.385897
2017-09-20 12:20:37,386 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-09-20 12:20:37,387 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-20 12:20:37,445 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'glance' 'network.remote-dio' 'True'' in directory '/root'
2017-09-20 12:20:38,147 [salt.state       ][INFO    ][31544] {'pid': 31787, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:20:38,147 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 12:20:38.147072 duration_in_ms=761.174
2017-09-20 12:20:38,148 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 12:20:38.147610
2017-09-20 12:20:38,148 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-09-20 12:20:38,148 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-09-20 12:20:38,205 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'glance' 'nfs.disable' 'True'' in directory '/root'
2017-09-20 12:20:40,481 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command saltutil.find_job with jid 20170920122040468007
2017-09-20 12:20:40,495 [salt.minion      ][INFO    ][31815] Starting a new job with PID 31815
2017-09-20 12:20:40,506 [salt.minion      ][INFO    ][31815] Returning information for job: 20170920122040468007
2017-09-20 12:20:40,862 [salt.state       ][INFO    ][31544] {'pid': 31804, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:20:40,862 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 12:20:40.862216 duration_in_ms=2714.605
2017-09-20 12:20:40,863 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 12:20:40.862910
2017-09-20 12:20:40,863 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-09-20 12:20:40,864 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-20 12:20:40,917 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-09-20 12:20:41,559 [salt.state       ][INFO    ][31544] {'pid': 31830, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:20:41,559 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 12:20:41.559222 duration_in_ms=696.31
2017-09-20 12:20:41,560 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 12:20:41.559782
2017-09-20 12:20:41,560 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-09-20 12:20:41,561 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-20 12:20:41,618 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'glance' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-09-20 12:20:42,261 [salt.state       ][INFO    ][31544] {'pid': 31847, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:20:42,261 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 12:20:42.261343 duration_in_ms=701.56
2017-09-20 12:20:42,262 [salt.state       ][INFO    ][31544] Running state [glance] at time 12:20:42.262351
2017-09-20 12:20:42,263 [salt.state       ][INFO    ][31544] Executing state glusterfs.started for glance
2017-09-20 12:20:42,263 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:42,274 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:42,328 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:42,338 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:42,396 [salt.state       ][INFO    ][31544] Volume glance is already started
2017-09-20 12:20:42,397 [salt.state       ][INFO    ][31544] Completed state [glance] at time 12:20:42.396500 duration_in_ms=134.149
2017-09-20 12:20:42,397 [salt.state       ][INFO    ][31544] Running state [keystone-keys] at time 12:20:42.397349
2017-09-20 12:20:42,398 [salt.state       ][INFO    ][31544] Executing state glusterfs.created for keystone-keys
2017-09-20 12:20:42,398 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:42,408 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:42,458 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:42,468 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:43,188 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:43,200 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:46,023 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:46,040 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:46,096 [salt.state       ][INFO    ][31544] {'new': ['glance', 'keystone-keys'], 'old': ['glance']}
2017-09-20 12:20:46,096 [salt.state       ][INFO    ][31544] Completed state [keystone-keys] at time 12:20:46.096203 duration_in_ms=3698.854
2017-09-20 12:20:46,099 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 12:20:46.099452
2017-09-20 12:20:46,100 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-09-20 12:20:46,100 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-20 12:20:46,154 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'keystone-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-09-20 12:20:46,788 [salt.state       ][INFO    ][31544] {'pid': 31959, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:20:46,789 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 12:20:46.788459 duration_in_ms=689.006
2017-09-20 12:20:46,789 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 12:20:46.789022
2017-09-20 12:20:46,789 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-09-20 12:20:46,790 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-20 12:20:46,848 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-09-20 12:20:47,515 [salt.state       ][INFO    ][31544] {'pid': 31976, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:20:47,516 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 12:20:47.515747 duration_in_ms=726.724
2017-09-20 12:20:47,516 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 12:20:47.516307
2017-09-20 12:20:47,517 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-09-20 12:20:47,517 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-20 12:20:47,575 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-09-20 12:20:48,222 [salt.state       ][INFO    ][31544] {'pid': 31994, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:20:48,223 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 12:20:48.222497 duration_in_ms=706.189
2017-09-20 12:20:48,223 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 12:20:48.223045
2017-09-20 12:20:48,223 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-09-20 12:20:48,224 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-09-20 12:20:48,278 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'keystone-keys' 'nfs.disable' 'True'' in directory '/root'
2017-09-20 12:20:50,622 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command saltutil.find_job with jid 20170920122050605956
2017-09-20 12:20:50,635 [salt.minion      ][INFO    ][32023] Starting a new job with PID 32023
2017-09-20 12:20:50,646 [salt.minion      ][INFO    ][32023] Returning information for job: 20170920122050605956
2017-09-20 12:20:50,976 [salt.state       ][INFO    ][31544] {'pid': 32011, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:20:50,977 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 12:20:50.976579 duration_in_ms=2753.533
2017-09-20 12:20:50,977 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 12:20:50.977139
2017-09-20 12:20:50,977 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-09-20 12:20:50,978 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-20 12:20:51,037 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-09-20 12:20:51,624 [salt.state       ][INFO    ][31544] {'pid': 32035, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:20:51,625 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 12:20:51.624775 duration_in_ms=647.634
2017-09-20 12:20:51,626 [salt.state       ][INFO    ][31544] Running state [keystone-keys] at time 12:20:51.625870
2017-09-20 12:20:51,626 [salt.state       ][INFO    ][31544] Executing state glusterfs.started for keystone-keys
2017-09-20 12:20:51,627 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:51,638 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:51,692 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:51,702 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:51,886 [salt.state       ][INFO    ][31544] Volume keystone-keys is already started
2017-09-20 12:20:51,886 [salt.state       ][INFO    ][31544] Completed state [keystone-keys] at time 12:20:51.886223 duration_in_ms=260.352
2017-09-20 12:20:51,888 [salt.state       ][INFO    ][31544] Running state [nova_instances] at time 12:20:51.887617
2017-09-20 12:20:51,888 [salt.state       ][INFO    ][31544] Executing state glusterfs.created for nova_instances
2017-09-20 12:20:51,888 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:51,900 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:51,954 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:51,963 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:52,696 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:52,709 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:55,540 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:20:55,552 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:20:55,606 [salt.state       ][INFO    ][31544] {'new': ['glance', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys']}
2017-09-20 12:20:55,606 [salt.state       ][INFO    ][31544] Completed state [nova_instances] at time 12:20:55.606378 duration_in_ms=3718.76
2017-09-20 12:20:55,609 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 12:20:55.609431
2017-09-20 12:20:55,610 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-09-20 12:20:55,610 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-20 12:20:55,667 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-09-20 12:20:56,289 [salt.state       ][INFO    ][31544] {'pid': 32149, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:20:56,289 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 12:20:56.288859 duration_in_ms=679.427
2017-09-20 12:20:56,289 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 12:20:56.289397
2017-09-20 12:20:56,290 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'
2017-09-20 12:20:56,290 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-20 12:20:56,347 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-09-20 12:20:56,978 [salt.state       ][INFO    ][31544] {'pid': 32167, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:20:56,978 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 12:20:56.977822 duration_in_ms=688.425
2017-09-20 12:20:56,978 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 12:20:56.978390
2017-09-20 12:20:56,979 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'True'
2017-09-20 12:20:56,979 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-20 12:20:57,037 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'nova_instances' 'network.remote-dio' 'True'' in directory '/root'
2017-09-20 12:20:57,892 [salt.state       ][INFO    ][31544] {'pid': 32184, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:20:57,893 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 12:20:57.892552 duration_in_ms=914.161
2017-09-20 12:20:57,893 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 12:20:57.893169
2017-09-20 12:20:57,893 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'True'
2017-09-20 12:20:57,894 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: True'' in directory '/root'
2017-09-20 12:20:57,953 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'nova_instances' 'nfs.disable' 'True'' in directory '/root'
2017-09-20 12:21:00,664 [salt.state       ][INFO    ][31544] {'pid': 32201, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:21:00,664 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 12:21:00.664365 duration_in_ms=2771.195
2017-09-20 12:21:00,665 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 12:21:00.664970
2017-09-20 12:21:00,665 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-09-20 12:21:00,666 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-20 12:21:00,722 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-09-20 12:21:00,766 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command saltutil.find_job with jid 20170920122100750360
2017-09-20 12:21:00,779 [salt.minion      ][INFO    ][32232] Starting a new job with PID 32232
2017-09-20 12:21:00,787 [salt.minion      ][INFO    ][32232] Returning information for job: 20170920122100750360
2017-09-20 12:21:01,356 [salt.state       ][INFO    ][31544] {'pid': 32222, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:21:01,356 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 12:21:01.356299 duration_in_ms=691.327
2017-09-20 12:21:01,357 [salt.state       ][INFO    ][31544] Running state [nova_instances] at time 12:21:01.357399
2017-09-20 12:21:01,358 [salt.state       ][INFO    ][31544] Executing state glusterfs.started for nova_instances
2017-09-20 12:21:01,359 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:01,372 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:01,426 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:01,435 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:01,493 [salt.state       ][INFO    ][31544] Volume nova_instances is already started
2017-09-20 12:21:01,493 [salt.state       ][INFO    ][31544] Completed state [nova_instances] at time 12:21:01.492937 duration_in_ms=135.537
2017-09-20 12:21:01,494 [salt.state       ][INFO    ][31544] Running state [keystone-credential-keys] at time 12:21:01.493890
2017-09-20 12:21:01,494 [salt.state       ][INFO    ][31544] Executing state glusterfs.created for keystone-credential-keys
2017-09-20 12:21:01,495 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:01,505 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:01,556 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:01,566 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:02,329 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:02,341 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:05,229 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:05,241 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:05,293 [salt.state       ][INFO    ][31544] {'new': ['glance', 'keystone-credential-keys', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys', 'nova_instances']}
2017-09-20 12:21:05,293 [salt.state       ][INFO    ][31544] Completed state [keystone-credential-keys] at time 12:21:05.292951 duration_in_ms=3799.061
2017-09-20 12:21:05,296 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 12:21:05.295999
2017-09-20 12:21:05,296 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-09-20 12:21:05,297 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-20 12:21:05,347 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-09-20 12:21:06,073 [salt.state       ][INFO    ][31544] {'pid': 32344, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:21:06,073 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 12:21:06.073300 duration_in_ms=777.3
2017-09-20 12:21:06,074 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 12:21:06.073868
2017-09-20 12:21:06,074 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-09-20 12:21:06,075 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-20 12:21:06,132 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-09-20 12:21:06,783 [salt.state       ][INFO    ][31544] {'pid': 32362, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:21:06,783 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 12:21:06.782827 duration_in_ms=708.958
2017-09-20 12:21:06,783 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 12:21:06.783382
2017-09-20 12:21:06,784 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-09-20 12:21:06,784 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-09-20 12:21:06,840 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'' in directory '/root'
2017-09-20 12:21:09,553 [salt.state       ][INFO    ][31544] {'pid': 32380, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:21:09,554 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 12:21:09.553447 duration_in_ms=2770.064
2017-09-20 12:21:09,554 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 12:21:09.554006
2017-09-20 12:21:09,554 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-09-20 12:21:09,555 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-20 12:21:09,614 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-09-20 12:21:10,274 [salt.state       ][INFO    ][31544] {'pid': 32399, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:21:10,274 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 12:21:10.274312 duration_in_ms=720.305
2017-09-20 12:21:10,275 [salt.state       ][INFO    ][31544] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 12:21:10.274855
2017-09-20 12:21:10,275 [salt.state       ][INFO    ][31544] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-09-20 12:21:10,276 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-20 12:21:10,333 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-09-20 12:21:10,903 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command saltutil.find_job with jid 20170920122110888078
2017-09-20 12:21:10,916 [salt.minion      ][INFO    ][32426] Starting a new job with PID 32426
2017-09-20 12:21:10,925 [salt.minion      ][INFO    ][32426] Returning information for job: 20170920122110888078
2017-09-20 12:21:10,956 [salt.state       ][INFO    ][31544] {'pid': 32416, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-20 12:21:10,956 [salt.state       ][INFO    ][31544] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 12:21:10.956291 duration_in_ms=681.435
2017-09-20 12:21:10,957 [salt.state       ][INFO    ][31544] Running state [keystone-credential-keys] at time 12:21:10.957306
2017-09-20 12:21:10,958 [salt.state       ][INFO    ][31544] Executing state glusterfs.started for keystone-credential-keys
2017-09-20 12:21:10,958 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:10,968 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:11,018 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:11,027 [salt.loaded.int.module.cmdmod][INFO    ][31544] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:11,083 [salt.state       ][INFO    ][31544] Volume keystone-credential-keys is already started
2017-09-20 12:21:11,084 [salt.state       ][INFO    ][31544] Completed state [keystone-credential-keys] at time 12:21:11.083476 duration_in_ms=126.169
2017-09-20 12:21:11,085 [salt.minion      ][INFO    ][31544] Returning information for job: 20170920122030427058
2017-09-20 12:21:27,896 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command test.ping with jid 20170920122127887816
2017-09-20 12:21:27,910 [salt.minion      ][INFO    ][32454] Starting a new job with PID 32454
2017-09-20 12:21:27,935 [salt.minion      ][INFO    ][32454] Returning information for job: 20170920122127887816
2017-09-20 12:21:28,105 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command cmd.run with jid 20170920122128090096
2017-09-20 12:21:28,118 [salt.minion      ][INFO    ][32459] Starting a new job with PID 32459
2017-09-20 12:21:28,122 [salt.loaded.int.module.cmdmod][INFO    ][32459] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-09-20 12:21:28,238 [salt.minion      ][INFO    ][32459] Returning information for job: 20170920122128090096
2017-09-20 12:38:27,207 [salt.utils.schedule][INFO    ][24138] Running scheduled job: __mine_interval
2017-09-20 13:38:12,651 [salt.minion      ][INFO    ][24138] User sudo_ubuntu Executing command cp.push_dir with jid 20170920133812634144
2017-09-20 13:38:12,676 [salt.minion      ][INFO    ][1213] Starting a new job with PID 1213
