2017-09-15 21:20:28,162 [salt.cli.daemons ][WARNING ][2483] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-15 21:20:30,810 [salt.state       ][ERROR   ][2839] No contents found in top file
2017-09-15 21:20:48,588 [salt.utils.parsers][WARNING ][2483] Minion received a SIGTERM. Exiting.
2017-09-15 21:23:12,019 [salt.cli.daemons ][WARNING ][3176] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-15 21:26:06,344 [py.warnings      ][WARNING ][15304] /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-15 21:31:57,207 [salt.loaded.int.module.cmdmod][ERROR   ][15304] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-15 21:31:57,208 [salt.loaded.int.module.cmdmod][ERROR   ][15304] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-15 21:32:05,364 [salt.loaded.int.module.cmdmod][ERROR   ][15304] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-15 21:32:05,365 [salt.loaded.int.module.cmdmod][ERROR   ][15304] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-15 21:32:16,721 [salt.loaded.int.module.cmdmod][ERROR   ][15304] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-15 21:32:16,722 [salt.loaded.int.module.cmdmod][ERROR   ][15304] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-15 21:32:25,807 [salt.loaded.int.module.cmdmod][ERROR   ][15304] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-15 21:32:25,808 [salt.loaded.int.module.cmdmod][ERROR   ][15304] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-15 21:32:36,200 [salt.loaded.int.module.cmdmod][ERROR   ][15304] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-15 21:32:36,201 [salt.loaded.int.module.cmdmod][ERROR   ][15304] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-15 21:32:44,571 [salt.loaded.int.module.cmdmod][ERROR   ][15304] 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-15 21:32:47,870 [salt.state       ][ERROR   ][23023] No contents found in top file
2017-09-15 21:32:52,061 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-09-15 21:32:52,074 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-09-15 21:32:52,092 [salt.loaded.int.module.cmdmod][INFO    ][23254] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-09-15 21:32:52,106 [salt.utils.parsers][WARNING ][3176] Minion received a SIGTERM. Exiting.
2017-09-15 21:32:52,489 [salt.cli.daemons ][INFO    ][23358] Setting up the Salt Minion "kvm01.baremetal-mcp-ocata-ovs-ha.local"
2017-09-15 21:32:53,244 [salt.minion      ][INFO    ][23358] Creating minion process manager
2017-09-15 21:32:53,245 [salt.cli.daemons ][WARNING ][23358] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-15 21:32:53,246 [salt.cli.daemons ][INFO    ][23358] The Salt Minion is starting up
2017-09-15 21:32:53,246 [salt.minion      ][INFO    ][23358] Minion is starting as user 'root'
2017-09-15 21:32:53,247 [salt.utils.event ][INFO    ][23358] Starting pull socket on /var/run/salt/minion/minion_event_fd678c5357_pull.ipc
2017-09-15 21:32:54,416 [salt.loaded.int.module.cmdmod][INFO    ][23358] Executing command ['date', '+%z'] in directory '/root'
2017-09-15 21:32:54,447 [salt.utils.schedule][INFO    ][23358] Updating job settings for scheduled job: __mine_interval
2017-09-15 21:32:54,451 [salt.minion      ][INFO    ][23358] Added mine.update to scheduler
2017-09-15 21:32:54,674 [salt.minion      ][INFO    ][23358] Minion is ready to receive requests!
2017-09-15 21:32:54,675 [salt.minion      ][INFO    ][23358] User sudo_ubuntu Executing command state.sls with jid 20170915213253492380
2017-09-15 21:32:54,696 [salt.minion      ][INFO    ][23446] Starting a new job with PID 23446
2017-09-15 21:32:55,268 [salt.state       ][INFO    ][23446] Loading fresh modules for state activity
2017-09-15 21:32:55,317 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-09-15 21:32:55,345 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-09-15 21:32:55,380 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-15 21:32:55,521 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-09-15 21:32:55,543 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-09-15 21:32:55,584 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-15 21:32:55,675 [salt.utils.schedule][INFO    ][23358] Running scheduled job: __mine_interval
2017-09-15 21:32:55,746 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-09-15 21:32:55,767 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-15 21:32:55,819 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-09-15 21:32:55,839 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-09-15 21:32:55,852 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-15 21:32:55,867 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-09-15 21:32:55,876 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-09-15 21:32:56,033 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-09-15 21:32:56,065 [py.warnings      ][WARNING ][23446] /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-15 21:32:56,066 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command 'salt-minion --version' in directory '/root'
2017-09-15 21:32:56,468 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-09-15 21:32:56,494 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-09-15 21:32:56,510 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-09-15 21:32:56,525 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-09-15 21:32:56,599 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-09-15 21:32:56,649 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-09-15 21:32:56,730 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-09-15 21:32:56,767 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-15 21:32:56,930 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-09-15 21:32:56,954 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-15 21:32:57,007 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-09-15 21:32:57,026 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-09-15 21:32:57,039 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-15 21:32:57,091 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-09-15 21:32:57,109 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-09-15 21:32:57,146 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-09-15 21:32:57,166 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command 'salt-minion --version' in directory '/root'
2017-09-15 21:32:57,541 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-09-15 21:32:57,581 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-09-15 21:32:57,598 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-09-15 21:32:57,632 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-09-15 21:32:57,713 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-09-15 21:32:57,762 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-09-15 21:32:57,848 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-09-15 21:32:57,913 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-15 21:32:58,595 [salt.state       ][INFO    ][23446] Running state [salt-minion] at time 21:32:58.594859
2017-09-15 21:32:58,595 [salt.state       ][INFO    ][23446] Executing state pkg.installed for salt-minion
2017-09-15 21:32:58,596 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-15 21:32:59,032 [salt.state       ][INFO    ][23446] Package salt-minion is already installed
2017-09-15 21:32:59,032 [salt.state       ][INFO    ][23446] Completed state [salt-minion] at time 21:32:59.031975 duration_in_ms=437.116
2017-09-15 21:32:59,032 [salt.state       ][INFO    ][23446] Running state [python-yaml] at time 21:32:59.032351
2017-09-15 21:32:59,033 [salt.state       ][INFO    ][23446] Executing state pkg.installed for python-yaml
2017-09-15 21:32:59,038 [salt.state       ][INFO    ][23446] Package python-yaml is already installed
2017-09-15 21:32:59,038 [salt.state       ][INFO    ][23446] Completed state [python-yaml] at time 21:32:59.038194 duration_in_ms=5.842
2017-09-15 21:32:59,039 [salt.state       ][INFO    ][23446] Running state [python-psutil] at time 21:32:59.038474
2017-09-15 21:32:59,039 [salt.state       ][INFO    ][23446] Executing state pkg.installed for python-psutil
2017-09-15 21:32:59,044 [salt.state       ][INFO    ][23446] Package python-psutil is already installed
2017-09-15 21:32:59,044 [salt.state       ][INFO    ][23446] Completed state [python-psutil] at time 21:32:59.044080 duration_in_ms=5.606
2017-09-15 21:32:59,044 [salt.state       ][INFO    ][23446] Running state [python-m2crypto] at time 21:32:59.044357
2017-09-15 21:32:59,045 [salt.state       ][INFO    ][23446] Executing state pkg.installed for python-m2crypto
2017-09-15 21:32:59,050 [salt.state       ][INFO    ][23446] Package python-m2crypto is already installed
2017-09-15 21:32:59,050 [salt.state       ][INFO    ][23446] Completed state [python-m2crypto] at time 21:32:59.049802 duration_in_ms=5.445
2017-09-15 21:32:59,052 [salt.state       ][INFO    ][23446] Running state [/etc/salt/minion.d/minion.conf] at time 21:32:59.052388
2017-09-15 21:32:59,053 [salt.state       ][INFO    ][23446] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-09-15 21:32:59,105 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-09-15 21:32:59,195 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-15 21:32:59,266 [salt.fileclient  ][INFO    ][23446] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-15 21:32:59,314 [salt.state       ][INFO    ][23446] File /etc/salt/minion.d/minion.conf is in the correct state
2017-09-15 21:32:59,315 [salt.state       ][INFO    ][23446] Completed state [/etc/salt/minion.d/minion.conf] at time 21:32:59.314503 duration_in_ms=262.115
2017-09-15 21:32:59,315 [salt.state       ][INFO    ][23446] Running state [/etc/salt/minion.d/_virt.conf] at time 21:32:59.315013
2017-09-15 21:32:59,315 [salt.state       ][INFO    ][23446] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-09-15 21:32:59,402 [salt.state       ][INFO    ][23446] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-09-15 21:32:59,403 [salt.state       ][INFO    ][23446] Completed state [/etc/salt/minion.d/_virt.conf] at time 21:32:59.402615 duration_in_ms=87.6
2017-09-15 21:32:59,406 [salt.state       ][INFO    ][23446] Running state [salt-minion] at time 21:32:59.405564
2017-09-15 21:32:59,406 [salt.state       ][INFO    ][23446] Executing state service.running for salt-minion
2017-09-15 21:32:59,407 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-09-15 21:32:59,441 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-09-15 21:32:59,459 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-09-15 21:32:59,476 [salt.state       ][INFO    ][23446] The service salt-minion is already running
2017-09-15 21:32:59,477 [salt.state       ][INFO    ][23446] Completed state [salt-minion] at time 21:32:59.476451 duration_in_ms=70.886
2017-09-15 21:32:59,479 [salt.state       ][INFO    ][23446] Running state [/etc/salt/grains.d] at time 21:32:59.479102
2017-09-15 21:32:59,480 [salt.state       ][INFO    ][23446] Executing state file.directory for /etc/salt/grains.d
2017-09-15 21:32:59,481 [salt.state       ][INFO    ][23446] Directory /etc/salt/grains.d is in the correct state
2017-09-15 21:32:59,482 [salt.state       ][INFO    ][23446] Completed state [/etc/salt/grains.d] at time 21:32:59.481415 duration_in_ms=2.312
2017-09-15 21:32:59,483 [salt.state       ][INFO    ][23446] Running state [/etc/salt/grains] at time 21:32:59.482697
2017-09-15 21:32:59,483 [salt.state       ][INFO    ][23446] Executing state file.managed for /etc/salt/grains
2017-09-15 21:32:59,484 [salt.state       ][INFO    ][23446] File /etc/salt/grains exists with proper permissions. No changes made.
2017-09-15 21:32:59,485 [salt.state       ][INFO    ][23446] Completed state [/etc/salt/grains] at time 21:32:59.484422 duration_in_ms=1.723
2017-09-15 21:32:59,485 [salt.state       ][INFO    ][23446] Running state [/etc/salt/grains.d/placeholder] at time 21:32:59.485271
2017-09-15 21:32:59,486 [salt.state       ][INFO    ][23446] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-09-15 21:32:59,487 [salt.state       ][INFO    ][23446] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-09-15 21:32:59,487 [salt.state       ][INFO    ][23446] Completed state [/etc/salt/grains.d/placeholder] at time 21:32:59.487008 duration_in_ms=1.736
2017-09-15 21:32:59,488 [salt.state       ][INFO    ][23446] Running state [/etc/salt/grains.d/sphinx] at time 21:32:59.487838
2017-09-15 21:32:59,488 [salt.state       ][INFO    ][23446] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-09-15 21:32:59,690 [salt.state       ][INFO    ][23446] File /etc/salt/grains.d/sphinx is in the correct state
2017-09-15 21:32:59,690 [salt.state       ][INFO    ][23446] Completed state [/etc/salt/grains.d/sphinx] at time 21:32:59.690408 duration_in_ms=202.569
2017-09-15 21:32:59,692 [salt.state       ][INFO    ][23446] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:32:59.691671
2017-09-15 21:32:59,692 [salt.state       ][INFO    ][23446] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-09-15 21:32:59,693 [salt.state       ][INFO    ][23446] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-09-15 21:32:59,693 [salt.state       ][INFO    ][23446] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:32:59.692973 duration_in_ms=1.302
2017-09-15 21:32:59,694 [salt.state       ][INFO    ][23446] Running state [/etc/salt/grains.d/dns_records] at time 21:32:59.693685
2017-09-15 21:32:59,694 [salt.state       ][INFO    ][23446] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-09-15 21:32:59,783 [salt.state       ][INFO    ][23446] File /etc/salt/grains.d/dns_records is in the correct state
2017-09-15 21:32:59,784 [salt.state       ][INFO    ][23446] Completed state [/etc/salt/grains.d/dns_records] at time 21:32:59.783602 duration_in_ms=89.917
2017-09-15 21:32:59,785 [salt.state       ][INFO    ][23446] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:32:59.784695
2017-09-15 21:32:59,785 [salt.state       ][INFO    ][23446] 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-15 21:32:59,786 [salt.state       ][INFO    ][23446] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-09-15 21:32:59,786 [salt.state       ][INFO    ][23446] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:32:59.785837 duration_in_ms=1.143
2017-09-15 21:32:59,786 [salt.state       ][INFO    ][23446] Running state [/etc/salt/grains.d/salt] at time 21:32:59.786410
2017-09-15 21:32:59,787 [salt.state       ][INFO    ][23446] Executing state file.managed for /etc/salt/grains.d/salt
2017-09-15 21:32:59,857 [salt.state       ][INFO    ][23446] File /etc/salt/grains.d/salt is in the correct state
2017-09-15 21:32:59,857 [salt.state       ][INFO    ][23446] Completed state [/etc/salt/grains.d/salt] at time 21:32:59.857404 duration_in_ms=70.993
2017-09-15 21:32:59,859 [salt.state       ][INFO    ][23446] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:32:59.858787
2017-09-15 21:32:59,859 [salt.state       ][INFO    ][23446] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-09-15 21:32:59,860 [salt.state       ][INFO    ][23446] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-09-15 21:32:59,860 [salt.state       ][INFO    ][23446] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:32:59.860138 duration_in_ms=1.35
2017-09-15 21:32:59,864 [salt.state       ][INFO    ][23446] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:32:59.863537
2017-09-15 21:32:59,864 [salt.state       ][INFO    ][23446] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-09-15 21:32:59,865 [salt.state       ][INFO    ][23446] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-09-15 21:32:59,865 [salt.state       ][INFO    ][23446] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:32:59.864830 duration_in_ms=1.293
2017-09-15 21:32:59,866 [salt.state       ][INFO    ][23446] Running state [mine.update] at time 21:32:59.865958
2017-09-15 21:32:59,866 [salt.state       ][INFO    ][23446] Executing state module.wait for mine.update
2017-09-15 21:32:59,867 [salt.state       ][INFO    ][23446] No changes made for mine.update
2017-09-15 21:32:59,867 [salt.state       ][INFO    ][23446] Completed state [mine.update] at time 21:32:59.867128 duration_in_ms=1.171
2017-09-15 21:32:59,868 [salt.state       ][INFO    ][23446] Running state [ca-certificates] at time 21:32:59.867498
2017-09-15 21:32:59,868 [salt.state       ][INFO    ][23446] Executing state pkg.installed for ca-certificates
2017-09-15 21:32:59,876 [salt.state       ][INFO    ][23446] Package ca-certificates is already installed
2017-09-15 21:32:59,876 [salt.state       ][INFO    ][23446] Completed state [ca-certificates] at time 21:32:59.876098 duration_in_ms=8.6
2017-09-15 21:32:59,877 [salt.state       ][INFO    ][23446] Running state [update-ca-certificates] at time 21:32:59.877160
2017-09-15 21:32:59,878 [salt.state       ][INFO    ][23446] Executing state cmd.wait for update-ca-certificates
2017-09-15 21:32:59,878 [salt.state       ][INFO    ][23446] No changes made for update-ca-certificates
2017-09-15 21:32:59,878 [salt.state       ][INFO    ][23446] Completed state [update-ca-certificates] at time 21:32:59.878322 duration_in_ms=1.162
2017-09-15 21:32:59,879 [salt.state       ][INFO    ][23446] Running state [libvirt-dev] at time 21:32:59.878677
2017-09-15 21:32:59,879 [salt.state       ][INFO    ][23446] Executing state pkg.installed for libvirt-dev
2017-09-15 21:32:59,886 [salt.state       ][INFO    ][23446] Package libvirt-dev is already installed
2017-09-15 21:32:59,886 [salt.state       ][INFO    ][23446] Completed state [libvirt-dev] at time 21:32:59.886017 duration_in_ms=7.34
2017-09-15 21:32:59,886 [salt.state       ][INFO    ][23446] Running state [pkg-config] at time 21:32:59.886357
2017-09-15 21:32:59,887 [salt.state       ][INFO    ][23446] Executing state pkg.installed for pkg-config
2017-09-15 21:32:59,893 [salt.state       ][INFO    ][23446] Package pkg-config is already installed
2017-09-15 21:32:59,894 [salt.state       ][INFO    ][23446] Completed state [pkg-config] at time 21:32:59.893454 duration_in_ms=7.098
2017-09-15 21:32:59,894 [salt.state       ][INFO    ][23446] Running state [virtng.init] at time 21:32:59.893816
2017-09-15 21:32:59,894 [salt.state       ][INFO    ][23446] Executing state module.run for virtng.init
2017-09-15 21:33:00,153 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command ['git', '--version'] in directory '/root'
2017-09-15 21:33:00,346 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command 'virsh list --all --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-15 21:33:00,380 [salt.state       ][INFO    ][23446] unless execution succeeded
2017-09-15 21:33:00,381 [salt.state       ][INFO    ][23446] Completed state [virtng.init] at time 21:33:00.380739 duration_in_ms=486.923
2017-09-15 21:33:00,381 [salt.state       ][INFO    ][23446] Running state [virt.set_autostart] at time 21:33:00.381235
2017-09-15 21:33:00,382 [salt.state       ][INFO    ][23446] Executing state module.run for virt.set_autostart
2017-09-15 21:33:00,386 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command 'virsh list --autostart --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-15 21:33:00,415 [salt.state       ][INFO    ][23446] unless execution succeeded
2017-09-15 21:33:00,416 [salt.state       ][INFO    ][23446] Completed state [virt.set_autostart] at time 21:33:00.415825 duration_in_ms=34.59
2017-09-15 21:33:00,416 [salt.state       ][INFO    ][23446] Running state [virtng.init] at time 21:33:00.416246
2017-09-15 21:33:00,417 [salt.state       ][INFO    ][23446] Executing state module.run for virtng.init
2017-09-15 21:33:00,421 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command 'virsh list --all --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-15 21:33:00,449 [salt.state       ][INFO    ][23446] unless execution succeeded
2017-09-15 21:33:00,450 [salt.state       ][INFO    ][23446] Completed state [virtng.init] at time 21:33:00.449582 duration_in_ms=33.335
2017-09-15 21:33:00,450 [salt.state       ][INFO    ][23446] Running state [virt.set_autostart] at time 21:33:00.450187
2017-09-15 21:33:00,451 [salt.state       ][INFO    ][23446] Executing state module.run for virt.set_autostart
2017-09-15 21:33:00,456 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command 'virsh list --autostart --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-15 21:33:00,603 [salt.state       ][INFO    ][23446] unless execution succeeded
2017-09-15 21:33:00,603 [salt.state       ][INFO    ][23446] Completed state [virt.set_autostart] at time 21:33:00.603254 duration_in_ms=153.066
2017-09-15 21:33:00,604 [salt.state       ][INFO    ][23446] Running state [virtng.init] at time 21:33:00.603789
2017-09-15 21:33:00,604 [salt.state       ][INFO    ][23446] Executing state module.run for virtng.init
2017-09-15 21:33:00,609 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command 'virsh list --all --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-15 21:33:00,644 [salt.state       ][INFO    ][23446] unless execution succeeded
2017-09-15 21:33:00,644 [salt.state       ][INFO    ][23446] Completed state [virtng.init] at time 21:33:00.644342 duration_in_ms=40.551
2017-09-15 21:33:00,645 [salt.state       ][INFO    ][23446] Running state [virt.set_autostart] at time 21:33:00.644888
2017-09-15 21:33:00,645 [salt.state       ][INFO    ][23446] Executing state module.run for virt.set_autostart
2017-09-15 21:33:00,651 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command 'virsh list --autostart --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-15 21:33:00,686 [salt.state       ][INFO    ][23446] unless execution succeeded
2017-09-15 21:33:00,687 [salt.state       ][INFO    ][23446] Completed state [virt.set_autostart] at time 21:33:00.686947 duration_in_ms=42.058
2017-09-15 21:33:00,688 [salt.state       ][INFO    ][23446] Running state [virtng.init] at time 21:33:00.687529
2017-09-15 21:33:00,688 [salt.state       ][INFO    ][23446] Executing state module.run for virtng.init
2017-09-15 21:33:00,692 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command 'virsh list --all --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-15 21:33:00,724 [salt.state       ][INFO    ][23446] unless execution succeeded
2017-09-15 21:33:00,725 [salt.state       ][INFO    ][23446] Completed state [virtng.init] at time 21:33:00.724661 duration_in_ms=37.132
2017-09-15 21:33:00,725 [salt.state       ][INFO    ][23446] Running state [virt.set_autostart] at time 21:33:00.725063
2017-09-15 21:33:00,725 [salt.state       ][INFO    ][23446] Executing state module.run for virt.set_autostart
2017-09-15 21:33:00,732 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command 'virsh list --autostart --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-15 21:33:00,762 [salt.state       ][INFO    ][23446] unless execution succeeded
2017-09-15 21:33:00,763 [salt.state       ][INFO    ][23446] Completed state [virt.set_autostart] at time 21:33:00.762452 duration_in_ms=37.387
2017-09-15 21:33:00,763 [salt.state       ][INFO    ][23446] Running state [virtng.init] at time 21:33:00.762893
2017-09-15 21:33:00,763 [salt.state       ][INFO    ][23446] Executing state module.run for virtng.init
2017-09-15 21:33:00,768 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command 'virsh list --all --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-15 21:33:00,798 [salt.state       ][INFO    ][23446] unless execution succeeded
2017-09-15 21:33:00,799 [salt.state       ][INFO    ][23446] Completed state [virtng.init] at time 21:33:00.798606 duration_in_ms=35.712
2017-09-15 21:33:00,799 [salt.state       ][INFO    ][23446] Running state [virt.set_autostart] at time 21:33:00.799033
2017-09-15 21:33:00,799 [salt.state       ][INFO    ][23446] Executing state module.run for virt.set_autostart
2017-09-15 21:33:00,808 [salt.loaded.int.module.cmdmod][INFO    ][23446] Executing command 'virsh list --autostart --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-15 21:33:00,840 [salt.state       ][INFO    ][23446] unless execution succeeded
2017-09-15 21:33:00,841 [salt.state       ][INFO    ][23446] Completed state [virt.set_autostart] at time 21:33:00.840520 duration_in_ms=41.486
2017-09-15 21:33:00,846 [salt.minion      ][INFO    ][23446] Returning information for job: 20170915213253492380
2017-09-15 21:33:01,723 [salt.minion      ][INFO    ][23358] User sudo_ubuntu Executing command pillar.get with jid 20170915213301713313
2017-09-15 21:33:01,744 [salt.minion      ][INFO    ][23551] Starting a new job with PID 23551
2017-09-15 21:33:01,834 [salt.minion      ][INFO    ][23551] Returning information for job: 20170915213301713313
2017-09-15 21:34:22,246 [salt.minion      ][INFO    ][23358] User sudo_ubuntu Executing command saltutil.sync_all with jid 20170915213422240216
2017-09-15 21:34:22,270 [salt.minion      ][INFO    ][23567] Starting a new job with PID 23567
2017-09-15 21:34:25,143 [salt.state       ][INFO    ][23567] Loading fresh modules for state activity
2017-09-15 21:34:26,026 [salt.state       ][ERROR   ][23567] No contents found in top file
2017-09-15 21:34:26,028 [salt.utils.extmods][INFO    ][23567] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-09-15 21:34:26,034 [salt.utils.extmods][INFO    ][23567] Syncing beacons for environment 'base'
2017-09-15 21:34:26,035 [salt.utils.extmods][INFO    ][23567] Loading cache from salt://_beacons, for base)
2017-09-15 21:34:26,035 [salt.fileclient  ][INFO    ][23567] Caching directory '_beacons/' for environment 'base'
2017-09-15 21:34:26,087 [salt.utils.extmods][INFO    ][23567] Syncing modules for environment 'base'
2017-09-15 21:34:26,087 [salt.utils.extmods][INFO    ][23567] Loading cache from salt://_modules, for base)
2017-09-15 21:34:26,087 [salt.fileclient  ][INFO    ][23567] Caching directory '_modules/' for environment 'base'
2017-09-15 21:34:26,137 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-09-15 21:34:26,243 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-09-15 21:34:26,272 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-09-15 21:34:26,280 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-09-15 21:34:26,290 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-09-15 21:34:26,302 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-09-15 21:34:26,336 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-09-15 21:34:26,353 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-09-15 21:34:26,369 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-09-15 21:34:26,870 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-09-15 21:34:26,921 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-09-15 21:34:26,933 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-09-15 21:34:26,957 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-09-15 21:34:26,982 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-09-15 21:34:27,044 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-09-15 21:34:27,074 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-09-15 21:34:27,102 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-09-15 21:34:27,112 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-09-15 21:34:27,128 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-09-15 21:34:27,140 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-09-15 21:34:27,162 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-09-15 21:34:27,185 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-09-15 21:34:27,245 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-09-15 21:34:27,275 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-09-15 21:34:27,290 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-09-15 21:34:27,381 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-09-15 21:34:27,406 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-09-15 21:34:27,426 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-09-15 21:34:27,455 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-09-15 21:34:27,464 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-09-15 21:34:27,480 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-09-15 21:34:27,504 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-09-15 21:34:27,508 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-09-15 21:34:27,508 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-09-15 21:34:27,509 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-09-15 21:34:27,509 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-09-15 21:34:27,510 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-09-15 21:34:27,510 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-09-15 21:34:27,510 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-09-15 21:34:27,511 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-09-15 21:34:27,511 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-09-15 21:34:27,512 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-09-15 21:34:27,512 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-09-15 21:34:27,513 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-09-15 21:34:27,513 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-09-15 21:34:27,513 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-09-15 21:34:27,514 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-09-15 21:34:27,514 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-09-15 21:34:27,514 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-09-15 21:34:27,515 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-09-15 21:34:27,515 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-09-15 21:34:27,515 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-09-15 21:34:27,515 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-09-15 21:34:27,516 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-09-15 21:34:27,516 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-09-15 21:34:27,517 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-09-15 21:34:27,517 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-09-15 21:34:27,517 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-09-15 21:34:27,517 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-09-15 21:34:27,518 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-09-15 21:34:27,518 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-09-15 21:34:27,518 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-09-15 21:34:27,519 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-09-15 21:34:27,519 [salt.utils.extmods][INFO    ][23567] 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-15 21:34:27,519 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-09-15 21:34:27,519 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-09-15 21:34:27,528 [salt.utils.extmods][INFO    ][23567] Syncing states for environment 'base'
2017-09-15 21:34:27,528 [salt.utils.extmods][INFO    ][23567] Loading cache from salt://_states, for base)
2017-09-15 21:34:27,529 [salt.fileclient  ][INFO    ][23567] Caching directory '_states/' for environment 'base'
2017-09-15 21:34:27,644 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-09-15 21:34:27,680 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-09-15 21:34:27,693 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-09-15 21:34:27,740 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-09-15 21:34:27,767 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-09-15 21:34:27,794 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-09-15 21:34:27,814 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-09-15 21:34:27,865 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-09-15 21:34:27,881 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-09-15 21:34:27,893 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-09-15 21:34:27,904 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-09-15 21:34:27,920 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-09-15 21:34:27,942 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-09-15 21:34:27,955 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-09-15 21:34:27,972 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-09-15 21:34:27,988 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-09-15 21:34:27,998 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-09-15 21:34:28,012 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-09-15 21:34:28,024 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-09-15 21:34:28,035 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-09-15 21:34:28,056 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-09-15 21:34:28,069 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-09-15 21:34:28,082 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-09-15 21:34:28,108 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-09-15 21:34:28,125 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-09-15 21:34:28,177 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-09-15 21:34:28,192 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-09-15 21:34:28,204 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-09-15 21:34:28,223 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-09-15 21:34:28,227 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-09-15 21:34:28,227 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-09-15 21:34:28,228 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-09-15 21:34:28,228 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-09-15 21:34:28,228 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-09-15 21:34:28,229 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-09-15 21:34:28,229 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-09-15 21:34:28,230 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-09-15 21:34:28,230 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-09-15 21:34:28,230 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-09-15 21:34:28,231 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-09-15 21:34:28,231 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-09-15 21:34:28,232 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-09-15 21:34:28,232 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-09-15 21:34:28,232 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-09-15 21:34:28,233 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-09-15 21:34:28,233 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-09-15 21:34:28,234 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-09-15 21:34:28,234 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-09-15 21:34:28,234 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-09-15 21:34:28,235 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-09-15 21:34:28,235 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-09-15 21:34:28,236 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-09-15 21:34:28,236 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-09-15 21:34:28,236 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-09-15 21:34:28,237 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-09-15 21:34:28,237 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-09-15 21:34:28,238 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-09-15 21:34:28,238 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-09-15 21:34:28,238 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-09-15 21:34:28,241 [salt.utils.extmods][INFO    ][23567] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-09-15 21:34:28,247 [salt.utils.extmods][INFO    ][23567] Syncing sdb for environment 'base'
2017-09-15 21:34:28,247 [salt.utils.extmods][INFO    ][23567] Loading cache from salt://_sdb, for base)
2017-09-15 21:34:28,248 [salt.fileclient  ][INFO    ][23567] Caching directory '_sdb/' for environment 'base'
2017-09-15 21:34:28,433 [salt.utils.extmods][INFO    ][23567] Syncing grains for environment 'base'
2017-09-15 21:34:28,433 [salt.utils.extmods][INFO    ][23567] Loading cache from salt://_grains, for base)
2017-09-15 21:34:28,434 [salt.fileclient  ][INFO    ][23567] Caching directory '_grains/' for environment 'base'
2017-09-15 21:34:28,543 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-09-15 21:34:28,566 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-09-15 21:34:28,583 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-09-15 21:34:28,605 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-09-15 21:34:28,685 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-09-15 21:34:28,710 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-09-15 21:34:28,719 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-09-15 21:34:28,732 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-09-15 21:34:28,791 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-09-15 21:34:28,813 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-09-15 21:34:28,826 [salt.fileclient  ][INFO    ][23567] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-09-15 21:34:28,832 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-09-15 21:34:28,832 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-09-15 21:34:28,832 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-09-15 21:34:28,833 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-09-15 21:34:28,833 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-09-15 21:34:28,833 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-09-15 21:34:28,834 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-09-15 21:34:28,834 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-09-15 21:34:28,834 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-09-15 21:34:28,835 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-09-15 21:34:28,835 [salt.utils.extmods][INFO    ][23567] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-09-15 21:34:28,838 [salt.utils.extmods][INFO    ][23567] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-09-15 21:34:28,844 [salt.utils.extmods][INFO    ][23567] Syncing renderers for environment 'base'
2017-09-15 21:34:28,844 [salt.utils.extmods][INFO    ][23567] Loading cache from salt://_renderers, for base)
2017-09-15 21:34:28,844 [salt.fileclient  ][INFO    ][23567] Caching directory '_renderers/' for environment 'base'
2017-09-15 21:34:28,894 [salt.utils.extmods][INFO    ][23567] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-09-15 21:34:28,899 [salt.utils.extmods][INFO    ][23567] Syncing returners for environment 'base'
2017-09-15 21:34:28,899 [salt.utils.extmods][INFO    ][23567] Loading cache from salt://_returners, for base)
2017-09-15 21:34:28,900 [salt.fileclient  ][INFO    ][23567] Caching directory '_returners/' for environment 'base'
2017-09-15 21:34:28,936 [salt.utils.extmods][INFO    ][23567] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-09-15 21:34:28,941 [salt.utils.extmods][INFO    ][23567] Syncing output for environment 'base'
2017-09-15 21:34:28,941 [salt.utils.extmods][INFO    ][23567] Loading cache from salt://_output, for base)
2017-09-15 21:34:28,942 [salt.fileclient  ][INFO    ][23567] Caching directory '_output/' for environment 'base'
2017-09-15 21:34:29,002 [salt.utils.extmods][INFO    ][23567] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-09-15 21:34:29,007 [salt.utils.extmods][INFO    ][23567] Syncing utils for environment 'base'
2017-09-15 21:34:29,007 [salt.utils.extmods][INFO    ][23567] Loading cache from salt://_utils, for base)
2017-09-15 21:34:29,008 [salt.fileclient  ][INFO    ][23567] Caching directory '_utils/' for environment 'base'
2017-09-15 21:34:29,090 [salt.utils.extmods][INFO    ][23567] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-09-15 21:34:29,095 [salt.utils.extmods][INFO    ][23567] Syncing log_handlers for environment 'base'
2017-09-15 21:34:29,095 [salt.utils.extmods][INFO    ][23567] Loading cache from salt://_log_handlers, for base)
2017-09-15 21:34:29,096 [salt.fileclient  ][INFO    ][23567] Caching directory '_log_handlers/' for environment 'base'
2017-09-15 21:34:29,200 [salt.utils.extmods][INFO    ][23567] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-09-15 21:34:29,205 [salt.utils.extmods][INFO    ][23567] Syncing proxy for environment 'base'
2017-09-15 21:34:29,205 [salt.utils.extmods][INFO    ][23567] Loading cache from salt://_proxy, for base)
2017-09-15 21:34:29,205 [salt.fileclient  ][INFO    ][23567] Caching directory '_proxy/' for environment 'base'
2017-09-15 21:34:29,263 [salt.utils.extmods][INFO    ][23567] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-09-15 21:34:29,267 [salt.utils.extmods][INFO    ][23567] Syncing engines for environment 'base'
2017-09-15 21:34:29,268 [salt.utils.extmods][INFO    ][23567] Loading cache from salt://_engines, for base)
2017-09-15 21:34:29,268 [salt.fileclient  ][INFO    ][23567] Caching directory '_engines/' for environment 'base'
2017-09-15 21:34:29,300 [salt.minion      ][INFO    ][23567] Returning information for job: 20170915213422240216
2017-09-15 21:48:14,259 [salt.minion      ][INFO    ][23358] User sudo_ubuntu Executing command test.ping with jid 20170915214814255215
2017-09-15 21:48:14,290 [salt.minion      ][INFO    ][23830] Starting a new job with PID 23830
2017-09-15 21:48:14,345 [salt.minion      ][INFO    ][23830] Returning information for job: 20170915214814255215
2017-09-15 21:52:21,898 [salt.minion      ][INFO    ][23358] User sudo_ubuntu Executing command state.sls with jid 20170915215221885389
2017-09-15 21:52:21,921 [salt.minion      ][INFO    ][23897] Starting a new job with PID 23897
2017-09-15 21:52:22,349 [salt.state       ][INFO    ][23897] Loading fresh modules for state activity
2017-09-15 21:52:22,459 [salt.fileclient  ][INFO    ][23897] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-09-15 21:52:22,479 [salt.fileclient  ][INFO    ][23897] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-09-15 21:52:22,509 [salt.fileclient  ][INFO    ][23897] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-15 21:52:22,847 [salt.state       ][INFO    ][23897] Running state [lsof] at time 21:52:22.847184
2017-09-15 21:52:22,847 [salt.state       ][INFO    ][23897] Executing state pkg.installed for lsof
2017-09-15 21:52:22,848 [salt.loaded.int.module.cmdmod][INFO    ][23897] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-15 21:52:23,215 [salt.state       ][INFO    ][23897] Package lsof is already installed
2017-09-15 21:52:23,216 [salt.state       ][INFO    ][23897] Completed state [lsof] at time 21:52:23.215525 duration_in_ms=368.341
2017-09-15 21:52:23,216 [salt.state       ][INFO    ][23897] Running state [keepalived] at time 21:52:23.215754
2017-09-15 21:52:23,216 [salt.state       ][INFO    ][23897] Executing state pkg.installed for keepalived
2017-09-15 21:52:23,237 [salt.loaded.int.module.cmdmod][INFO    ][23897] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-15 21:52:25,493 [salt.loaded.int.module.cmdmod][INFO    ][23897] 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-15 21:52:31,943 [salt.minion      ][INFO    ][23358] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915215231930686
2017-09-15 21:52:31,964 [salt.minion      ][INFO    ][25169] Starting a new job with PID 25169
2017-09-15 21:52:31,983 [salt.minion      ][INFO    ][25169] Returning information for job: 20170915215231930686
2017-09-15 21:52:35,166 [salt.loaded.int.module.cmdmod][INFO    ][23897] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-15 21:52:35,220 [salt.state       ][INFO    ][23897] 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-15 21:52:35,237 [salt.state       ][INFO    ][23897] Loading fresh modules for state activity
2017-09-15 21:52:35,261 [salt.state       ][INFO    ][23897] Completed state [keepalived] at time 21:52:35.261182 duration_in_ms=12045.426
2017-09-15 21:52:35,264 [salt.state       ][INFO    ][23897] Running state [/etc/keepalived/keepalived.conf] at time 21:52:35.264446
2017-09-15 21:52:35,265 [salt.state       ][INFO    ][23897] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-09-15 21:52:35,300 [salt.fileclient  ][INFO    ][23897] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-09-15 21:52:35,348 [salt.fileclient  ][INFO    ][23897] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-15 21:52:35,365 [salt.state       ][INFO    ][23897] File changed:
New file
2017-09-15 21:52:35,365 [salt.state       ][INFO    ][23897] Completed state [/etc/keepalived/keepalived.conf] at time 21:52:35.365357 duration_in_ms=100.91
2017-09-15 21:52:35,469 [salt.state       ][INFO    ][23897] Running state [keepalived] at time 21:52:35.468589
2017-09-15 21:52:35,469 [salt.state       ][INFO    ][23897] Executing state service.running for keepalived
2017-09-15 21:52:35,471 [salt.loaded.int.module.cmdmod][INFO    ][23897] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-09-15 21:52:35,489 [salt.loaded.int.module.cmdmod][INFO    ][23897] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-09-15 21:52:35,504 [salt.loaded.int.module.cmdmod][INFO    ][23897] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-15 21:52:35,519 [salt.loaded.int.module.cmdmod][INFO    ][23897] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-15 21:52:35,535 [salt.loaded.int.module.cmdmod][INFO    ][23897] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-09-15 21:52:35,609 [salt.loaded.int.module.cmdmod][INFO    ][23897] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-09-15 21:52:35,625 [salt.loaded.int.module.cmdmod][INFO    ][23897] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-15 21:52:35,641 [salt.loaded.int.module.cmdmod][INFO    ][23897] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-15 21:52:35,654 [salt.state       ][INFO    ][23897] {'keepalived': True}
2017-09-15 21:52:35,655 [salt.state       ][INFO    ][23897] Completed state [keepalived] at time 21:52:35.654752 duration_in_ms=186.162
2017-09-15 21:52:35,656 [salt.minion      ][INFO    ][23897] Returning information for job: 20170915215221885389
2017-09-15 21:52:54,991 [salt.minion      ][INFO    ][23358] User sudo_ubuntu Executing command pillar.get with jid 20170915215254985265
2017-09-15 21:52:55,014 [salt.minion      ][INFO    ][25454] Starting a new job with PID 25454
2017-09-15 21:52:55,020 [salt.minion      ][INFO    ][25454] Returning information for job: 20170915215254985265
2017-09-15 21:59:35,769 [salt.minion      ][INFO    ][23358] User sudo_ubuntu Executing command state.sls with jid 20170915215935755943
2017-09-15 21:59:35,793 [salt.minion      ][INFO    ][25582] Starting a new job with PID 25582
2017-09-15 21:59:38,289 [salt.state       ][INFO    ][25582] Loading fresh modules for state activity
2017-09-15 21:59:38,331 [salt.fileclient  ][INFO    ][25582] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-09-15 21:59:38,362 [salt.fileclient  ][INFO    ][25582] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-15 21:59:38,778 [salt.state       ][INFO    ][25582] Running state [attr] at time 21:59:38.777652
2017-09-15 21:59:38,778 [salt.state       ][INFO    ][25582] Executing state pkg.installed for attr
2017-09-15 21:59:38,778 [salt.loaded.int.module.cmdmod][INFO    ][25582] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-15 21:59:39,168 [salt.loaded.int.module.cmdmod][INFO    ][25582] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-15 21:59:41,402 [salt.loaded.int.module.cmdmod][INFO    ][25582] 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-15 21:59:44,612 [salt.loaded.int.module.cmdmod][INFO    ][25582] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-15 21:59:44,668 [salt.state       ][INFO    ][25582] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-09-15 21:59:44,694 [salt.state       ][INFO    ][25582] Loading fresh modules for state activity
2017-09-15 21:59:44,722 [salt.state       ][INFO    ][25582] Completed state [attr] at time 21:59:44.722154 duration_in_ms=5944.5
2017-09-15 21:59:44,736 [salt.state       ][INFO    ][25582] Running state [glusterfs-server] at time 21:59:44.736001
2017-09-15 21:59:44,736 [salt.state       ][INFO    ][25582] Executing state pkg.installed for glusterfs-server
2017-09-15 21:59:45,109 [salt.loaded.int.module.cmdmod][INFO    ][25582] 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-15 21:59:45,862 [salt.minion      ][INFO    ][23358] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915215945850417
2017-09-15 21:59:45,885 [salt.minion      ][INFO    ][26539] Starting a new job with PID 26539
2017-09-15 21:59:45,904 [salt.minion      ][INFO    ][26539] Returning information for job: 20170915215945850417
2017-09-15 21:59:56,083 [salt.minion      ][INFO    ][23358] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915215956070025
2017-09-15 21:59:56,105 [salt.minion      ][INFO    ][29221] Starting a new job with PID 29221
2017-09-15 21:59:56,126 [salt.minion      ][INFO    ][29221] Returning information for job: 20170915215956070025
2017-09-15 22:00:06,303 [salt.minion      ][INFO    ][23358] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915220006289697
2017-09-15 22:00:06,326 [salt.minion      ][INFO    ][29821] Starting a new job with PID 29821
2017-09-15 22:00:06,344 [salt.minion      ][INFO    ][29821] Returning information for job: 20170915220006289697
2017-09-15 22:00:07,207 [salt.loaded.int.module.cmdmod][INFO    ][25582] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-15 22:00:07,268 [salt.state       ][INFO    ][25582] 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-15 22:00:07,286 [salt.state       ][INFO    ][25582] Loading fresh modules for state activity
2017-09-15 22:00:07,314 [salt.state       ][INFO    ][25582] Completed state [glusterfs-server] at time 22:00:07.314078 duration_in_ms=22578.076
2017-09-15 22:00:07,323 [salt.state       ][INFO    ][25582] Running state [bc] at time 22:00:07.323261
2017-09-15 22:00:07,324 [salt.state       ][INFO    ][25582] Executing state pkg.installed for bc
2017-09-15 22:00:07,742 [salt.loaded.int.module.cmdmod][INFO    ][25582] 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-15 22:00:11,316 [salt.loaded.int.module.cmdmod][INFO    ][25582] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-15 22:00:11,369 [salt.state       ][INFO    ][25582] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-09-15 22:00:11,382 [salt.state       ][INFO    ][25582] Loading fresh modules for state activity
2017-09-15 22:00:11,402 [salt.state       ][INFO    ][25582] Completed state [bc] at time 22:00:11.402163 duration_in_ms=4078.902
2017-09-15 22:00:11,510 [salt.state       ][INFO    ][25582] Running state [glusterfs-server] at time 22:00:11.509959
2017-09-15 22:00:11,510 [salt.state       ][INFO    ][25582] Executing state service.running for glusterfs-server
2017-09-15 22:00:11,513 [salt.loaded.int.module.cmdmod][INFO    ][25582] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-15 22:00:11,533 [salt.loaded.int.module.cmdmod][INFO    ][25582] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-09-15 22:00:11,550 [salt.loaded.int.module.cmdmod][INFO    ][25582] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-09-15 22:00:11,568 [salt.state       ][INFO    ][25582] The service glusterfs-server is already running
2017-09-15 22:00:11,568 [salt.state       ][INFO    ][25582] Completed state [glusterfs-server] at time 22:00:11.568090 duration_in_ms=58.132
2017-09-15 22:00:11,571 [salt.state       ][INFO    ][25582] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 22:00:11.570919
2017-09-15 22:00:11,571 [salt.state       ][INFO    ][25582] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-09-15 22:00:11,587 [salt.state       ][INFO    ][25582] File changed:
New file
2017-09-15 22:00:11,587 [salt.state       ][INFO    ][25582] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 22:00:11.586816 duration_in_ms=15.897
2017-09-15 22:00:11,587 [salt.state       ][INFO    ][25582] Running state [/srv/glusterfs/glance] at time 22:00:11.587068
2017-09-15 22:00:11,587 [salt.state       ][INFO    ][25582] Executing state file.directory for /srv/glusterfs/glance
2017-09-15 22:00:11,588 [salt.state       ][INFO    ][25582] {'/srv/glusterfs/glance': 'New Dir'}
2017-09-15 22:00:11,588 [salt.state       ][INFO    ][25582] Completed state [/srv/glusterfs/glance] at time 22:00:11.588354 duration_in_ms=1.286
2017-09-15 22:00:11,589 [salt.state       ][INFO    ][25582] Running state [/srv/glusterfs/keystone-keys] at time 22:00:11.588580
2017-09-15 22:00:11,589 [salt.state       ][INFO    ][25582] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-09-15 22:00:11,589 [salt.state       ][INFO    ][25582] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-09-15 22:00:11,590 [salt.state       ][INFO    ][25582] Completed state [/srv/glusterfs/keystone-keys] at time 22:00:11.589602 duration_in_ms=1.022
2017-09-15 22:00:11,590 [salt.state       ][INFO    ][25582] Running state [/srv/glusterfs/nova_instances] at time 22:00:11.589869
2017-09-15 22:00:11,590 [salt.state       ][INFO    ][25582] Executing state file.directory for /srv/glusterfs/nova_instances
2017-09-15 22:00:11,591 [salt.state       ][INFO    ][25582] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-09-15 22:00:11,591 [salt.state       ][INFO    ][25582] Completed state [/srv/glusterfs/nova_instances] at time 22:00:11.590884 duration_in_ms=1.015
2017-09-15 22:00:11,591 [salt.state       ][INFO    ][25582] Running state [/srv/glusterfs/keystone-credential-keys] at time 22:00:11.591115
2017-09-15 22:00:11,591 [salt.state       ][INFO    ][25582] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-09-15 22:00:11,592 [salt.state       ][INFO    ][25582] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-09-15 22:00:11,592 [salt.state       ][INFO    ][25582] Completed state [/srv/glusterfs/keystone-credential-keys] at time 22:00:11.592134 duration_in_ms=1.019
2017-09-15 22:00:11,593 [salt.minion      ][INFO    ][25582] Returning information for job: 20170915215935755943
2017-09-15 22:00:13,981 [salt.minion      ][INFO    ][23358] User sudo_ubuntu Executing command test.ping with jid 20170915220013970443
2017-09-15 22:00:14,003 [salt.minion      ][INFO    ][30292] Starting a new job with PID 30292
2017-09-15 22:00:14,058 [salt.minion      ][INFO    ][30292] Returning information for job: 20170915220013970443
2017-09-15 22:01:09,177 [salt.minion      ][INFO    ][23358] User sudo_ubuntu Executing command state.sls with jid 20170915220109167800
2017-09-15 22:01:09,202 [salt.minion      ][INFO    ][30514] Starting a new job with PID 30514
2017-09-15 22:01:09,672 [salt.state       ][INFO    ][30514] Loading fresh modules for state activity
2017-09-15 22:01:09,713 [salt.fileclient  ][INFO    ][30514] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-09-15 22:01:09,771 [salt.fileclient  ][INFO    ][30514] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-15 22:01:09,869 [salt.fileclient  ][INFO    ][30514] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-09-15 22:01:09,891 [salt.fileclient  ][INFO    ][30514] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-15 22:01:10,206 [salt.state       ][INFO    ][30514] Running state [attr] at time 22:01:10.205989
2017-09-15 22:01:10,206 [salt.state       ][INFO    ][30514] Executing state pkg.installed for attr
2017-09-15 22:01:10,207 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-15 22:01:10,497 [salt.state       ][INFO    ][30514] Package attr is already installed
2017-09-15 22:01:10,497 [salt.state       ][INFO    ][30514] Completed state [attr] at time 22:01:10.497134 duration_in_ms=291.145
2017-09-15 22:01:10,497 [salt.state       ][INFO    ][30514] Running state [glusterfs-server] at time 22:01:10.497360
2017-09-15 22:01:10,498 [salt.state       ][INFO    ][30514] Executing state pkg.installed for glusterfs-server
2017-09-15 22:01:10,500 [salt.state       ][INFO    ][30514] Package glusterfs-server is already installed
2017-09-15 22:01:10,500 [salt.state       ][INFO    ][30514] Completed state [glusterfs-server] at time 22:01:10.500395 duration_in_ms=3.035
2017-09-15 22:01:10,501 [salt.state       ][INFO    ][30514] Running state [bc] at time 22:01:10.500546
2017-09-15 22:01:10,501 [salt.state       ][INFO    ][30514] Executing state pkg.installed for bc
2017-09-15 22:01:10,503 [salt.state       ][INFO    ][30514] Package bc is already installed
2017-09-15 22:01:10,504 [salt.state       ][INFO    ][30514] Completed state [bc] at time 22:01:10.503520 duration_in_ms=2.974
2017-09-15 22:01:10,504 [salt.state       ][INFO    ][30514] Running state [glusterfs-server] at time 22:01:10.504358
2017-09-15 22:01:10,505 [salt.state       ][INFO    ][30514] Executing state service.running for glusterfs-server
2017-09-15 22:01:10,505 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-15 22:01:10,522 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-09-15 22:01:10,622 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-15 22:01:10,653 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-09-15 22:01:10,669 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-09-15 22:01:10,689 [salt.state       ][INFO    ][30514] The service glusterfs-server is already running
2017-09-15 22:01:10,689 [salt.state       ][INFO    ][30514] Completed state [glusterfs-server] at time 22:01:10.689055 duration_in_ms=184.695
2017-09-15 22:01:10,693 [salt.state       ][INFO    ][30514] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 22:01:10.692480
2017-09-15 22:01:10,693 [salt.state       ][INFO    ][30514] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-09-15 22:01:10,705 [salt.state       ][INFO    ][30514] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-09-15 22:01:10,705 [salt.state       ][INFO    ][30514] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 22:01:10.704930 duration_in_ms=12.45
2017-09-15 22:01:10,705 [salt.state       ][INFO    ][30514] Running state [/srv/glusterfs/glance] at time 22:01:10.705327
2017-09-15 22:01:10,706 [salt.state       ][INFO    ][30514] Executing state file.directory for /srv/glusterfs/glance
2017-09-15 22:01:10,707 [salt.state       ][INFO    ][30514] Directory /srv/glusterfs/glance is in the correct state
2017-09-15 22:01:10,707 [salt.state       ][INFO    ][30514] Completed state [/srv/glusterfs/glance] at time 22:01:10.706916 duration_in_ms=1.589
2017-09-15 22:01:10,707 [salt.state       ][INFO    ][30514] Running state [/srv/glusterfs/keystone-keys] at time 22:01:10.707284
2017-09-15 22:01:10,708 [salt.state       ][INFO    ][30514] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-09-15 22:01:10,708 [salt.state       ][INFO    ][30514] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-09-15 22:01:10,709 [salt.state       ][INFO    ][30514] Completed state [/srv/glusterfs/keystone-keys] at time 22:01:10.708719 duration_in_ms=1.434
2017-09-15 22:01:10,709 [salt.state       ][INFO    ][30514] Running state [/srv/glusterfs/nova_instances] at time 22:01:10.709084
2017-09-15 22:01:10,709 [salt.state       ][INFO    ][30514] Executing state file.directory for /srv/glusterfs/nova_instances
2017-09-15 22:01:10,710 [salt.state       ][INFO    ][30514] Directory /srv/glusterfs/nova_instances is in the correct state
2017-09-15 22:01:10,711 [salt.state       ][INFO    ][30514] Completed state [/srv/glusterfs/nova_instances] at time 22:01:10.710594 duration_in_ms=1.51
2017-09-15 22:01:10,711 [salt.state       ][INFO    ][30514] Running state [/srv/glusterfs/keystone-credential-keys] at time 22:01:10.710935
2017-09-15 22:01:10,711 [salt.state       ][INFO    ][30514] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-09-15 22:01:10,712 [salt.state       ][INFO    ][30514] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-09-15 22:01:10,712 [salt.state       ][INFO    ][30514] Completed state [/srv/glusterfs/keystone-credential-keys] at time 22:01:10.712285 duration_in_ms=1.35
2017-09-15 22:01:10,813 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command ['git', '--version'] in directory '/root'
2017-09-15 22:01:10,924 [salt.state       ][INFO    ][30514] Running state [sleep 5] at time 22:01:10.923475
2017-09-15 22:01:10,924 [salt.state       ][INFO    ][30514] Executing state cmd.wait for sleep 5
2017-09-15 22:01:10,925 [salt.state       ][INFO    ][30514] No changes made for sleep 5
2017-09-15 22:01:10,925 [salt.state       ][INFO    ][30514] Completed state [sleep 5] at time 22:01:10.925182 duration_in_ms=1.717
2017-09-15 22:01:10,926 [salt.state       ][INFO    ][30514] Running state [10.167.4.141] at time 22:01:10.926046
2017-09-15 22:01:10,926 [salt.state       ][INFO    ][30514] Executing state glusterfs.peered for 10.167.4.141
2017-09-15 22:01:10,927 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:10,947 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:11,060 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:11,077 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:11,186 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:11,202 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:11,312 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:11,331 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:11,433 [salt.state       ][INFO    ][30514] Peering with localhost is not needed
2017-09-15 22:01:11,434 [salt.state       ][INFO    ][30514] Completed state [10.167.4.141] at time 22:01:11.433852 duration_in_ms=507.805
2017-09-15 22:01:11,440 [salt.state       ][INFO    ][30514] Running state [10.167.4.142] at time 22:01:11.440108
2017-09-15 22:01:11,441 [salt.state       ][INFO    ][30514] Executing state glusterfs.peered for 10.167.4.142
2017-09-15 22:01:11,442 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:11,465 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:11,575 [salt.state       ][INFO    ][30514] Host 10.167.4.142 already peered
2017-09-15 22:01:11,576 [salt.state       ][INFO    ][30514] Completed state [10.167.4.142] at time 22:01:11.575767 duration_in_ms=135.658
2017-09-15 22:01:11,582 [salt.state       ][INFO    ][30514] Running state [10.167.4.143] at time 22:01:11.581878
2017-09-15 22:01:11,582 [salt.state       ][INFO    ][30514] Executing state glusterfs.peered for 10.167.4.143
2017-09-15 22:01:11,583 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:11,601 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:11,707 [salt.state       ][INFO    ][30514] Host 10.167.4.143 already peered
2017-09-15 22:01:11,708 [salt.state       ][INFO    ][30514] Completed state [10.167.4.143] at time 22:01:11.707587 duration_in_ms=125.708
2017-09-15 22:01:11,710 [salt.state       ][INFO    ][30514] Running state [glance] at time 22:01:11.709560
2017-09-15 22:01:11,710 [salt.state       ][INFO    ][30514] Executing state glusterfs.created for glance
2017-09-15 22:01:11,711 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:11,728 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:11,836 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:11,853 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:11,967 [salt.state       ][INFO    ][30514] Volume glance already exists and is started.
2017-09-15 22:01:11,968 [salt.state       ][INFO    ][30514] Completed state [glance] at time 22:01:11.967587 duration_in_ms=258.026
2017-09-15 22:01:11,973 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 22:01:11.973095
2017-09-15 22:01:11,974 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-09-15 22:01:11,974 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-15 22:01:12,084 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:12,085 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 22:01:12.084495 duration_in_ms=111.398
2017-09-15 22:01:12,086 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 22:01:12.085568
2017-09-15 22:01:12,086 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-09-15 22:01:12,087 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-15 22:01:12,191 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:12,191 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 22:01:12.191166 duration_in_ms=105.597
2017-09-15 22:01:12,192 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 22:01:12.192258
2017-09-15 22:01:12,193 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-09-15 22:01:12,194 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-09-15 22:01:12,297 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:12,297 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 22:01:12.297217 duration_in_ms=104.958
2017-09-15 22:01:12,299 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 22:01:12.298452
2017-09-15 22:01:12,299 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-09-15 22:01:12,300 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-15 22:01:12,397 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:12,397 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 22:01:12.397063 duration_in_ms=98.611
2017-09-15 22:01:12,398 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 22:01:12.398185
2017-09-15 22:01:12,399 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-09-15 22:01:12,400 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-15 22:01:12,509 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:12,509 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 22:01:12.509126 duration_in_ms=110.941
2017-09-15 22:01:12,512 [salt.state       ][INFO    ][30514] Running state [glance] at time 22:01:12.511497
2017-09-15 22:01:12,512 [salt.state       ][INFO    ][30514] Executing state glusterfs.started for glance
2017-09-15 22:01:12,513 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:12,531 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:12,634 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:12,652 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:12,849 [salt.state       ][INFO    ][30514] Volume glance is already started
2017-09-15 22:01:12,850 [salt.state       ][INFO    ][30514] Completed state [glance] at time 22:01:12.849532 duration_in_ms=338.036
2017-09-15 22:01:12,851 [salt.state       ][INFO    ][30514] Running state [keystone-keys] at time 22:01:12.850704
2017-09-15 22:01:12,851 [salt.state       ][INFO    ][30514] Executing state glusterfs.created for keystone-keys
2017-09-15 22:01:12,852 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:12,870 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:12,979 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:12,996 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:13,112 [salt.state       ][INFO    ][30514] Volume keystone-keys already exists and is started.
2017-09-15 22:01:13,112 [salt.state       ][INFO    ][30514] Completed state [keystone-keys] at time 22:01:13.112019 duration_in_ms=261.313
2017-09-15 22:01:13,119 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 22:01:13.119350
2017-09-15 22:01:13,120 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-09-15 22:01:13,121 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-15 22:01:13,230 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:13,231 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 22:01:13.230696 duration_in_ms=111.345
2017-09-15 22:01:13,232 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 22:01:13.231768
2017-09-15 22:01:13,232 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-09-15 22:01:13,233 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-15 22:01:13,343 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:13,343 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 22:01:13.343254 duration_in_ms=111.486
2017-09-15 22:01:13,344 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 22:01:13.344331
2017-09-15 22:01:13,345 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-09-15 22:01:13,346 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-15 22:01:13,455 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:13,456 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 22:01:13.455456 duration_in_ms=111.124
2017-09-15 22:01:13,457 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 22:01:13.456527
2017-09-15 22:01:13,457 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-09-15 22:01:13,458 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-09-15 22:01:13,560 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:13,560 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 22:01:13.560366 duration_in_ms=103.838
2017-09-15 22:01:13,561 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 22:01:13.561389
2017-09-15 22:01:13,562 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-09-15 22:01:13,563 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-15 22:01:13,673 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:13,674 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 22:01:13.673482 duration_in_ms=112.092
2017-09-15 22:01:13,676 [salt.state       ][INFO    ][30514] Running state [keystone-keys] at time 22:01:13.676054
2017-09-15 22:01:13,677 [salt.state       ][INFO    ][30514] Executing state glusterfs.started for keystone-keys
2017-09-15 22:01:13,678 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:13,693 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:13,795 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:13,813 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:13,926 [salt.state       ][INFO    ][30514] Volume keystone-keys is already started
2017-09-15 22:01:13,927 [salt.state       ][INFO    ][30514] Completed state [keystone-keys] at time 22:01:13.926932 duration_in_ms=250.878
2017-09-15 22:01:13,929 [salt.state       ][INFO    ][30514] Running state [nova_instances] at time 22:01:13.928926
2017-09-15 22:01:13,929 [salt.state       ][INFO    ][30514] Executing state glusterfs.created for nova_instances
2017-09-15 22:01:13,930 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:13,949 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:14,057 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:14,075 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:14,200 [salt.state       ][INFO    ][30514] Volume nova_instances already exists and is started.
2017-09-15 22:01:14,200 [salt.state       ][INFO    ][30514] Completed state [nova_instances] at time 22:01:14.200279 duration_in_ms=271.351
2017-09-15 22:01:14,209 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 22:01:14.208478
2017-09-15 22:01:14,209 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-09-15 22:01:14,210 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-15 22:01:14,311 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:14,311 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 22:01:14.311130 duration_in_ms=102.651
2017-09-15 22:01:14,312 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 22:01:14.312226
2017-09-15 22:01:14,313 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'
2017-09-15 22:01:14,314 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-15 22:01:14,423 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:14,424 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 22:01:14.423415 duration_in_ms=111.188
2017-09-15 22:01:14,425 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 22:01:14.424512
2017-09-15 22:01:14,425 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'True'
2017-09-15 22:01:14,426 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-15 22:01:14,533 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:14,533 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 22:01:14.533042 duration_in_ms=108.529
2017-09-15 22:01:14,534 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 22:01:14.534162
2017-09-15 22:01:14,535 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'True'
2017-09-15 22:01:14,536 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: True'' in directory '/root'
2017-09-15 22:01:14,635 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:14,635 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 22:01:14.635384 duration_in_ms=101.222
2017-09-15 22:01:14,637 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 22:01:14.636480
2017-09-15 22:01:14,637 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-09-15 22:01:14,638 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-15 22:01:14,747 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:14,748 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 22:01:14.747571 duration_in_ms=111.09
2017-09-15 22:01:14,750 [salt.state       ][INFO    ][30514] Running state [nova_instances] at time 22:01:14.750132
2017-09-15 22:01:14,751 [salt.state       ][INFO    ][30514] Executing state glusterfs.started for nova_instances
2017-09-15 22:01:14,752 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:14,769 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:14,872 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:14,891 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:15,009 [salt.state       ][INFO    ][30514] Volume nova_instances is already started
2017-09-15 22:01:15,009 [salt.state       ][INFO    ][30514] Completed state [nova_instances] at time 22:01:15.009051 duration_in_ms=258.918
2017-09-15 22:01:15,011 [salt.state       ][INFO    ][30514] Running state [keystone-credential-keys] at time 22:01:15.011121
2017-09-15 22:01:15,012 [salt.state       ][INFO    ][30514] Executing state glusterfs.created for keystone-credential-keys
2017-09-15 22:01:15,013 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:15,031 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:15,138 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:15,156 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:15,267 [salt.state       ][INFO    ][30514] Volume keystone-credential-keys already exists and is started.
2017-09-15 22:01:15,268 [salt.state       ][INFO    ][30514] Completed state [keystone-credential-keys] at time 22:01:15.267709 duration_in_ms=256.586
2017-09-15 22:01:15,276 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 22:01:15.275577
2017-09-15 22:01:15,276 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-09-15 22:01:15,277 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-15 22:01:15,380 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:15,380 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 22:01:15.380366 duration_in_ms=104.788
2017-09-15 22:01:15,382 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 22:01:15.381462
2017-09-15 22:01:15,382 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-09-15 22:01:15,383 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-15 22:01:15,489 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:15,490 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 22:01:15.489543 duration_in_ms=108.081
2017-09-15 22:01:15,491 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 22:01:15.490718
2017-09-15 22:01:15,491 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-09-15 22:01:15,492 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-09-15 22:01:15,596 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:15,597 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 22:01:15.596881 duration_in_ms=106.162
2017-09-15 22:01:15,598 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 22:01:15.597989
2017-09-15 22:01:15,599 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-09-15 22:01:15,600 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-15 22:01:15,711 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:15,711 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 22:01:15.711059 duration_in_ms=113.069
2017-09-15 22:01:15,712 [salt.state       ][INFO    ][30514] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 22:01:15.712108
2017-09-15 22:01:15,713 [salt.state       ][INFO    ][30514] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-09-15 22:01:15,714 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-15 22:01:15,815 [salt.state       ][INFO    ][30514] unless execution succeeded
2017-09-15 22:01:15,816 [salt.state       ][INFO    ][30514] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 22:01:15.815415 duration_in_ms=103.306
2017-09-15 22:01:15,818 [salt.state       ][INFO    ][30514] Running state [keystone-credential-keys] at time 22:01:15.817981
2017-09-15 22:01:15,819 [salt.state       ][INFO    ][30514] Executing state glusterfs.started for keystone-credential-keys
2017-09-15 22:01:15,819 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:15,837 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:15,943 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --version' in directory '/root'
2017-09-15 22:01:15,960 [salt.loaded.int.module.cmdmod][INFO    ][30514] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 22:01:16,071 [salt.state       ][INFO    ][30514] Volume keystone-credential-keys is already started
2017-09-15 22:01:16,072 [salt.state       ][INFO    ][30514] Completed state [keystone-credential-keys] at time 22:01:16.071457 duration_in_ms=253.476
2017-09-15 22:01:16,075 [salt.minion      ][INFO    ][30514] Returning information for job: 20170915220109167800
2017-09-15 22:01:17,203 [salt.minion      ][INFO    ][23358] User sudo_ubuntu Executing command test.ping with jid 20170915220117192620
2017-09-15 22:01:17,225 [salt.minion      ][INFO    ][30915] Starting a new job with PID 30915
2017-09-15 22:01:17,279 [salt.minion      ][INFO    ][30915] Returning information for job: 20170915220117192620
2017-09-15 22:01:18,187 [salt.minion      ][INFO    ][23358] User sudo_ubuntu Executing command cmd.run with jid 20170915220118175721
2017-09-15 22:01:18,209 [salt.minion      ][INFO    ][30920] Starting a new job with PID 30920
2017-09-15 22:01:18,217 [salt.loaded.int.module.cmdmod][INFO    ][30920] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-09-15 22:01:18,437 [salt.minion      ][INFO    ][30920] Returning information for job: 20170915220118175721
2017-09-15 22:32:55,675 [salt.utils.schedule][INFO    ][23358] Running scheduled job: __mine_interval
2017-09-15 23:11:00,168 [salt.minion      ][INFO    ][23358] User sudo_ubuntu Executing command cp.push_dir with jid 20170915231100155312
2017-09-15 23:11:00,201 [salt.minion      ][INFO    ][31922] Starting a new job with PID 31922
