2017-12-10 02:25:44,936 [salt.cli.daemons ][WARNING ][2525] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-12-10 02:44:24,525 [salt.state       ][ERROR   ][3824] No contents found in top file
2017-12-10 02:45:58,365 [salt.utils.parsers][WARNING ][2525] Minion received a SIGTERM. Exiting.
2017-12-10 02:48:23,789 [salt.cli.daemons ][WARNING ][3296] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-12-10 02:48:58,158 [py.warnings      ][WARNING ][3410] /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-12-10 02:49:49,939 [salt.loaded.int.module.cmdmod][ERROR   ][3410] 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-12-10 02:49:57,827 [salt.transport.ipc][ERROR   ][3296] Exception occurred while handling stream: [Errno 0] Success
2017-12-10 02:51:09,995 [salt.loaded.int.module.cmdmod][INFO    ][16527] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-12-10 02:51:10,003 [salt.loaded.int.module.cmdmod][INFO    ][16527] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-12-10 02:51:10,016 [salt.loaded.int.module.cmdmod][INFO    ][16527] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-12-10 02:51:10,024 [salt.utils.parsers][WARNING ][3296] Minion received a SIGTERM. Exiting.
2017-12-10 02:51:10,315 [salt.cli.daemons ][INFO    ][16600] Setting up the Salt Minion "kvm01.baremetal-mcp-ocata-ovs-ha.local"
2017-12-10 02:51:11,072 [salt.minion      ][INFO    ][16600] Creating minion process manager
2017-12-10 02:51:11,072 [salt.cli.daemons ][WARNING ][16600] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-12-10 02:51:11,072 [salt.cli.daemons ][INFO    ][16600] The Salt Minion is starting up
2017-12-10 02:51:11,073 [salt.minion      ][INFO    ][16600] Minion is starting as user 'root'
2017-12-10 02:51:11,073 [salt.utils.event ][INFO    ][16600] Starting pull socket on /var/run/salt/minion/minion_event_fd678c5357_pull.ipc
2017-12-10 02:51:12,009 [salt.loaded.int.module.cmdmod][INFO    ][16600] Executing command ['date', '+%z'] in directory '/root'
2017-12-10 02:51:12,021 [salt.utils.schedule][INFO    ][16600] Updating job settings for scheduled job: __mine_interval
2017-12-10 02:51:12,024 [salt.minion      ][INFO    ][16600] Added mine.update to scheduler
2017-12-10 02:51:12,059 [salt.minion      ][INFO    ][16600] Minion is ready to receive requests!
2017-12-10 02:51:13,059 [salt.utils.schedule][INFO    ][16600] Running scheduled job: __mine_interval
2017-12-10 02:51:22,628 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command state.apply with jid 20171210025122608107
2017-12-10 02:51:22,640 [salt.minion      ][INFO    ][16697] Starting a new job with PID 16697
2017-12-10 02:51:25,065 [salt.state       ][INFO    ][16697] Loading fresh modules for state activity
2017-12-10 02:51:25,085 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/init.sls'
2017-12-10 02:51:25,103 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-12-10 02:51:25,116 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-12-10 02:51:25,138 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-10 02:51:25,192 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-10 02:51:25,226 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-10 02:51:25,269 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-10 02:51:25,293 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-10 02:51:25,305 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-10 02:51:25,320 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-10 02:51:25,327 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-10 02:51:25,338 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-10 02:51:25,360 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-10 02:51:25,375 [py.warnings      ][WARNING ][16697] /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-12-10 02:51:25,375 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'salt-minion --version' in directory '/root'
2017-12-10 02:51:25,600 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'salt-minion --version' in directory '/root'
2017-12-10 02:51:25,875 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-10 02:51:25,895 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-10 02:51:25,906 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-10 02:51:25,918 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-10 02:51:26,006 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-10 02:51:26,019 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-10 02:51:26,039 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-10 02:51:26,051 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-10 02:51:26,068 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-10 02:51:26,099 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-10 02:51:26,151 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-12-10 02:51:26,171 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-10 02:51:26,224 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-10 02:51:26,241 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-10 02:51:26,285 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-10 02:51:26,306 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-10 02:51:26,319 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-10 02:51:26,335 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-10 02:51:26,341 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-10 02:51:26,351 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-10 02:51:26,370 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-10 02:51:26,384 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'salt-minion --version' in directory '/root'
2017-12-10 02:51:26,635 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'salt-minion --version' in directory '/root'
2017-12-10 02:51:26,906 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-10 02:51:26,924 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-10 02:51:26,938 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-10 02:51:26,951 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-10 02:51:26,983 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-10 02:51:26,997 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-10 02:51:27,020 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-10 02:51:27,031 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-10 02:51:27,047 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-10 02:51:27,078 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-10 02:51:27,143 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-12-10 02:51:27,195 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-10 02:51:27,240 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-12-10 02:51:27,253 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-12-10 02:51:27,292 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-10 02:51:27,628 [salt.state       ][INFO    ][16697] Running state [salt-minion] at time 02:51:27.628153
2017-12-10 02:51:27,628 [salt.state       ][INFO    ][16697] Executing state pkg.installed for salt-minion
2017-12-10 02:51:27,629 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-10 02:51:27,878 [salt.state       ][INFO    ][16697] Package salt-minion is already installed
2017-12-10 02:51:27,878 [salt.state       ][INFO    ][16697] Completed state [salt-minion] at time 02:51:27.877731 duration_in_ms=249.578
2017-12-10 02:51:27,878 [salt.state       ][INFO    ][16697] Running state [salt_minion_dependency_packages] at time 02:51:27.877960
2017-12-10 02:51:27,878 [salt.state       ][INFO    ][16697] Executing state pkg.installed for salt_minion_dependency_packages
2017-12-10 02:51:27,881 [salt.state       ][INFO    ][16697] All specified packages are already installed
2017-12-10 02:51:27,881 [salt.state       ][INFO    ][16697] Completed state [salt_minion_dependency_packages] at time 02:51:27.880852 duration_in_ms=2.893
2017-12-10 02:51:27,882 [salt.state       ][INFO    ][16697] Running state [/etc/salt/minion.d/minion.conf] at time 02:51:27.882230
2017-12-10 02:51:27,882 [salt.state       ][INFO    ][16697] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-12-10 02:51:27,904 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-12-10 02:51:27,946 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-10 02:51:27,989 [salt.fileclient  ][INFO    ][16697] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-10 02:51:28,022 [salt.state       ][INFO    ][16697] File /etc/salt/minion.d/minion.conf is in the correct state
2017-12-10 02:51:28,022 [salt.state       ][INFO    ][16697] Completed state [/etc/salt/minion.d/minion.conf] at time 02:51:28.021878 duration_in_ms=139.648
2017-12-10 02:51:28,022 [salt.state       ][INFO    ][16697] Running state [/etc/salt/minion.d/_virt.conf] at time 02:51:28.022148
2017-12-10 02:51:28,022 [salt.state       ][INFO    ][16697] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-12-10 02:51:28,028 [salt.state       ][INFO    ][16697] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-12-10 02:51:28,028 [salt.state       ][INFO    ][16697] Completed state [/etc/salt/minion.d/_virt.conf] at time 02:51:28.027694 duration_in_ms=5.546
2017-12-10 02:51:28,029 [salt.state       ][INFO    ][16697] Running state [salt-minion] at time 02:51:28.029041
2017-12-10 02:51:28,029 [salt.state       ][INFO    ][16697] Executing state service.running for salt-minion
2017-12-10 02:51:28,030 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-12-10 02:51:28,044 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-12-10 02:51:28,052 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-12-10 02:51:28,059 [salt.state       ][INFO    ][16697] The service salt-minion is already running
2017-12-10 02:51:28,059 [salt.state       ][INFO    ][16697] Completed state [salt-minion] at time 02:51:28.059202 duration_in_ms=30.16
2017-12-10 02:51:28,060 [salt.state       ][INFO    ][16697] Running state [/etc/salt/grains.d] at time 02:51:28.060369
2017-12-10 02:51:28,061 [salt.state       ][INFO    ][16697] Executing state file.directory for /etc/salt/grains.d
2017-12-10 02:51:28,061 [salt.state       ][INFO    ][16697] Directory /etc/salt/grains.d is in the correct state
2017-12-10 02:51:28,061 [salt.state       ][INFO    ][16697] Completed state [/etc/salt/grains.d] at time 02:51:28.061209 duration_in_ms=0.84
2017-12-10 02:51:28,062 [salt.state       ][INFO    ][16697] Running state [/etc/salt/grains] at time 02:51:28.061610
2017-12-10 02:51:28,062 [salt.state       ][INFO    ][16697] Executing state file.managed for /etc/salt/grains
2017-12-10 02:51:28,062 [salt.state       ][INFO    ][16697] File /etc/salt/grains exists with proper permissions. No changes made.
2017-12-10 02:51:28,062 [salt.state       ][INFO    ][16697] Completed state [/etc/salt/grains] at time 02:51:28.062214 duration_in_ms=0.603
2017-12-10 02:51:28,062 [salt.state       ][INFO    ][16697] Running state [/etc/salt/grains.d/placeholder] at time 02:51:28.062474
2017-12-10 02:51:28,063 [salt.state       ][INFO    ][16697] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-12-10 02:51:28,063 [salt.state       ][INFO    ][16697] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-12-10 02:51:28,063 [salt.state       ][INFO    ][16697] Completed state [/etc/salt/grains.d/placeholder] at time 02:51:28.063065 duration_in_ms=0.591
2017-12-10 02:51:28,063 [salt.state       ][INFO    ][16697] Running state [/etc/salt/grains.d/sphinx] at time 02:51:28.063326
2017-12-10 02:51:28,064 [salt.state       ][INFO    ][16697] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-12-10 02:51:28,071 [salt.state       ][INFO    ][16697] File /etc/salt/grains.d/sphinx is in the correct state
2017-12-10 02:51:28,071 [salt.state       ][INFO    ][16697] Completed state [/etc/salt/grains.d/sphinx] at time 02:51:28.071074 duration_in_ms=7.749
2017-12-10 02:51:28,072 [salt.state       ][INFO    ][16697] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:51:28.071596
2017-12-10 02:51:28,072 [salt.state       ][INFO    ][16697] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-10 02:51:28,072 [salt.state       ][INFO    ][16697] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-10 02:51:28,072 [salt.state       ][INFO    ][16697] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:51:28.072081 duration_in_ms=0.485
2017-12-10 02:51:28,072 [salt.state       ][INFO    ][16697] Running state [/etc/salt/grains.d/dns_records] at time 02:51:28.072340
2017-12-10 02:51:28,073 [salt.state       ][INFO    ][16697] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-12-10 02:51:28,077 [salt.state       ][INFO    ][16697] File /etc/salt/grains.d/dns_records is in the correct state
2017-12-10 02:51:28,077 [salt.state       ][INFO    ][16697] Completed state [/etc/salt/grains.d/dns_records] at time 02:51:28.077059 duration_in_ms=4.719
2017-12-10 02:51:28,078 [salt.state       ][INFO    ][16697] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:51:28.077577
2017-12-10 02:51:28,078 [salt.state       ][INFO    ][16697] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-12-10 02:51:28,078 [salt.state       ][INFO    ][16697] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-12-10 02:51:28,078 [salt.state       ][INFO    ][16697] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:51:28.078051 duration_in_ms=0.473
2017-12-10 02:51:28,078 [salt.state       ][INFO    ][16697] Running state [/etc/salt/grains.d/salt] at time 02:51:28.078312
2017-12-10 02:51:28,078 [salt.state       ][INFO    ][16697] Executing state file.managed for /etc/salt/grains.d/salt
2017-12-10 02:51:28,083 [salt.state       ][INFO    ][16697] File /etc/salt/grains.d/salt is in the correct state
2017-12-10 02:51:28,083 [salt.state       ][INFO    ][16697] Completed state [/etc/salt/grains.d/salt] at time 02:51:28.083081 duration_in_ms=4.768
2017-12-10 02:51:28,084 [salt.state       ][INFO    ][16697] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:51:28.083581
2017-12-10 02:51:28,084 [salt.state       ][INFO    ][16697] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-10 02:51:28,084 [salt.state       ][INFO    ][16697] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-10 02:51:28,084 [salt.state       ][INFO    ][16697] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:51:28.084047 duration_in_ms=0.466
2017-12-10 02:51:28,085 [salt.state       ][INFO    ][16697] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:51:28.085165
2017-12-10 02:51:28,085 [salt.state       ][INFO    ][16697] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-10 02:51:28,086 [salt.state       ][INFO    ][16697] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-10 02:51:28,086 [salt.state       ][INFO    ][16697] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:51:28.085644 duration_in_ms=0.479
2017-12-10 02:51:28,086 [salt.state       ][INFO    ][16697] Running state [mine.update] at time 02:51:28.086021
2017-12-10 02:51:28,086 [salt.state       ][INFO    ][16697] Executing state module.wait for mine.update
2017-12-10 02:51:28,086 [salt.state       ][INFO    ][16697] No changes made for mine.update
2017-12-10 02:51:28,086 [salt.state       ][INFO    ][16697] Completed state [mine.update] at time 02:51:28.086450 duration_in_ms=0.43
2017-12-10 02:51:28,087 [salt.state       ][INFO    ][16697] Running state [ca-certificates] at time 02:51:28.086592
2017-12-10 02:51:28,087 [salt.state       ][INFO    ][16697] Executing state pkg.installed for ca-certificates
2017-12-10 02:51:28,090 [salt.state       ][INFO    ][16697] Package ca-certificates is already installed
2017-12-10 02:51:28,090 [salt.state       ][INFO    ][16697] Completed state [ca-certificates] at time 02:51:28.089681 duration_in_ms=3.089
2017-12-10 02:51:28,090 [salt.state       ][INFO    ][16697] Running state [update-ca-certificates] at time 02:51:28.090072
2017-12-10 02:51:28,090 [salt.state       ][INFO    ][16697] Executing state cmd.wait for update-ca-certificates
2017-12-10 02:51:28,090 [salt.state       ][INFO    ][16697] No changes made for update-ca-certificates
2017-12-10 02:51:28,091 [salt.state       ][INFO    ][16697] Completed state [update-ca-certificates] at time 02:51:28.090524 duration_in_ms=0.453
2017-12-10 02:51:28,091 [salt.state       ][INFO    ][16697] Running state [libvirt-dev] at time 02:51:28.090665
2017-12-10 02:51:28,091 [salt.state       ][INFO    ][16697] Executing state pkg.installed for libvirt-dev
2017-12-10 02:51:28,098 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-12-10 02:51:29,768 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'libvirt-dev'] in directory '/root'
2017-12-10 02:51:32,729 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210025132710747
2017-12-10 02:51:32,742 [salt.minion      ][INFO    ][17171] Starting a new job with PID 17171
2017-12-10 02:51:32,753 [salt.minion      ][INFO    ][17171] Returning information for job: 20171210025132710747
2017-12-10 02:51:42,914 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210025142903333
2017-12-10 02:51:42,931 [salt.minion      ][INFO    ][17306] Starting a new job with PID 17306
2017-12-10 02:51:42,949 [salt.minion      ][INFO    ][17306] Returning information for job: 20171210025142903333
2017-12-10 02:51:53,109 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210025153102651
2017-12-10 02:51:53,123 [salt.minion      ][INFO    ][17493] Starting a new job with PID 17493
2017-12-10 02:51:53,133 [salt.minion      ][INFO    ][17493] Returning information for job: 20171210025153102651
2017-12-10 02:52:03,305 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210025203300393
2017-12-10 02:52:03,319 [salt.minion      ][INFO    ][22302] Starting a new job with PID 22302
2017-12-10 02:52:03,329 [salt.minion      ][INFO    ][22302] Returning information for job: 20171210025203300393
2017-12-10 02:52:05,630 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-10 02:52:05,653 [salt.state       ][INFO    ][16697] Made the following changes:
'build-essential' changed from 'absent' to '12.1ubuntu2'
'libgomp1' changed from 'absent' to '5.4.0-6ubuntu1~16.04.5'
'c++-compiler' changed from 'absent' to '1'
'cpp' changed from 'absent' to '4:5.3.1-1ubuntu1'
'libalgorithm-merge-perl' changed from 'absent' to '0.08-3'
'binutils-gold' changed from 'absent' to '1'
'libfakeroot' changed from 'absent' to '1.20.2-1ubuntu1'
'linux-libc-dev' changed from 'absent' to '4.4.0-103.126'
'gcc-5' changed from 'absent' to '5.4.0-6ubuntu1~16.04.5'
'libubsan0' changed from 'absent' to '5.4.0-6ubuntu1~16.04.5'
'libalgorithm-diff-xs-perl' changed from 'absent' to '0.04-4build1'
'gcc' changed from 'absent' to '4:5.3.1-1ubuntu1'
'make' changed from 'absent' to '4.1-6'
'libitm1' changed from 'absent' to '5.4.0-6ubuntu1~16.04.5'
'g++' changed from 'absent' to '4:5.3.1-1ubuntu1'
'libfile-fcntllock-perl' changed from 'absent' to '0.22-3'
'c-compiler' changed from 'absent' to '1'
'libquadmath0' changed from 'absent' to '5.4.0-6ubuntu1~16.04.5'
'cpp:any' changed from 'absent' to '1'
'libmpc3' changed from 'absent' to '1.0.3-1'
'dpkg-dev' changed from 'absent' to '1.18.4ubuntu1.3'
'libmpx0' changed from 'absent' to '5.4.0-6ubuntu1~16.04.5'
'libstdc++-5-dev' changed from 'absent' to '5.4.0-6ubuntu1~16.04.5'
'g++-5' changed from 'absent' to '5.4.0-6ubuntu1~16.04.5'
'libc-dev' changed from 'absent' to '1'
'make:any' changed from 'absent' to '1'
'libatomic1' changed from 'absent' to '5.4.0-6ubuntu1~16.04.5'
'libtsan0' changed from 'absent' to '5.4.0-6ubuntu1~16.04.5'
'libasan2' changed from 'absent' to '5.4.0-6ubuntu1~16.04.5'
'libvirt-dev' changed from 'absent' to '1.3.1-1ubuntu10.1~xenial1+contrail1'
'libstdc++-dev' changed from 'absent' to '1'
'libc6-dev' changed from 'absent' to '2.23-0ubuntu9'
'libdpkg-perl' changed from 'absent' to '1.18.4ubuntu1.3'
'libcilkrts5' changed from 'absent' to '5.4.0-6ubuntu1~16.04.5'
'elf-binutils' changed from 'absent' to '1'
'libxen-dev' changed from 'absent' to '4.6.5-0ubuntu1.4'
'manpages-dev' changed from 'absent' to '4.04-2'
'cpp-5' changed from 'absent' to '5.4.0-6ubuntu1~16.04.5'
'c++abi2-dev' changed from 'absent' to '1'
'libisl15' changed from 'absent' to '0.16.1-1'
'linux-kernel-headers' changed from 'absent' to '1'
'liblsan0' changed from 'absent' to '5.4.0-6ubuntu1~16.04.5'
'fakeroot' changed from 'absent' to '1.20.2-1ubuntu1'
'libc-dev-bin' changed from 'absent' to '2.23-0ubuntu9'
'libgcc-5-dev' changed from 'absent' to '5.4.0-6ubuntu1~16.04.5'
'libcc1-0' changed from 'absent' to '5.4.0-6ubuntu1~16.04.5'
'libalgorithm-diff-perl' changed from 'absent' to '1.19.03-1'
'binutils' changed from 'absent' to '2.26.1-1ubuntu1~16.04.5'
'pkg-config' changed from 'absent' to '0.29.1-0ubuntu1'

2017-12-10 02:52:05,661 [salt.state       ][INFO    ][16697] Loading fresh modules for state activity
2017-12-10 02:52:05,673 [salt.state       ][INFO    ][16697] Completed state [libvirt-dev] at time 02:52:05.672886 duration_in_ms=37582.22
2017-12-10 02:52:05,678 [salt.state       ][INFO    ][16697] Running state [pkg-config] at time 02:52:05.677498
2017-12-10 02:52:05,678 [salt.state       ][INFO    ][16697] Executing state pkg.installed for pkg-config
2017-12-10 02:52:05,900 [salt.state       ][INFO    ][16697] Package pkg-config is already installed
2017-12-10 02:52:05,900 [salt.state       ][INFO    ][16697] Completed state [pkg-config] at time 02:52:05.899792 duration_in_ms=222.292
2017-12-10 02:52:05,900 [salt.state       ][INFO    ][16697] Running state [virtng.init] at time 02:52:05.900148
2017-12-10 02:52:05,900 [salt.state       ][INFO    ][16697] Executing state module.run for virtng.init
2017-12-10 02:52:05,938 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command ['git', '--version'] in directory '/root'
2017-12-10 02:52:05,975 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'virsh list --all --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:52:13,507 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210025213503166
2017-12-10 02:52:13,521 [salt.minion      ][INFO    ][22382] Starting a new job with PID 22382
2017-12-10 02:52:13,531 [salt.minion      ][INFO    ][22382] Returning information for job: 20171210025213503166
2017-12-10 02:52:23,706 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210025223700763
2017-12-10 02:52:23,720 [salt.minion      ][INFO    ][22387] Starting a new job with PID 22387
2017-12-10 02:52:23,730 [salt.minion      ][INFO    ][22387] Returning information for job: 20171210025223700763
2017-12-10 02:52:33,907 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210025233903402
2017-12-10 02:52:33,921 [salt.minion      ][INFO    ][22392] Starting a new job with PID 22392
2017-12-10 02:52:33,932 [salt.minion      ][INFO    ][22392] Returning information for job: 20171210025233903402
2017-12-10 02:52:44,111 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210025244107705
2017-12-10 02:52:44,125 [salt.minion      ][INFO    ][22407] Starting a new job with PID 22407
2017-12-10 02:52:44,136 [salt.minion      ][INFO    ][22407] Returning information for job: 20171210025244107705
2017-12-10 02:52:54,313 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210025254309611
2017-12-10 02:52:54,327 [salt.minion      ][INFO    ][22412] Starting a new job with PID 22412
2017-12-10 02:52:54,337 [salt.minion      ][INFO    ][22412] Returning information for job: 20171210025254309611
2017-12-10 02:53:04,512 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210025304508994
2017-12-10 02:53:04,527 [salt.minion      ][INFO    ][22417] Starting a new job with PID 22417
2017-12-10 02:53:04,538 [salt.minion      ][INFO    ][22417] Returning information for job: 20171210025304508994
2017-12-10 02:53:14,721 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210025314718195
2017-12-10 02:53:14,736 [salt.minion      ][INFO    ][22422] Starting a new job with PID 22422
2017-12-10 02:53:14,745 [salt.minion      ][INFO    ][22422] Returning information for job: 20171210025314718195
2017-12-10 02:53:21,197 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-12-10 02:53:21,219 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-10 02:53:21,280 [salt.loaded.int.module.cmdmod][ERROR   ][16697] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-10 02:53:21,281 [salt.loaded.int.module.cmdmod][ERROR   ][16697] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-10 02:53:21,281 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'qemu-nbd -c /dev/nbd9 /var/lib/libvirt/images/prx01.baremetal-mcp-ocata-ovs-ha.local/system.qcow2' in directory '/root'
2017-12-10 02:53:21,296 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-10 02:53:21,303 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-12-10 02:53:22,354 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-12-10 02:53:22,986 [salt.loaded.ext.module.seedng][INFO    ][16697] salt-minion pre-installed on image, configuring as prx01.baremetal-mcp-ocata-ovs-ha.local
2017-12-10 02:53:22,991 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'mount -l' in directory '/root'
2017-12-10 02:53:23,050 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-12-10 02:53:24,157 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-12-10 02:53:24,930 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210025324927174
2017-12-10 02:53:24,940 [salt.minion      ][INFO    ][22686] Starting a new job with PID 22686
2017-12-10 02:53:24,948 [salt.minion      ][INFO    ][22686] Returning information for job: 20171210025324927174
2017-12-10 02:53:26,108 [salt.state       ][INFO    ][16697] {'ret': True}
2017-12-10 02:53:26,108 [salt.state       ][INFO    ][16697] Completed state [virtng.init] at time 02:53:26.108382 duration_in_ms=80208.234
2017-12-10 02:53:26,109 [salt.state       ][INFO    ][16697] Running state [virt.set_autostart] at time 02:53:26.108687
2017-12-10 02:53:26,109 [salt.state       ][INFO    ][16697] Executing state module.run for virt.set_autostart
2017-12-10 02:53:26,111 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'virsh list --autostart --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:53:26,128 [salt.state       ][INFO    ][16697] {'ret': True}
2017-12-10 02:53:26,128 [salt.state       ][INFO    ][16697] Completed state [virt.set_autostart] at time 02:53:26.127894 duration_in_ms=19.207
2017-12-10 02:53:26,128 [salt.state       ][INFO    ][16697] Running state [virtng.init] at time 02:53:26.128074
2017-12-10 02:53:26,128 [salt.state       ][INFO    ][16697] Executing state module.run for virtng.init
2017-12-10 02:53:26,130 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'virsh list --all --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:53:28,903 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-12-10 02:53:28,911 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-10 02:53:28,917 [salt.loaded.int.module.cmdmod][ERROR   ][16697] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-10 02:53:28,917 [salt.loaded.int.module.cmdmod][ERROR   ][16697] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-10 02:53:28,917 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'qemu-nbd -c /dev/nbd9 /var/lib/libvirt/images/mdb01.baremetal-mcp-ocata-ovs-ha.local/system.qcow2' in directory '/root'
2017-12-10 02:53:28,931 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-10 02:53:28,938 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-12-10 02:53:29,969 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-12-10 02:53:30,499 [salt.loaded.ext.module.seedng][INFO    ][16697] salt-minion pre-installed on image, configuring as mdb01.baremetal-mcp-ocata-ovs-ha.local
2017-12-10 02:53:30,504 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'mount -l' in directory '/root'
2017-12-10 02:53:30,714 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-12-10 02:53:33,116 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-12-10 02:53:34,518 [salt.state       ][INFO    ][16697] {'ret': True}
2017-12-10 02:53:34,518 [salt.state       ][INFO    ][16697] Completed state [virtng.init] at time 02:53:34.518367 duration_in_ms=8390.293
2017-12-10 02:53:34,519 [salt.state       ][INFO    ][16697] Running state [virt.set_autostart] at time 02:53:34.518651
2017-12-10 02:53:34,519 [salt.state       ][INFO    ][16697] Executing state module.run for virt.set_autostart
2017-12-10 02:53:34,521 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'virsh list --autostart --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:53:34,541 [salt.state       ][INFO    ][16697] {'ret': True}
2017-12-10 02:53:34,542 [salt.state       ][INFO    ][16697] Completed state [virt.set_autostart] at time 02:53:34.541618 duration_in_ms=22.967
2017-12-10 02:53:34,542 [salt.state       ][INFO    ][16697] Running state [virtng.init] at time 02:53:34.541862
2017-12-10 02:53:34,542 [salt.state       ][INFO    ][16697] Executing state module.run for virtng.init
2017-12-10 02:53:34,544 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'virsh list --all --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:53:35,130 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210025335127816
2017-12-10 02:53:35,142 [salt.minion      ][INFO    ][23145] Starting a new job with PID 23145
2017-12-10 02:53:35,151 [salt.minion      ][INFO    ][23145] Returning information for job: 20171210025335127816
2017-12-10 02:53:36,923 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-12-10 02:53:36,931 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-10 02:53:36,937 [salt.loaded.int.module.cmdmod][ERROR   ][16697] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-10 02:53:36,937 [salt.loaded.int.module.cmdmod][ERROR   ][16697] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-10 02:53:36,937 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'qemu-nbd -c /dev/nbd9 /var/lib/libvirt/images/ctl01.baremetal-mcp-ocata-ovs-ha.local/system.qcow2' in directory '/root'
2017-12-10 02:53:36,950 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-10 02:53:36,957 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-12-10 02:53:37,997 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-12-10 02:53:38,600 [salt.loaded.ext.module.seedng][INFO    ][16697] salt-minion pre-installed on image, configuring as ctl01.baremetal-mcp-ocata-ovs-ha.local
2017-12-10 02:53:38,605 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'mount -l' in directory '/root'
2017-12-10 02:53:38,808 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-12-10 02:53:41,658 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-12-10 02:53:43,130 [salt.state       ][INFO    ][16697] {'ret': True}
2017-12-10 02:53:43,130 [salt.state       ][INFO    ][16697] Completed state [virtng.init] at time 02:53:43.130238 duration_in_ms=8588.375
2017-12-10 02:53:43,131 [salt.state       ][INFO    ][16697] Running state [virt.set_autostart] at time 02:53:43.130526
2017-12-10 02:53:43,131 [salt.state       ][INFO    ][16697] Executing state module.run for virt.set_autostart
2017-12-10 02:53:43,133 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'virsh list --autostart --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:53:43,151 [salt.state       ][INFO    ][16697] {'ret': True}
2017-12-10 02:53:43,151 [salt.state       ][INFO    ][16697] Completed state [virt.set_autostart] at time 02:53:43.151351 duration_in_ms=20.824
2017-12-10 02:53:43,152 [salt.state       ][INFO    ][16697] Running state [virtng.init] at time 02:53:43.151617
2017-12-10 02:53:43,152 [salt.state       ][INFO    ][16697] Executing state module.run for virtng.init
2017-12-10 02:53:43,154 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'virsh list --all --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:53:45,340 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210025345338421
2017-12-10 02:53:45,350 [salt.minion      ][INFO    ][23408] Starting a new job with PID 23408
2017-12-10 02:53:45,380 [salt.minion      ][INFO    ][23408] Returning information for job: 20171210025345338421
2017-12-10 02:53:45,508 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-12-10 02:53:45,515 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-10 02:53:45,520 [salt.loaded.int.module.cmdmod][ERROR   ][16697] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-10 02:53:45,521 [salt.loaded.int.module.cmdmod][ERROR   ][16697] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-10 02:53:45,521 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'qemu-nbd -c /dev/nbd9 /var/lib/libvirt/images/msg01.baremetal-mcp-ocata-ovs-ha.local/system.qcow2' in directory '/root'
2017-12-10 02:53:45,535 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-10 02:53:45,542 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-12-10 02:53:46,597 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-12-10 02:53:47,166 [salt.loaded.ext.module.seedng][INFO    ][16697] salt-minion pre-installed on image, configuring as msg01.baremetal-mcp-ocata-ovs-ha.local
2017-12-10 02:53:47,171 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'mount -l' in directory '/root'
2017-12-10 02:53:47,319 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-12-10 02:53:50,420 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-12-10 02:53:51,813 [salt.state       ][INFO    ][16697] {'ret': True}
2017-12-10 02:53:51,813 [salt.state       ][INFO    ][16697] Completed state [virtng.init] at time 02:53:51.812994 duration_in_ms=8661.376
2017-12-10 02:53:51,813 [salt.state       ][INFO    ][16697] Running state [virt.set_autostart] at time 02:53:51.813329
2017-12-10 02:53:51,814 [salt.state       ][INFO    ][16697] Executing state module.run for virt.set_autostart
2017-12-10 02:53:51,816 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'virsh list --autostart --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:53:51,834 [salt.state       ][INFO    ][16697] {'ret': True}
2017-12-10 02:53:51,834 [salt.state       ][INFO    ][16697] Completed state [virt.set_autostart] at time 02:53:51.834329 duration_in_ms=21.0
2017-12-10 02:53:51,835 [salt.state       ][INFO    ][16697] Running state [virtng.init] at time 02:53:51.834521
2017-12-10 02:53:51,835 [salt.state       ][INFO    ][16697] Executing state module.run for virtng.init
2017-12-10 02:53:51,837 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'virsh list --all --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:53:54,213 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-12-10 02:53:54,221 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-10 02:53:54,227 [salt.loaded.int.module.cmdmod][ERROR   ][16697] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-10 02:53:54,227 [salt.loaded.int.module.cmdmod][ERROR   ][16697] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-10 02:53:54,228 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'qemu-nbd -c /dev/nbd9 /var/lib/libvirt/images/dbs01.baremetal-mcp-ocata-ovs-ha.local/system.qcow2' in directory '/root'
2017-12-10 02:53:54,242 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-10 02:53:54,250 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-12-10 02:53:55,293 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-12-10 02:53:55,565 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210025355562353
2017-12-10 02:53:55,577 [salt.minion      ][INFO    ][23700] Starting a new job with PID 23700
2017-12-10 02:53:55,587 [salt.minion      ][INFO    ][23700] Returning information for job: 20171210025355562353
2017-12-10 02:53:55,903 [salt.loaded.ext.module.seedng][INFO    ][16697] salt-minion pre-installed on image, configuring as dbs01.baremetal-mcp-ocata-ovs-ha.local
2017-12-10 02:53:55,908 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'mount -l' in directory '/root'
2017-12-10 02:53:55,992 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-12-10 02:53:59,425 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-12-10 02:54:00,829 [salt.state       ][INFO    ][16697] {'ret': True}
2017-12-10 02:54:00,830 [salt.state       ][INFO    ][16697] Completed state [virtng.init] at time 02:54:00.829613 duration_in_ms=8995.092
2017-12-10 02:54:00,830 [salt.state       ][INFO    ][16697] Running state [virt.set_autostart] at time 02:54:00.829845
2017-12-10 02:54:00,830 [salt.state       ][INFO    ][16697] Executing state module.run for virt.set_autostart
2017-12-10 02:54:00,832 [salt.loaded.int.module.cmdmod][INFO    ][16697] Executing command 'virsh list --autostart --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:54:00,849 [salt.state       ][INFO    ][16697] {'ret': True}
2017-12-10 02:54:00,849 [salt.state       ][INFO    ][16697] Completed state [virt.set_autostart] at time 02:54:00.849337 duration_in_ms=19.491
2017-12-10 02:54:00,852 [salt.minion      ][INFO    ][16697] Returning information for job: 20171210025122608107
2017-12-10 02:54:23,125 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command state.sls with jid 20171210025423123487
2017-12-10 02:54:23,140 [salt.minion      ][INFO    ][23932] Starting a new job with PID 23932
2017-12-10 02:54:25,571 [salt.state       ][INFO    ][23932] Loading fresh modules for state activity
2017-12-10 02:54:25,593 [salt.fileclient  ][INFO    ][23932] Fetching file from saltenv 'base', ** done ** 'libvirt/init.sls'
2017-12-10 02:54:25,638 [salt.fileclient  ][INFO    ][23932] Fetching file from saltenv 'base', ** done ** 'libvirt/server/init.sls'
2017-12-10 02:54:25,653 [salt.fileclient  ][INFO    ][23932] Fetching file from saltenv 'base', ** done ** 'libvirt/server/service.sls'
2017-12-10 02:54:25,672 [salt.fileclient  ][INFO    ][23932] Fetching file from saltenv 'base', ** done ** 'libvirt/map.jinja'
2017-12-10 02:54:25,696 [salt.fileclient  ][INFO    ][23932] Fetching file from saltenv 'base', ** done ** 'libvirt/server/network.sls'
2017-12-10 02:54:25,719 [salt.fileclient  ][INFO    ][23932] Fetching file from saltenv 'base', ** done ** 'libvirt/map.jinja'
2017-12-10 02:54:25,736 [salt.fileclient  ][INFO    ][23932] Fetching file from saltenv 'base', ** done ** 'libvirt/server/pool.sls'
2017-12-10 02:54:25,797 [salt.fileclient  ][INFO    ][23932] Fetching file from saltenv 'base', ** done ** 'libvirt/map.jinja'
2017-12-10 02:54:26,053 [salt.state       ][INFO    ][23932] Running state [libvirt0] at time 02:54:26.052481
2017-12-10 02:54:26,053 [salt.state       ][INFO    ][23932] Executing state pkg.installed for libvirt0
2017-12-10 02:54:26,053 [salt.loaded.int.module.cmdmod][INFO    ][23932] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-10 02:54:26,306 [salt.state       ][INFO    ][23932] Package libvirt0 is already installed
2017-12-10 02:54:26,306 [salt.state       ][INFO    ][23932] Completed state [libvirt0] at time 02:54:26.306405 duration_in_ms=253.925
2017-12-10 02:54:26,307 [salt.state       ][INFO    ][23932] Running state [pm-utils] at time 02:54:26.306622
2017-12-10 02:54:26,307 [salt.state       ][INFO    ][23932] Executing state pkg.installed for pm-utils
2017-12-10 02:54:26,309 [salt.state       ][INFO    ][23932] Package pm-utils is already installed
2017-12-10 02:54:26,310 [salt.state       ][INFO    ][23932] Completed state [pm-utils] at time 02:54:26.309523 duration_in_ms=2.901
2017-12-10 02:54:26,310 [salt.state       ][INFO    ][23932] Running state [python-libvirt] at time 02:54:26.309668
2017-12-10 02:54:26,310 [salt.state       ][INFO    ][23932] Executing state pkg.installed for python-libvirt
2017-12-10 02:54:26,312 [salt.state       ][INFO    ][23932] Package python-libvirt is already installed
2017-12-10 02:54:26,312 [salt.state       ][INFO    ][23932] Completed state [python-libvirt] at time 02:54:26.312443 duration_in_ms=2.775
2017-12-10 02:54:26,313 [salt.state       ][INFO    ][23932] Running state [libvirt-bin] at time 02:54:26.312597
2017-12-10 02:54:26,313 [salt.state       ][INFO    ][23932] Executing state pkg.installed for libvirt-bin
2017-12-10 02:54:26,315 [salt.state       ][INFO    ][23932] Package libvirt-bin is already installed
2017-12-10 02:54:26,315 [salt.state       ][INFO    ][23932] Completed state [libvirt-bin] at time 02:54:26.315399 duration_in_ms=2.802
2017-12-10 02:54:26,316 [salt.state       ][INFO    ][23932] Running state [qemu-utils] at time 02:54:26.315540
2017-12-10 02:54:26,316 [salt.state       ][INFO    ][23932] Executing state pkg.installed for qemu-utils
2017-12-10 02:54:26,318 [salt.state       ][INFO    ][23932] Package qemu-utils is already installed
2017-12-10 02:54:26,318 [salt.state       ][INFO    ][23932] Completed state [qemu-utils] at time 02:54:26.318340 duration_in_ms=2.8
2017-12-10 02:54:26,318 [salt.state       ][INFO    ][23932] Running state [qemu-kvm] at time 02:54:26.318474
2017-12-10 02:54:26,319 [salt.state       ][INFO    ][23932] Executing state pkg.installed for qemu-kvm
2017-12-10 02:54:26,321 [salt.state       ][INFO    ][23932] Package qemu-kvm is already installed
2017-12-10 02:54:26,321 [salt.state       ][INFO    ][23932] Completed state [qemu-kvm] at time 02:54:26.321300 duration_in_ms=2.826
2017-12-10 02:54:26,323 [salt.state       ][INFO    ][23932] Running state [/etc/libvirt/libvirtd.conf] at time 02:54:26.322666
2017-12-10 02:54:26,323 [salt.state       ][INFO    ][23932] Executing state file.managed for /etc/libvirt/libvirtd.conf
2017-12-10 02:54:26,347 [salt.fileclient  ][INFO    ][23932] Fetching file from saltenv 'base', ** done ** 'libvirt/files/libvirtd.conf.Debian'
2017-12-10 02:54:26,352 [salt.state       ][INFO    ][23932] File /etc/libvirt/libvirtd.conf is in the correct state
2017-12-10 02:54:26,352 [salt.state       ][INFO    ][23932] Completed state [/etc/libvirt/libvirtd.conf] at time 02:54:26.351980 duration_in_ms=29.314
2017-12-10 02:54:26,352 [salt.state       ][INFO    ][23932] Running state [/etc/default/libvirt-bin] at time 02:54:26.352193
2017-12-10 02:54:26,352 [salt.state       ][INFO    ][23932] Executing state file.managed for /etc/default/libvirt-bin
2017-12-10 02:54:26,369 [salt.fileclient  ][INFO    ][23932] Fetching file from saltenv 'base', ** done ** 'libvirt/files/libvirt-bin'
2017-12-10 02:54:26,370 [salt.state       ][INFO    ][23932] File /etc/default/libvirt-bin is in the correct state
2017-12-10 02:54:26,371 [salt.state       ][INFO    ][23932] Completed state [/etc/default/libvirt-bin] at time 02:54:26.370574 duration_in_ms=18.382
2017-12-10 02:54:26,371 [salt.state       ][INFO    ][23932] Running state [service.systemctl_reload] at time 02:54:26.371095
2017-12-10 02:54:26,371 [salt.state       ][INFO    ][23932] Executing state module.wait for service.systemctl_reload
2017-12-10 02:54:26,371 [salt.state       ][INFO    ][23932] No changes made for service.systemctl_reload
2017-12-10 02:54:26,372 [salt.state       ][INFO    ][23932] Completed state [service.systemctl_reload] at time 02:54:26.371517 duration_in_ms=0.423
2017-12-10 02:54:26,373 [salt.state       ][INFO    ][23932] Running state [/etc/libvirt/qemu/networks/default.xml] at time 02:54:26.372745
2017-12-10 02:54:26,373 [salt.state       ][INFO    ][23932] Executing state file.absent for /etc/libvirt/qemu/networks/default.xml
2017-12-10 02:54:26,373 [salt.state       ][INFO    ][23932] File /etc/libvirt/qemu/networks/default.xml is not present
2017-12-10 02:54:26,373 [salt.state       ][INFO    ][23932] Completed state [/etc/libvirt/qemu/networks/default.xml] at time 02:54:26.373233 duration_in_ms=0.488
2017-12-10 02:54:26,374 [salt.state       ][INFO    ][23932] Running state [libvirt-bin] at time 02:54:26.373534
2017-12-10 02:54:26,374 [salt.state       ][INFO    ][23932] Executing state service.running for libvirt-bin
2017-12-10 02:54:26,374 [salt.loaded.int.module.cmdmod][INFO    ][23932] Executing command ['systemctl', 'status', 'libvirt-bin.service', '-n', '0'] in directory '/root'
2017-12-10 02:54:26,391 [salt.loaded.int.module.cmdmod][INFO    ][23932] Executing command ['systemctl', 'is-active', 'libvirt-bin.service'] in directory '/root'
2017-12-10 02:54:26,399 [salt.loaded.int.module.cmdmod][INFO    ][23932] Executing command ['systemctl', 'is-enabled', 'libvirt-bin.service'] in directory '/root'
2017-12-10 02:54:26,406 [salt.state       ][INFO    ][23932] The service libvirt-bin is already running
2017-12-10 02:54:26,406 [salt.state       ][INFO    ][23932] Completed state [libvirt-bin] at time 02:54:26.406423 duration_in_ms=32.888
2017-12-10 02:54:26,407 [salt.state       ][INFO    ][23932] Running state [virsh net-undefine default;virsh net-destroy default 2>&1 1>/dev/null] at time 02:54:26.407374
2017-12-10 02:54:26,408 [salt.state       ][INFO    ][23932] Executing state cmd.run for virsh net-undefine default;virsh net-destroy default 2>&1 1>/dev/null
2017-12-10 02:54:26,408 [salt.loaded.int.module.cmdmod][INFO    ][23932] Executing command 'virsh -q net-list --all | grep -Eq '^\s*default'' in directory '/root'
2017-12-10 02:54:26,423 [salt.state       ][INFO    ][23932] onlyif execution failed
2017-12-10 02:54:26,423 [salt.state       ][INFO    ][23932] Completed state [virsh net-undefine default;virsh net-destroy default 2>&1 1>/dev/null] at time 02:54:26.423048 duration_in_ms=15.673
2017-12-10 02:54:26,423 [salt.state       ][INFO    ][23932] Running state [virsh net-autostart default --disable] at time 02:54:26.423302
2017-12-10 02:54:26,424 [salt.state       ][INFO    ][23932] Executing state cmd.run for virsh net-autostart default --disable
2017-12-10 02:54:26,424 [salt.loaded.int.module.cmdmod][INFO    ][23932] Executing command 'virsh net-info default | grep -Eq '^Autostart:\s+yes'' in directory '/root'
2017-12-10 02:54:26,439 [salt.state       ][INFO    ][23932] onlyif execution failed
2017-12-10 02:54:26,439 [salt.state       ][INFO    ][23932] Completed state [virsh net-autostart default --disable] at time 02:54:26.438755 duration_in_ms=15.453
2017-12-10 02:54:26,439 [salt.state       ][INFO    ][23932] Running state [virsh net-destroy default] at time 02:54:26.438989
2017-12-10 02:54:26,439 [salt.state       ][INFO    ][23932] Executing state cmd.run for virsh net-destroy default
2017-12-10 02:54:26,440 [salt.loaded.int.module.cmdmod][INFO    ][23932] Executing command 'virsh -q net-list --all | grep -Eq '^\s*default\s+active'' in directory '/root'
2017-12-10 02:54:26,456 [salt.state       ][INFO    ][23932] onlyif execution failed
2017-12-10 02:54:26,456 [salt.state       ][INFO    ][23932] Completed state [virsh net-destroy default] at time 02:54:26.455751 duration_in_ms=16.762
2017-12-10 02:54:26,456 [salt.state       ][INFO    ][23932] Running state [echo 'No pool is defined'] at time 02:54:26.455945
2017-12-10 02:54:26,456 [salt.state       ][INFO    ][23932] Executing state cmd.run for echo 'No pool is defined'
2017-12-10 02:54:26,457 [salt.loaded.int.module.cmdmod][INFO    ][23932] Executing command 'echo 'No pool is defined'' in directory '/root'
2017-12-10 02:54:26,462 [salt.state       ][INFO    ][23932] {'pid': 23966, 'retcode': 0, 'stderr': '', 'stdout': 'No pool is defined'}
2017-12-10 02:54:26,462 [salt.state       ][INFO    ][23932] Completed state [echo 'No pool is defined'] at time 02:54:26.462033 duration_in_ms=6.086
2017-12-10 02:54:26,463 [salt.minion      ][INFO    ][23932] Returning information for job: 20171210025423123487
2017-12-10 02:55:42,184 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command state.apply with jid 20171210025542181162
2017-12-10 02:55:42,199 [salt.minion      ][INFO    ][23990] Starting a new job with PID 23990
2017-12-10 02:55:44,690 [salt.state       ][INFO    ][23990] Loading fresh modules for state activity
2017-12-10 02:55:44,712 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/init.sls'
2017-12-10 02:55:44,731 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-12-10 02:55:44,744 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-12-10 02:55:44,768 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-10 02:55:44,829 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-10 02:55:44,847 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-10 02:55:44,927 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-10 02:55:44,949 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-10 02:55:44,963 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-10 02:55:44,979 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-10 02:55:44,986 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-10 02:55:44,997 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-10 02:55:45,018 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-10 02:55:45,032 [py.warnings      ][WARNING ][23990] /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-12-10 02:55:45,032 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'salt-minion --version' in directory '/root'
2017-12-10 02:55:45,264 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'salt-minion --version' in directory '/root'
2017-12-10 02:55:45,508 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-10 02:55:45,527 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-10 02:55:45,539 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-10 02:55:45,551 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-10 02:55:45,588 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-10 02:55:45,603 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-10 02:55:45,626 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-10 02:55:45,639 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-10 02:55:45,655 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-10 02:55:45,688 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-10 02:55:45,742 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-12-10 02:55:45,761 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-10 02:55:45,812 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-10 02:55:45,830 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-10 02:55:45,869 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-10 02:55:45,889 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-10 02:55:45,901 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-10 02:55:45,917 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-10 02:55:45,923 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-10 02:55:45,933 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-10 02:55:45,952 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-10 02:55:45,966 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'salt-minion --version' in directory '/root'
2017-12-10 02:55:46,194 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'salt-minion --version' in directory '/root'
2017-12-10 02:55:46,450 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-10 02:55:46,474 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-10 02:55:46,514 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-10 02:55:46,527 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-10 02:55:46,568 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-10 02:55:46,585 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-10 02:55:46,606 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-10 02:55:46,624 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-10 02:55:46,640 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-10 02:55:46,675 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-10 02:55:46,740 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-12-10 02:55:46,796 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-10 02:55:46,844 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-12-10 02:55:46,858 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-12-10 02:55:46,908 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-10 02:55:47,225 [salt.state       ][INFO    ][23990] Running state [salt-minion] at time 02:55:47.225029
2017-12-10 02:55:47,225 [salt.state       ][INFO    ][23990] Executing state pkg.installed for salt-minion
2017-12-10 02:55:47,226 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-10 02:55:47,480 [salt.state       ][INFO    ][23990] Package salt-minion is already installed
2017-12-10 02:55:47,481 [salt.state       ][INFO    ][23990] Completed state [salt-minion] at time 02:55:47.480661 duration_in_ms=255.633
2017-12-10 02:55:47,481 [salt.state       ][INFO    ][23990] Running state [salt_minion_dependency_packages] at time 02:55:47.480944
2017-12-10 02:55:47,481 [salt.state       ][INFO    ][23990] Executing state pkg.installed for salt_minion_dependency_packages
2017-12-10 02:55:47,484 [salt.state       ][INFO    ][23990] All specified packages are already installed
2017-12-10 02:55:47,484 [salt.state       ][INFO    ][23990] Completed state [salt_minion_dependency_packages] at time 02:55:47.484005 duration_in_ms=3.06
2017-12-10 02:55:47,486 [salt.state       ][INFO    ][23990] Running state [/etc/salt/minion.d/minion.conf] at time 02:55:47.485637
2017-12-10 02:55:47,486 [salt.state       ][INFO    ][23990] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-12-10 02:55:47,506 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-12-10 02:55:47,549 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-10 02:55:47,591 [salt.fileclient  ][INFO    ][23990] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-10 02:55:47,624 [salt.state       ][INFO    ][23990] File /etc/salt/minion.d/minion.conf is in the correct state
2017-12-10 02:55:47,624 [salt.state       ][INFO    ][23990] Completed state [/etc/salt/minion.d/minion.conf] at time 02:55:47.623961 duration_in_ms=138.323
2017-12-10 02:55:47,624 [salt.state       ][INFO    ][23990] Running state [/etc/salt/minion.d/_virt.conf] at time 02:55:47.624314
2017-12-10 02:55:47,625 [salt.state       ][INFO    ][23990] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-12-10 02:55:47,631 [salt.state       ][INFO    ][23990] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-12-10 02:55:47,631 [salt.state       ][INFO    ][23990] Completed state [/etc/salt/minion.d/_virt.conf] at time 02:55:47.631048 duration_in_ms=6.734
2017-12-10 02:55:47,633 [salt.state       ][INFO    ][23990] Running state [salt-minion] at time 02:55:47.632509
2017-12-10 02:55:47,633 [salt.state       ][INFO    ][23990] Executing state service.running for salt-minion
2017-12-10 02:55:47,633 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-12-10 02:55:47,650 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-12-10 02:55:47,658 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-12-10 02:55:47,666 [salt.state       ][INFO    ][23990] The service salt-minion is already running
2017-12-10 02:55:47,666 [salt.state       ][INFO    ][23990] Completed state [salt-minion] at time 02:55:47.665786 duration_in_ms=33.277
2017-12-10 02:55:47,667 [salt.state       ][INFO    ][23990] Running state [/etc/salt/grains.d] at time 02:55:47.667027
2017-12-10 02:55:47,667 [salt.state       ][INFO    ][23990] Executing state file.directory for /etc/salt/grains.d
2017-12-10 02:55:47,668 [salt.state       ][INFO    ][23990] Directory /etc/salt/grains.d is in the correct state
2017-12-10 02:55:47,668 [salt.state       ][INFO    ][23990] Completed state [/etc/salt/grains.d] at time 02:55:47.667866 duration_in_ms=0.839
2017-12-10 02:55:47,668 [salt.state       ][INFO    ][23990] Running state [/etc/salt/grains] at time 02:55:47.668254
2017-12-10 02:55:47,668 [salt.state       ][INFO    ][23990] Executing state file.managed for /etc/salt/grains
2017-12-10 02:55:47,669 [salt.state       ][INFO    ][23990] File /etc/salt/grains exists with proper permissions. No changes made.
2017-12-10 02:55:47,669 [salt.state       ][INFO    ][23990] Completed state [/etc/salt/grains] at time 02:55:47.668863 duration_in_ms=0.609
2017-12-10 02:55:47,669 [salt.state       ][INFO    ][23990] Running state [/etc/salt/grains.d/placeholder] at time 02:55:47.669112
2017-12-10 02:55:47,669 [salt.state       ][INFO    ][23990] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-12-10 02:55:47,670 [salt.state       ][INFO    ][23990] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-12-10 02:55:47,670 [salt.state       ][INFO    ][23990] Completed state [/etc/salt/grains.d/placeholder] at time 02:55:47.669657 duration_in_ms=0.546
2017-12-10 02:55:47,670 [salt.state       ][INFO    ][23990] Running state [/etc/salt/grains.d/sphinx] at time 02:55:47.669905
2017-12-10 02:55:47,670 [salt.state       ][INFO    ][23990] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-12-10 02:55:47,680 [salt.state       ][INFO    ][23990] File /etc/salt/grains.d/sphinx is in the correct state
2017-12-10 02:55:47,680 [salt.state       ][INFO    ][23990] Completed state [/etc/salt/grains.d/sphinx] at time 02:55:47.680238 duration_in_ms=10.333
2017-12-10 02:55:47,681 [salt.state       ][INFO    ][23990] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:55:47.680797
2017-12-10 02:55:47,681 [salt.state       ][INFO    ][23990] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-10 02:55:47,681 [salt.state       ][INFO    ][23990] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-10 02:55:47,681 [salt.state       ][INFO    ][23990] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:55:47.681249 duration_in_ms=0.452
2017-12-10 02:55:47,682 [salt.state       ][INFO    ][23990] Running state [/etc/salt/grains.d/dns_records] at time 02:55:47.681495
2017-12-10 02:55:47,682 [salt.state       ][INFO    ][23990] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-12-10 02:55:47,686 [salt.state       ][INFO    ][23990] File /etc/salt/grains.d/dns_records is in the correct state
2017-12-10 02:55:47,686 [salt.state       ][INFO    ][23990] Completed state [/etc/salt/grains.d/dns_records] at time 02:55:47.686215 duration_in_ms=4.72
2017-12-10 02:55:47,687 [salt.state       ][INFO    ][23990] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:55:47.686746
2017-12-10 02:55:47,687 [salt.state       ][INFO    ][23990] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-12-10 02:55:47,687 [salt.state       ][INFO    ][23990] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-12-10 02:55:47,687 [salt.state       ][INFO    ][23990] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:55:47.687199 duration_in_ms=0.452
2017-12-10 02:55:47,687 [salt.state       ][INFO    ][23990] Running state [/etc/salt/grains.d/salt] at time 02:55:47.687443
2017-12-10 02:55:47,688 [salt.state       ][INFO    ][23990] Executing state file.managed for /etc/salt/grains.d/salt
2017-12-10 02:55:47,692 [salt.state       ][INFO    ][23990] File /etc/salt/grains.d/salt is in the correct state
2017-12-10 02:55:47,692 [salt.state       ][INFO    ][23990] Completed state [/etc/salt/grains.d/salt] at time 02:55:47.692220 duration_in_ms=4.776
2017-12-10 02:55:47,693 [salt.state       ][INFO    ][23990] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:55:47.692757
2017-12-10 02:55:47,693 [salt.state       ][INFO    ][23990] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-10 02:55:47,693 [salt.state       ][INFO    ][23990] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-10 02:55:47,693 [salt.state       ][INFO    ][23990] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:55:47.693205 duration_in_ms=0.448
2017-12-10 02:55:47,694 [salt.state       ][INFO    ][23990] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:55:47.694301
2017-12-10 02:55:47,694 [salt.state       ][INFO    ][23990] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-10 02:55:47,695 [salt.state       ][INFO    ][23990] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-10 02:55:47,695 [salt.state       ][INFO    ][23990] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:55:47.694755 duration_in_ms=0.454
2017-12-10 02:55:47,695 [salt.state       ][INFO    ][23990] Running state [mine.update] at time 02:55:47.695133
2017-12-10 02:55:47,695 [salt.state       ][INFO    ][23990] Executing state module.wait for mine.update
2017-12-10 02:55:47,695 [salt.state       ][INFO    ][23990] No changes made for mine.update
2017-12-10 02:55:47,696 [salt.state       ][INFO    ][23990] Completed state [mine.update] at time 02:55:47.695546 duration_in_ms=0.414
2017-12-10 02:55:47,696 [salt.state       ][INFO    ][23990] Running state [ca-certificates] at time 02:55:47.695677
2017-12-10 02:55:47,696 [salt.state       ][INFO    ][23990] Executing state pkg.installed for ca-certificates
2017-12-10 02:55:47,699 [salt.state       ][INFO    ][23990] Package ca-certificates is already installed
2017-12-10 02:55:47,699 [salt.state       ][INFO    ][23990] Completed state [ca-certificates] at time 02:55:47.698866 duration_in_ms=3.189
2017-12-10 02:55:47,699 [salt.state       ][INFO    ][23990] Running state [update-ca-certificates] at time 02:55:47.699261
2017-12-10 02:55:47,699 [salt.state       ][INFO    ][23990] Executing state cmd.wait for update-ca-certificates
2017-12-10 02:55:47,700 [salt.state       ][INFO    ][23990] No changes made for update-ca-certificates
2017-12-10 02:55:47,700 [salt.state       ][INFO    ][23990] Completed state [update-ca-certificates] at time 02:55:47.699698 duration_in_ms=0.438
2017-12-10 02:55:47,700 [salt.state       ][INFO    ][23990] Running state [libvirt-dev] at time 02:55:47.699833
2017-12-10 02:55:47,700 [salt.state       ][INFO    ][23990] Executing state pkg.installed for libvirt-dev
2017-12-10 02:55:47,703 [salt.state       ][INFO    ][23990] Package libvirt-dev is already installed
2017-12-10 02:55:47,703 [salt.state       ][INFO    ][23990] Completed state [libvirt-dev] at time 02:55:47.702650 duration_in_ms=2.817
2017-12-10 02:55:47,703 [salt.state       ][INFO    ][23990] Running state [pkg-config] at time 02:55:47.702785
2017-12-10 02:55:47,703 [salt.state       ][INFO    ][23990] Executing state pkg.installed for pkg-config
2017-12-10 02:55:47,705 [salt.state       ][INFO    ][23990] Package pkg-config is already installed
2017-12-10 02:55:47,706 [salt.state       ][INFO    ][23990] Completed state [pkg-config] at time 02:55:47.705590 duration_in_ms=2.805
2017-12-10 02:55:47,706 [salt.state       ][INFO    ][23990] Running state [virtng.init] at time 02:55:47.705726
2017-12-10 02:55:47,706 [salt.state       ][INFO    ][23990] Executing state module.run for virtng.init
2017-12-10 02:55:47,745 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command ['git', '--version'] in directory '/root'
2017-12-10 02:55:47,828 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'virsh list --all --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:55:47,847 [salt.state       ][INFO    ][23990] unless execution succeeded
2017-12-10 02:55:47,847 [salt.state       ][INFO    ][23990] Completed state [virtng.init] at time 02:55:47.847435 duration_in_ms=141.707
2017-12-10 02:55:47,848 [salt.state       ][INFO    ][23990] Running state [virt.set_autostart] at time 02:55:47.847690
2017-12-10 02:55:47,848 [salt.state       ][INFO    ][23990] Executing state module.run for virt.set_autostart
2017-12-10 02:55:47,850 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'virsh list --autostart --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:55:47,866 [salt.state       ][INFO    ][23990] unless execution succeeded
2017-12-10 02:55:47,866 [salt.state       ][INFO    ][23990] Completed state [virt.set_autostart] at time 02:55:47.866174 duration_in_ms=18.483
2017-12-10 02:55:47,866 [salt.state       ][INFO    ][23990] Running state [virtng.init] at time 02:55:47.866379
2017-12-10 02:55:47,867 [salt.state       ][INFO    ][23990] Executing state module.run for virtng.init
2017-12-10 02:55:47,868 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'virsh list --all --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:55:47,884 [salt.state       ][INFO    ][23990] unless execution succeeded
2017-12-10 02:55:47,884 [salt.state       ][INFO    ][23990] Completed state [virtng.init] at time 02:55:47.884266 duration_in_ms=17.885
2017-12-10 02:55:47,885 [salt.state       ][INFO    ][23990] Running state [virt.set_autostart] at time 02:55:47.884534
2017-12-10 02:55:47,885 [salt.state       ][INFO    ][23990] Executing state module.run for virt.set_autostart
2017-12-10 02:55:47,887 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'virsh list --autostart --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:55:47,902 [salt.state       ][INFO    ][23990] unless execution succeeded
2017-12-10 02:55:47,902 [salt.state       ][INFO    ][23990] Completed state [virt.set_autostart] at time 02:55:47.901878 duration_in_ms=17.344
2017-12-10 02:55:47,902 [salt.state       ][INFO    ][23990] Running state [virtng.init] at time 02:55:47.902086
2017-12-10 02:55:47,902 [salt.state       ][INFO    ][23990] Executing state module.run for virtng.init
2017-12-10 02:55:47,904 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'virsh list --all --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:55:47,920 [salt.state       ][INFO    ][23990] unless execution succeeded
2017-12-10 02:55:47,920 [salt.state       ][INFO    ][23990] Completed state [virtng.init] at time 02:55:47.919825 duration_in_ms=17.738
2017-12-10 02:55:47,920 [salt.state       ][INFO    ][23990] Running state [virt.set_autostart] at time 02:55:47.920054
2017-12-10 02:55:47,920 [salt.state       ][INFO    ][23990] Executing state module.run for virt.set_autostart
2017-12-10 02:55:47,922 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'virsh list --autostart --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:55:47,936 [salt.state       ][INFO    ][23990] unless execution succeeded
2017-12-10 02:55:47,936 [salt.state       ][INFO    ][23990] Completed state [virt.set_autostart] at time 02:55:47.936424 duration_in_ms=16.369
2017-12-10 02:55:47,937 [salt.state       ][INFO    ][23990] Running state [virtng.init] at time 02:55:47.936671
2017-12-10 02:55:47,937 [salt.state       ][INFO    ][23990] Executing state module.run for virtng.init
2017-12-10 02:55:47,939 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'virsh list --all --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:55:47,952 [salt.state       ][INFO    ][23990] unless execution succeeded
2017-12-10 02:55:47,952 [salt.state       ][INFO    ][23990] Completed state [virtng.init] at time 02:55:47.952351 duration_in_ms=15.679
2017-12-10 02:55:47,953 [salt.state       ][INFO    ][23990] Running state [virt.set_autostart] at time 02:55:47.952609
2017-12-10 02:55:47,953 [salt.state       ][INFO    ][23990] Executing state module.run for virt.set_autostart
2017-12-10 02:55:47,955 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'virsh list --autostart --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:55:47,968 [salt.state       ][INFO    ][23990] unless execution succeeded
2017-12-10 02:55:47,968 [salt.state       ][INFO    ][23990] Completed state [virt.set_autostart] at time 02:55:47.968153 duration_in_ms=15.543
2017-12-10 02:55:47,968 [salt.state       ][INFO    ][23990] Running state [virtng.init] at time 02:55:47.968373
2017-12-10 02:55:47,969 [salt.state       ][INFO    ][23990] Executing state module.run for virtng.init
2017-12-10 02:55:47,971 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'virsh list --all --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:55:47,983 [salt.state       ][INFO    ][23990] unless execution succeeded
2017-12-10 02:55:47,983 [salt.state       ][INFO    ][23990] Completed state [virtng.init] at time 02:55:47.983328 duration_in_ms=14.954
2017-12-10 02:55:47,984 [salt.state       ][INFO    ][23990] Running state [virt.set_autostart] at time 02:55:47.983551
2017-12-10 02:55:47,984 [salt.state       ][INFO    ][23990] Executing state module.run for virt.set_autostart
2017-12-10 02:55:47,986 [salt.loaded.int.module.cmdmod][INFO    ][23990] Executing command 'virsh list --autostart --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 02:55:47,999 [salt.state       ][INFO    ][23990] unless execution succeeded
2017-12-10 02:55:47,999 [salt.state       ][INFO    ][23990] Completed state [virt.set_autostart] at time 02:55:47.999246 duration_in_ms=15.694
2017-12-10 02:55:48,002 [salt.minion      ][INFO    ][23990] Returning information for job: 20171210025542181162
2017-12-10 03:10:04,051 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command state.sls with jid 20171210031004042465
2017-12-10 03:10:04,065 [salt.minion      ][INFO    ][24398] Starting a new job with PID 24398
2017-12-10 03:10:06,499 [salt.state       ][INFO    ][24398] Loading fresh modules for state activity
2017-12-10 03:10:06,522 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-12-10 03:10:06,540 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-12-10 03:10:06,601 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-10 03:10:06,667 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-12-10 03:10:06,680 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-12-10 03:10:06,705 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-10 03:10:06,761 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-10 03:10:06,779 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-10 03:10:06,820 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-10 03:10:06,843 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-10 03:10:06,855 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-10 03:10:06,870 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-10 03:10:06,877 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-10 03:10:06,888 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-10 03:10:06,907 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-10 03:10:06,921 [py.warnings      ][WARNING ][24398] /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-12-10 03:10:06,921 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'salt-minion --version' in directory '/root'
2017-12-10 03:10:07,148 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'salt-minion --version' in directory '/root'
2017-12-10 03:10:07,394 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-10 03:10:07,412 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-10 03:10:07,423 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-10 03:10:07,436 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-10 03:10:07,469 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-10 03:10:07,487 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-10 03:10:07,511 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-10 03:10:07,527 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-10 03:10:07,546 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-10 03:10:07,581 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-10 03:10:07,635 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-12-10 03:10:07,656 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-10 03:10:07,710 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-10 03:10:07,731 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-10 03:10:07,773 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-10 03:10:07,795 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-10 03:10:07,807 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-10 03:10:07,822 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-10 03:10:07,828 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-10 03:10:07,839 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-10 03:10:07,858 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-10 03:10:07,872 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'salt-minion --version' in directory '/root'
2017-12-10 03:10:08,097 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'salt-minion --version' in directory '/root'
2017-12-10 03:10:08,357 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-10 03:10:08,377 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-10 03:10:08,389 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-10 03:10:08,402 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-10 03:10:08,438 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-10 03:10:08,452 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-10 03:10:08,475 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-10 03:10:08,487 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-10 03:10:08,504 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-10 03:10:08,537 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-10 03:10:08,600 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-12-10 03:10:08,660 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-10 03:10:08,959 [salt.state       ][INFO    ][24398] Running state [salt-minion] at time 03:10:08.958795
2017-12-10 03:10:08,959 [salt.state       ][INFO    ][24398] Executing state pkg.installed for salt-minion
2017-12-10 03:10:08,960 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-10 03:10:09,229 [salt.state       ][INFO    ][24398] Package salt-minion is already installed
2017-12-10 03:10:09,229 [salt.state       ][INFO    ][24398] Completed state [salt-minion] at time 03:10:09.229050 duration_in_ms=270.255
2017-12-10 03:10:09,229 [salt.state       ][INFO    ][24398] Running state [salt_minion_dependency_packages] at time 03:10:09.229246
2017-12-10 03:10:09,229 [salt.state       ][INFO    ][24398] Executing state pkg.installed for salt_minion_dependency_packages
2017-12-10 03:10:09,232 [salt.state       ][INFO    ][24398] All specified packages are already installed
2017-12-10 03:10:09,232 [salt.state       ][INFO    ][24398] Completed state [salt_minion_dependency_packages] at time 03:10:09.232200 duration_in_ms=2.955
2017-12-10 03:10:09,234 [salt.state       ][INFO    ][24398] Running state [/etc/salt/minion.d/minion.conf] at time 03:10:09.233655
2017-12-10 03:10:09,234 [salt.state       ][INFO    ][24398] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-12-10 03:10:09,252 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-12-10 03:10:09,296 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-10 03:10:09,338 [salt.fileclient  ][INFO    ][24398] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-10 03:10:09,371 [salt.state       ][INFO    ][24398] File /etc/salt/minion.d/minion.conf is in the correct state
2017-12-10 03:10:09,371 [salt.state       ][INFO    ][24398] Completed state [/etc/salt/minion.d/minion.conf] at time 03:10:09.370788 duration_in_ms=137.133
2017-12-10 03:10:09,371 [salt.state       ][INFO    ][24398] Running state [/etc/salt/minion.d/_virt.conf] at time 03:10:09.371062
2017-12-10 03:10:09,371 [salt.state       ][INFO    ][24398] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-12-10 03:10:09,381 [salt.state       ][INFO    ][24398] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-12-10 03:10:09,381 [salt.state       ][INFO    ][24398] Completed state [/etc/salt/minion.d/_virt.conf] at time 03:10:09.380778 duration_in_ms=9.717
2017-12-10 03:10:09,382 [salt.state       ][INFO    ][24398] Running state [salt-minion] at time 03:10:09.382088
2017-12-10 03:10:09,382 [salt.state       ][INFO    ][24398] Executing state service.running for salt-minion
2017-12-10 03:10:09,383 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-12-10 03:10:09,401 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-12-10 03:10:09,409 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-12-10 03:10:09,416 [salt.state       ][INFO    ][24398] The service salt-minion is already running
2017-12-10 03:10:09,416 [salt.state       ][INFO    ][24398] Completed state [salt-minion] at time 03:10:09.416081 duration_in_ms=33.992
2017-12-10 03:10:09,417 [salt.state       ][INFO    ][24398] Running state [/etc/salt/grains.d] at time 03:10:09.417453
2017-12-10 03:10:09,418 [salt.state       ][INFO    ][24398] Executing state file.directory for /etc/salt/grains.d
2017-12-10 03:10:09,418 [salt.state       ][INFO    ][24398] Directory /etc/salt/grains.d is in the correct state
2017-12-10 03:10:09,418 [salt.state       ][INFO    ][24398] Completed state [/etc/salt/grains.d] at time 03:10:09.418324 duration_in_ms=0.871
2017-12-10 03:10:09,419 [salt.state       ][INFO    ][24398] Running state [/etc/salt/grains] at time 03:10:09.418728
2017-12-10 03:10:09,419 [salt.state       ][INFO    ][24398] Executing state file.managed for /etc/salt/grains
2017-12-10 03:10:09,419 [salt.state       ][INFO    ][24398] File /etc/salt/grains exists with proper permissions. No changes made.
2017-12-10 03:10:09,419 [salt.state       ][INFO    ][24398] Completed state [/etc/salt/grains] at time 03:10:09.419323 duration_in_ms=0.595
2017-12-10 03:10:09,420 [salt.state       ][INFO    ][24398] Running state [/etc/salt/grains.d/placeholder] at time 03:10:09.419580
2017-12-10 03:10:09,420 [salt.state       ][INFO    ][24398] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-12-10 03:10:09,420 [salt.state       ][INFO    ][24398] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-12-10 03:10:09,420 [salt.state       ][INFO    ][24398] Completed state [/etc/salt/grains.d/placeholder] at time 03:10:09.420150 duration_in_ms=0.569
2017-12-10 03:10:09,420 [salt.state       ][INFO    ][24398] Running state [/etc/salt/grains.d/sphinx] at time 03:10:09.420411
2017-12-10 03:10:09,421 [salt.state       ][INFO    ][24398] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-12-10 03:10:09,429 [salt.state       ][INFO    ][24398] File /etc/salt/grains.d/sphinx is in the correct state
2017-12-10 03:10:09,429 [salt.state       ][INFO    ][24398] Completed state [/etc/salt/grains.d/sphinx] at time 03:10:09.429036 duration_in_ms=8.625
2017-12-10 03:10:09,430 [salt.state       ][INFO    ][24398] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:10:09.429571
2017-12-10 03:10:09,430 [salt.state       ][INFO    ][24398] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-10 03:10:09,430 [salt.state       ][INFO    ][24398] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-10 03:10:09,430 [salt.state       ][INFO    ][24398] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:10:09.430058 duration_in_ms=0.487
2017-12-10 03:10:09,430 [salt.state       ][INFO    ][24398] Running state [/etc/salt/grains.d/dns_records] at time 03:10:09.430325
2017-12-10 03:10:09,431 [salt.state       ][INFO    ][24398] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-12-10 03:10:09,435 [salt.state       ][INFO    ][24398] File /etc/salt/grains.d/dns_records is in the correct state
2017-12-10 03:10:09,435 [salt.state       ][INFO    ][24398] Completed state [/etc/salt/grains.d/dns_records] at time 03:10:09.435329 duration_in_ms=5.005
2017-12-10 03:10:09,436 [salt.state       ][INFO    ][24398] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:10:09.435852
2017-12-10 03:10:09,436 [salt.state       ][INFO    ][24398] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-12-10 03:10:09,436 [salt.state       ][INFO    ][24398] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-12-10 03:10:09,436 [salt.state       ][INFO    ][24398] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:10:09.436331 duration_in_ms=0.479
2017-12-10 03:10:09,437 [salt.state       ][INFO    ][24398] Running state [/etc/salt/grains.d/salt] at time 03:10:09.436627
2017-12-10 03:10:09,437 [salt.state       ][INFO    ][24398] Executing state file.managed for /etc/salt/grains.d/salt
2017-12-10 03:10:09,441 [salt.state       ][INFO    ][24398] File /etc/salt/grains.d/salt is in the correct state
2017-12-10 03:10:09,441 [salt.state       ][INFO    ][24398] Completed state [/etc/salt/grains.d/salt] at time 03:10:09.441141 duration_in_ms=4.514
2017-12-10 03:10:09,442 [salt.state       ][INFO    ][24398] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:10:09.441643
2017-12-10 03:10:09,442 [salt.state       ][INFO    ][24398] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-10 03:10:09,442 [salt.state       ][INFO    ][24398] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-10 03:10:09,442 [salt.state       ][INFO    ][24398] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:10:09.442118 duration_in_ms=0.475
2017-12-10 03:10:09,443 [salt.state       ][INFO    ][24398] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:10:09.443233
2017-12-10 03:10:09,443 [salt.state       ][INFO    ][24398] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-10 03:10:09,444 [salt.state       ][INFO    ][24398] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-10 03:10:09,444 [salt.state       ][INFO    ][24398] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:10:09.443711 duration_in_ms=0.478
2017-12-10 03:10:09,444 [salt.state       ][INFO    ][24398] Running state [mine.update] at time 03:10:09.444105
2017-12-10 03:10:09,444 [salt.state       ][INFO    ][24398] Executing state module.wait for mine.update
2017-12-10 03:10:09,444 [salt.state       ][INFO    ][24398] No changes made for mine.update
2017-12-10 03:10:09,445 [salt.state       ][INFO    ][24398] Completed state [mine.update] at time 03:10:09.444578 duration_in_ms=0.473
2017-12-10 03:10:09,445 [salt.state       ][INFO    ][24398] Running state [ca-certificates] at time 03:10:09.444722
2017-12-10 03:10:09,445 [salt.state       ][INFO    ][24398] Executing state pkg.installed for ca-certificates
2017-12-10 03:10:09,448 [salt.state       ][INFO    ][24398] Package ca-certificates is already installed
2017-12-10 03:10:09,448 [salt.state       ][INFO    ][24398] Completed state [ca-certificates] at time 03:10:09.447956 duration_in_ms=3.234
2017-12-10 03:10:09,448 [salt.state       ][INFO    ][24398] Running state [update-ca-certificates] at time 03:10:09.448350
2017-12-10 03:10:09,449 [salt.state       ][INFO    ][24398] Executing state cmd.wait for update-ca-certificates
2017-12-10 03:10:09,449 [salt.state       ][INFO    ][24398] No changes made for update-ca-certificates
2017-12-10 03:10:09,449 [salt.state       ][INFO    ][24398] Completed state [update-ca-certificates] at time 03:10:09.448849 duration_in_ms=0.499
2017-12-10 03:10:09,449 [salt.state       ][INFO    ][24398] Running state [libvirt-dev] at time 03:10:09.448992
2017-12-10 03:10:09,449 [salt.state       ][INFO    ][24398] Executing state pkg.installed for libvirt-dev
2017-12-10 03:10:09,452 [salt.state       ][INFO    ][24398] Package libvirt-dev is already installed
2017-12-10 03:10:09,452 [salt.state       ][INFO    ][24398] Completed state [libvirt-dev] at time 03:10:09.451827 duration_in_ms=2.835
2017-12-10 03:10:09,452 [salt.state       ][INFO    ][24398] Running state [pkg-config] at time 03:10:09.451970
2017-12-10 03:10:09,452 [salt.state       ][INFO    ][24398] Executing state pkg.installed for pkg-config
2017-12-10 03:10:09,455 [salt.state       ][INFO    ][24398] Package pkg-config is already installed
2017-12-10 03:10:09,455 [salt.state       ][INFO    ][24398] Completed state [pkg-config] at time 03:10:09.454771 duration_in_ms=2.802
2017-12-10 03:10:09,455 [salt.state       ][INFO    ][24398] Running state [virtng.init] at time 03:10:09.454927
2017-12-10 03:10:09,455 [salt.state       ][INFO    ][24398] Executing state module.run for virtng.init
2017-12-10 03:10:09,530 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command ['git', '--version'] in directory '/root'
2017-12-10 03:10:09,571 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'virsh list --all --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 03:10:09,588 [salt.state       ][INFO    ][24398] unless execution succeeded
2017-12-10 03:10:09,588 [salt.state       ][INFO    ][24398] Completed state [virtng.init] at time 03:10:09.587780 duration_in_ms=132.851
2017-12-10 03:10:09,588 [salt.state       ][INFO    ][24398] Running state [virt.set_autostart] at time 03:10:09.588016
2017-12-10 03:10:09,588 [salt.state       ][INFO    ][24398] Executing state module.run for virt.set_autostart
2017-12-10 03:10:09,590 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'virsh list --autostart --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 03:10:09,607 [salt.state       ][INFO    ][24398] unless execution succeeded
2017-12-10 03:10:09,607 [salt.state       ][INFO    ][24398] Completed state [virt.set_autostart] at time 03:10:09.607147 duration_in_ms=19.131
2017-12-10 03:10:09,607 [salt.state       ][INFO    ][24398] Running state [virtng.init] at time 03:10:09.607359
2017-12-10 03:10:09,608 [salt.state       ][INFO    ][24398] Executing state module.run for virtng.init
2017-12-10 03:10:09,609 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'virsh list --all --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 03:10:09,624 [salt.state       ][INFO    ][24398] unless execution succeeded
2017-12-10 03:10:09,624 [salt.state       ][INFO    ][24398] Completed state [virtng.init] at time 03:10:09.624087 duration_in_ms=16.728
2017-12-10 03:10:09,624 [salt.state       ][INFO    ][24398] Running state [virt.set_autostart] at time 03:10:09.624315
2017-12-10 03:10:09,625 [salt.state       ][INFO    ][24398] Executing state module.run for virt.set_autostart
2017-12-10 03:10:09,626 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'virsh list --autostart --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 03:10:09,640 [salt.state       ][INFO    ][24398] unless execution succeeded
2017-12-10 03:10:09,641 [salt.state       ][INFO    ][24398] Completed state [virt.set_autostart] at time 03:10:09.640623 duration_in_ms=16.308
2017-12-10 03:10:09,641 [salt.state       ][INFO    ][24398] Running state [virtng.init] at time 03:10:09.640839
2017-12-10 03:10:09,641 [salt.state       ][INFO    ][24398] Executing state module.run for virtng.init
2017-12-10 03:10:09,643 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'virsh list --all --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 03:10:09,657 [salt.state       ][INFO    ][24398] unless execution succeeded
2017-12-10 03:10:09,657 [salt.state       ][INFO    ][24398] Completed state [virtng.init] at time 03:10:09.657176 duration_in_ms=16.337
2017-12-10 03:10:09,657 [salt.state       ][INFO    ][24398] Running state [virt.set_autostart] at time 03:10:09.657394
2017-12-10 03:10:09,658 [salt.state       ][INFO    ][24398] Executing state module.run for virt.set_autostart
2017-12-10 03:10:09,659 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'virsh list --autostart --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 03:10:09,674 [salt.state       ][INFO    ][24398] unless execution succeeded
2017-12-10 03:10:09,674 [salt.state       ][INFO    ][24398] Completed state [virt.set_autostart] at time 03:10:09.674302 duration_in_ms=16.907
2017-12-10 03:10:09,675 [salt.state       ][INFO    ][24398] Running state [virtng.init] at time 03:10:09.674510
2017-12-10 03:10:09,675 [salt.state       ][INFO    ][24398] Executing state module.run for virtng.init
2017-12-10 03:10:09,677 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'virsh list --all --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 03:10:09,691 [salt.state       ][INFO    ][24398] unless execution succeeded
2017-12-10 03:10:09,691 [salt.state       ][INFO    ][24398] Completed state [virtng.init] at time 03:10:09.690771 duration_in_ms=16.259
2017-12-10 03:10:09,691 [salt.state       ][INFO    ][24398] Running state [virt.set_autostart] at time 03:10:09.691024
2017-12-10 03:10:09,691 [salt.state       ][INFO    ][24398] Executing state module.run for virt.set_autostart
2017-12-10 03:10:09,693 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'virsh list --autostart --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 03:10:09,708 [salt.state       ][INFO    ][24398] unless execution succeeded
2017-12-10 03:10:09,708 [salt.state       ][INFO    ][24398] Completed state [virt.set_autostart] at time 03:10:09.708031 duration_in_ms=17.007
2017-12-10 03:10:09,708 [salt.state       ][INFO    ][24398] Running state [virtng.init] at time 03:10:09.708235
2017-12-10 03:10:09,708 [salt.state       ][INFO    ][24398] Executing state module.run for virtng.init
2017-12-10 03:10:09,710 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'virsh list --all --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 03:10:09,725 [salt.state       ][INFO    ][24398] unless execution succeeded
2017-12-10 03:10:09,725 [salt.state       ][INFO    ][24398] Completed state [virtng.init] at time 03:10:09.724895 duration_in_ms=16.66
2017-12-10 03:10:09,725 [salt.state       ][INFO    ][24398] Running state [virt.set_autostart] at time 03:10:09.725103
2017-12-10 03:10:09,725 [salt.state       ][INFO    ][24398] Executing state module.run for virt.set_autostart
2017-12-10 03:10:09,727 [salt.loaded.int.module.cmdmod][INFO    ][24398] Executing command 'virsh list --autostart --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-10 03:10:09,741 [salt.state       ][INFO    ][24398] unless execution succeeded
2017-12-10 03:10:09,741 [salt.state       ][INFO    ][24398] Completed state [virt.set_autostart] at time 03:10:09.741231 duration_in_ms=16.127
2017-12-10 03:10:09,743 [salt.minion      ][INFO    ][24398] Returning information for job: 20171210031004042465
2017-12-10 03:10:10,499 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command pillar.get with jid 20171210031010495446
2017-12-10 03:10:10,512 [salt.minion      ][INFO    ][24525] Starting a new job with PID 24525
2017-12-10 03:10:10,516 [salt.minion      ][INFO    ][24525] Returning information for job: 20171210031010495446
2017-12-10 03:11:30,681 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171210031130670771
2017-12-10 03:11:30,699 [salt.minion      ][INFO    ][24549] Starting a new job with PID 24549
2017-12-10 03:11:34,080 [salt.state       ][INFO    ][24549] Loading fresh modules for state activity
2017-12-10 03:11:34,219 [salt.state       ][ERROR   ][24549] No contents found in top file
2017-12-10 03:11:34,221 [salt.utils.extmods][INFO    ][24549] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-12-10 03:11:34,224 [salt.utils.extmods][INFO    ][24549] Syncing beacons for environment 'base'
2017-12-10 03:11:34,224 [salt.utils.extmods][INFO    ][24549] Loading cache from salt://_beacons, for base)
2017-12-10 03:11:34,224 [salt.fileclient  ][INFO    ][24549] Caching directory '_beacons/' for environment 'base'
2017-12-10 03:11:34,397 [salt.utils.extmods][INFO    ][24549] Syncing modules for environment 'base'
2017-12-10 03:11:34,397 [salt.utils.extmods][INFO    ][24549] Loading cache from salt://_modules, for base)
2017-12-10 03:11:34,397 [salt.fileclient  ][INFO    ][24549] Caching directory '_modules/' for environment 'base'
2017-12-10 03:11:34,462 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/avinetworks.py'
2017-12-10 03:11:34,487 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-12-10 03:11:34,553 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-12-10 03:11:34,592 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-12-10 03:11:34,618 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-12-10 03:11:34,645 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-12-10 03:11:34,670 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-12-10 03:11:34,682 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-12-10 03:11:34,725 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-12-10 03:11:34,748 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-12-10 03:11:34,800 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-12-10 03:11:34,833 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-12-10 03:11:34,882 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-12-10 03:11:34,897 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-12-10 03:11:34,915 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-12-10 03:11:34,939 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-12-10 03:11:34,971 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-12-10 03:11:34,987 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-12-10 03:11:34,997 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-12-10 03:11:35,042 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-12-10 03:11:35,079 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-12-10 03:11:35,101 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-12-10 03:11:35,135 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-12-10 03:11:35,169 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-12-10 03:11:35,209 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-12-10 03:11:35,247 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-12-10 03:11:35,257 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-12-10 03:11:35,300 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-12-10 03:11:35,311 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-12-10 03:11:35,367 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-12-10 03:11:35,404 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-12-10 03:11:35,413 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-12-10 03:11:35,427 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-12-10 03:11:35,481 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-12-10 03:11:35,485 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-12-10 03:11:35,485 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/avinetworks.py' to '/var/cache/salt/minion/extmods/modules/avinetworks.py'
2017-12-10 03:11:35,485 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-12-10 03:11:35,486 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-12-10 03:11:35,491 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-12-10 03:11:35,492 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-12-10 03:11:35,492 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-12-10 03:11:35,493 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-12-10 03:11:35,498 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-12-10 03:11:35,498 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-12-10 03:11:35,498 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-12-10 03:11:35,499 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-12-10 03:11:35,499 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-12-10 03:11:35,499 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-12-10 03:11:35,500 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-12-10 03:11:35,500 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-12-10 03:11:35,500 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-12-10 03:11:35,501 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-12-10 03:11:35,501 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-12-10 03:11:35,502 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-12-10 03:11:35,502 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-12-10 03:11:35,502 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-12-10 03:11:35,502 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-12-10 03:11:35,503 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-12-10 03:11:35,504 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-12-10 03:11:35,504 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-12-10 03:11:35,504 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-12-10 03:11:35,505 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-12-10 03:11:35,505 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-12-10 03:11:35,505 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-12-10 03:11:35,505 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-12-10 03:11:35,506 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-12-10 03:11:35,506 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-12-10 03:11:35,506 [salt.utils.extmods][INFO    ][24549] 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-12-10 03:11:35,506 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-12-10 03:11:35,507 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-12-10 03:11:35,512 [salt.utils.extmods][INFO    ][24549] Syncing states for environment 'base'
2017-12-10 03:11:35,512 [salt.utils.extmods][INFO    ][24549] Loading cache from salt://_states, for base)
2017-12-10 03:11:35,512 [salt.fileclient  ][INFO    ][24549] Caching directory '_states/' for environment 'base'
2017-12-10 03:11:35,557 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/avinetworks.py'
2017-12-10 03:11:35,616 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-12-10 03:11:35,632 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-12-10 03:11:35,659 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-12-10 03:11:35,669 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-12-10 03:11:35,708 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-12-10 03:11:35,734 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-12-10 03:11:35,797 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-12-10 03:11:35,884 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-12-10 03:11:35,953 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/helm_repos.py'
2017-12-10 03:11:35,974 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-12-10 03:11:35,998 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-12-10 03:11:36,011 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-12-10 03:11:36,037 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/jenkins_globalenvprop.py'
2017-12-10 03:11:36,082 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-12-10 03:11:36,093 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-12-10 03:11:36,481 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-12-10 03:11:36,488 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-12-10 03:11:36,496 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-12-10 03:11:36,519 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-12-10 03:11:36,529 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-12-10 03:11:36,547 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-12-10 03:11:36,853 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-12-10 03:11:36,861 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-12-10 03:11:36,869 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-12-10 03:11:36,876 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-12-10 03:11:37,167 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-12-10 03:11:37,237 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-12-10 03:11:37,314 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-12-10 03:11:37,337 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-12-10 03:11:37,398 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-12-10 03:11:37,406 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-12-10 03:11:37,409 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/avinetworks.py' to '/var/cache/salt/minion/extmods/states/avinetworks.py'
2017-12-10 03:11:37,409 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-12-10 03:11:37,409 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-12-10 03:11:37,409 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-12-10 03:11:37,409 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-12-10 03:11:37,410 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-12-10 03:11:37,410 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-12-10 03:11:37,410 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-12-10 03:11:37,410 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-12-10 03:11:37,411 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/helm_repos.py' to '/var/cache/salt/minion/extmods/states/helm_repos.py'
2017-12-10 03:11:37,411 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-12-10 03:11:37,411 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-12-10 03:11:37,411 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-12-10 03:11:37,411 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/jenkins_globalenvprop.py' to '/var/cache/salt/minion/extmods/states/jenkins_globalenvprop.py'
2017-12-10 03:11:37,412 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-12-10 03:11:37,412 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-12-10 03:11:37,412 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-12-10 03:11:37,412 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-12-10 03:11:37,412 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-12-10 03:11:37,413 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-12-10 03:11:37,413 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-12-10 03:11:37,413 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-12-10 03:11:37,413 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-12-10 03:11:37,413 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-12-10 03:11:37,414 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-12-10 03:11:37,414 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-12-10 03:11:37,414 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-12-10 03:11:37,414 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-12-10 03:11:37,414 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-12-10 03:11:37,415 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-12-10 03:11:37,415 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-12-10 03:11:37,415 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-12-10 03:11:37,415 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-12-10 03:11:37,417 [salt.utils.extmods][INFO    ][24549] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-12-10 03:11:37,420 [salt.utils.extmods][INFO    ][24549] Syncing sdb for environment 'base'
2017-12-10 03:11:37,420 [salt.utils.extmods][INFO    ][24549] Loading cache from salt://_sdb, for base)
2017-12-10 03:11:37,420 [salt.fileclient  ][INFO    ][24549] Caching directory '_sdb/' for environment 'base'
2017-12-10 03:11:37,441 [salt.utils.extmods][INFO    ][24549] Syncing grains for environment 'base'
2017-12-10 03:11:37,441 [salt.utils.extmods][INFO    ][24549] Loading cache from salt://_grains, for base)
2017-12-10 03:11:37,441 [salt.fileclient  ][INFO    ][24549] Caching directory '_grains/' for environment 'base'
2017-12-10 03:11:37,469 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-12-10 03:11:37,647 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-12-10 03:11:37,713 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-12-10 03:11:37,753 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-12-10 03:11:37,802 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-12-10 03:11:37,849 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-12-10 03:11:37,895 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-12-10 03:11:37,934 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-12-10 03:11:37,945 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-12-10 03:11:37,1000 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-12-10 03:11:38,060 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-12-10 03:11:38,443 [salt.fileclient  ][INFO    ][24549] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-12-10 03:11:38,446 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-12-10 03:11:38,458 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-12-10 03:11:38,458 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-12-10 03:11:38,459 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-12-10 03:11:38,459 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-12-10 03:11:38,459 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-12-10 03:11:38,473 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-12-10 03:11:38,473 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-12-10 03:11:38,474 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-12-10 03:11:38,474 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-12-10 03:11:38,475 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-12-10 03:11:38,475 [salt.utils.extmods][INFO    ][24549] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-12-10 03:11:38,476 [salt.utils.extmods][INFO    ][24549] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-12-10 03:11:38,479 [salt.utils.extmods][INFO    ][24549] Syncing renderers for environment 'base'
2017-12-10 03:11:38,479 [salt.utils.extmods][INFO    ][24549] Loading cache from salt://_renderers, for base)
2017-12-10 03:11:38,479 [salt.fileclient  ][INFO    ][24549] Caching directory '_renderers/' for environment 'base'
2017-12-10 03:11:38,503 [salt.utils.extmods][INFO    ][24549] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-12-10 03:11:38,505 [salt.utils.extmods][INFO    ][24549] Syncing returners for environment 'base'
2017-12-10 03:11:38,506 [salt.utils.extmods][INFO    ][24549] Loading cache from salt://_returners, for base)
2017-12-10 03:11:38,506 [salt.fileclient  ][INFO    ][24549] Caching directory '_returners/' for environment 'base'
2017-12-10 03:11:38,527 [salt.utils.extmods][INFO    ][24549] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-12-10 03:11:38,529 [salt.utils.extmods][INFO    ][24549] Syncing output for environment 'base'
2017-12-10 03:11:38,529 [salt.utils.extmods][INFO    ][24549] Loading cache from salt://_output, for base)
2017-12-10 03:11:38,529 [salt.fileclient  ][INFO    ][24549] Caching directory '_output/' for environment 'base'
2017-12-10 03:11:38,551 [salt.utils.extmods][INFO    ][24549] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-12-10 03:11:38,554 [salt.utils.extmods][INFO    ][24549] Syncing utils for environment 'base'
2017-12-10 03:11:38,554 [salt.utils.extmods][INFO    ][24549] Loading cache from salt://_utils, for base)
2017-12-10 03:11:38,554 [salt.fileclient  ][INFO    ][24549] Caching directory '_utils/' for environment 'base'
2017-12-10 03:11:38,585 [salt.utils.extmods][INFO    ][24549] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-12-10 03:11:38,587 [salt.utils.extmods][INFO    ][24549] Syncing log_handlers for environment 'base'
2017-12-10 03:11:38,588 [salt.utils.extmods][INFO    ][24549] Loading cache from salt://_log_handlers, for base)
2017-12-10 03:11:38,588 [salt.fileclient  ][INFO    ][24549] Caching directory '_log_handlers/' for environment 'base'
2017-12-10 03:11:38,609 [salt.utils.extmods][INFO    ][24549] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-12-10 03:11:38,611 [salt.utils.extmods][INFO    ][24549] Syncing proxy for environment 'base'
2017-12-10 03:11:38,611 [salt.utils.extmods][INFO    ][24549] Loading cache from salt://_proxy, for base)
2017-12-10 03:11:38,611 [salt.fileclient  ][INFO    ][24549] Caching directory '_proxy/' for environment 'base'
2017-12-10 03:11:38,637 [salt.utils.extmods][INFO    ][24549] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-12-10 03:11:38,639 [salt.utils.extmods][INFO    ][24549] Syncing engines for environment 'base'
2017-12-10 03:11:38,639 [salt.utils.extmods][INFO    ][24549] Loading cache from salt://_engines, for base)
2017-12-10 03:11:38,639 [salt.fileclient  ][INFO    ][24549] Caching directory '_engines/' for environment 'base'
2017-12-10 03:11:38,668 [salt.minion      ][INFO    ][24549] Returning information for job: 20171210031130670771
2017-12-10 03:17:03,050 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command test.ping with jid 20171210031703047788
2017-12-10 03:17:03,068 [salt.minion      ][INFO    ][24714] Starting a new job with PID 24714
2017-12-10 03:17:03,146 [salt.minion      ][INFO    ][24714] Returning information for job: 20171210031703047788
2017-12-10 03:20:53,823 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command state.sls with jid 20171210032053814726
2017-12-10 03:20:53,838 [salt.minion      ][INFO    ][24769] Starting a new job with PID 24769
2017-12-10 03:20:55,403 [salt.state       ][INFO    ][24769] Loading fresh modules for state activity
2017-12-10 03:20:55,428 [salt.fileclient  ][INFO    ][24769] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-12-10 03:20:55,445 [salt.fileclient  ][INFO    ][24769] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-12-10 03:20:55,469 [salt.fileclient  ][INFO    ][24769] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-10 03:20:55,758 [salt.state       ][INFO    ][24769] Running state [lsof] at time 03:20:55.757591
2017-12-10 03:20:55,758 [salt.state       ][INFO    ][24769] Executing state pkg.installed for lsof
2017-12-10 03:20:55,758 [salt.loaded.int.module.cmdmod][INFO    ][24769] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-10 03:20:56,045 [salt.state       ][INFO    ][24769] Package lsof is already installed
2017-12-10 03:20:56,045 [salt.state       ][INFO    ][24769] Completed state [lsof] at time 03:20:56.044912 duration_in_ms=287.32
2017-12-10 03:20:56,045 [salt.state       ][INFO    ][24769] Running state [keepalived] at time 03:20:56.045176
2017-12-10 03:20:56,045 [salt.state       ][INFO    ][24769] Executing state pkg.installed for keepalived
2017-12-10 03:20:56,060 [salt.loaded.int.module.cmdmod][INFO    ][24769] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-12-10 03:20:59,196 [salt.loaded.int.module.cmdmod][INFO    ][24769] 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-12-10 03:21:03,866 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210032103857719
2017-12-10 03:21:03,879 [salt.minion      ][INFO    ][25904] Starting a new job with PID 25904
2017-12-10 03:21:03,931 [salt.minion      ][INFO    ][25904] Returning information for job: 20171210032103857719
2017-12-10 03:21:07,105 [salt.loaded.int.module.cmdmod][INFO    ][24769] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-10 03:21:07,127 [salt.state       ][INFO    ][24769] 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-12-10 03:21:07,135 [salt.state       ][INFO    ][24769] Loading fresh modules for state activity
2017-12-10 03:21:07,146 [salt.state       ][INFO    ][24769] Completed state [keepalived] at time 03:21:07.146351 duration_in_ms=11101.175
2017-12-10 03:21:07,148 [salt.state       ][INFO    ][24769] Running state [/etc/keepalived/keepalived.conf] at time 03:21:07.148048
2017-12-10 03:21:07,148 [salt.state       ][INFO    ][24769] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-12-10 03:21:07,178 [salt.fileclient  ][INFO    ][24769] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-12-10 03:21:07,210 [salt.fileclient  ][INFO    ][24769] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-10 03:21:07,217 [salt.state       ][INFO    ][24769] File changed:
New file
2017-12-10 03:21:07,217 [salt.state       ][INFO    ][24769] Completed state [/etc/keepalived/keepalived.conf] at time 03:21:07.216816 duration_in_ms=68.768
2017-12-10 03:21:07,274 [salt.state       ][INFO    ][24769] Running state [keepalived] at time 03:21:07.274064
2017-12-10 03:21:07,274 [salt.state       ][INFO    ][24769] Executing state service.running for keepalived
2017-12-10 03:21:07,276 [salt.loaded.int.module.cmdmod][INFO    ][24769] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-12-10 03:21:07,285 [salt.loaded.int.module.cmdmod][INFO    ][24769] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-12-10 03:21:07,292 [salt.loaded.int.module.cmdmod][INFO    ][24769] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-10 03:21:07,298 [salt.loaded.int.module.cmdmod][INFO    ][24769] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-10 03:21:07,306 [salt.loaded.int.module.cmdmod][INFO    ][24769] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-12-10 03:21:07,365 [salt.loaded.int.module.cmdmod][INFO    ][24769] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-12-10 03:21:07,373 [salt.loaded.int.module.cmdmod][INFO    ][24769] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-10 03:21:07,380 [salt.loaded.int.module.cmdmod][INFO    ][24769] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-10 03:21:07,387 [salt.state       ][INFO    ][24769] {'keepalived': True}
2017-12-10 03:21:07,387 [salt.state       ][INFO    ][24769] Completed state [keepalived] at time 03:21:07.387390 duration_in_ms=113.327
2017-12-10 03:21:07,388 [salt.minion      ][INFO    ][24769] Returning information for job: 20171210032053814726
2017-12-10 03:21:08,043 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command pillar.get with jid 20171210032108039743
2017-12-10 03:21:08,067 [salt.minion      ][INFO    ][26202] Starting a new job with PID 26202
2017-12-10 03:21:08,074 [salt.minion      ][INFO    ][26202] Returning information for job: 20171210032108039743
2017-12-10 03:26:21,791 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command state.sls with jid 20171210032621783991
2017-12-10 03:26:21,806 [salt.minion      ][INFO    ][26291] Starting a new job with PID 26291
2017-12-10 03:26:24,289 [salt.state       ][INFO    ][26291] Loading fresh modules for state activity
2017-12-10 03:26:24,311 [salt.fileclient  ][INFO    ][26291] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-12-10 03:26:24,331 [salt.fileclient  ][INFO    ][26291] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-12-10 03:26:24,594 [salt.state       ][INFO    ][26291] Running state [attr] at time 03:26:24.593973
2017-12-10 03:26:24,594 [salt.state       ][INFO    ][26291] Executing state pkg.installed for attr
2017-12-10 03:26:24,595 [salt.loaded.int.module.cmdmod][INFO    ][26291] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-10 03:26:24,863 [salt.loaded.int.module.cmdmod][INFO    ][26291] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-12-10 03:26:26,352 [salt.loaded.int.module.cmdmod][INFO    ][26291] 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-12-10 03:26:28,936 [salt.loaded.int.module.cmdmod][INFO    ][26291] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-10 03:26:28,960 [salt.state       ][INFO    ][26291] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-12-10 03:26:28,968 [salt.state       ][INFO    ][26291] Loading fresh modules for state activity
2017-12-10 03:26:28,980 [salt.state       ][INFO    ][26291] Completed state [attr] at time 03:26:28.980023 duration_in_ms=4386.049
2017-12-10 03:26:28,985 [salt.state       ][INFO    ][26291] Running state [glusterfs-server] at time 03:26:28.984957
2017-12-10 03:26:28,985 [salt.state       ][INFO    ][26291] Executing state pkg.installed for glusterfs-server
2017-12-10 03:26:29,169 [salt.loaded.int.module.cmdmod][INFO    ][26291] 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-12-10 03:26:31,885 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210032631875655
2017-12-10 03:26:31,900 [salt.minion      ][INFO    ][27129] Starting a new job with PID 27129
2017-12-10 03:26:31,911 [salt.minion      ][INFO    ][27129] Returning information for job: 20171210032631875655
2017-12-10 03:26:42,091 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command saltutil.find_job with jid 20171210032642082278
2017-12-10 03:26:42,105 [salt.minion      ][INFO    ][28038] Starting a new job with PID 28038
2017-12-10 03:26:42,118 [salt.minion      ][INFO    ][28038] Returning information for job: 20171210032642082278
2017-12-10 03:26:48,481 [salt.loaded.int.module.cmdmod][INFO    ][26291] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-10 03:26:48,505 [salt.state       ][INFO    ][26291] 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'
'libpython2.7' changed from 'absent' to '2.7.12-1ubuntu0~16.04.2'
'glusterfs-client' changed from 'absent' to '3.10.8-ubuntu1~xenial1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'glusterfs-common' changed from 'absent' to '3.10.8-ubuntu1~xenial1'
'liburcu4' changed from 'absent' to '0.9.1-3'
'portmap' changed from 'absent' to '1'
'libnfsidmap2' changed from 'absent' to '0.25-5'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libtirpc1' changed from 'absent' to '0.2.5-1'
'nfs-client' changed from 'absent' to '1'
'glusterfs-server' changed from 'absent' to '3.10.8-ubuntu1~xenial1'

2017-12-10 03:26:48,512 [salt.state       ][INFO    ][26291] Loading fresh modules for state activity
2017-12-10 03:26:48,524 [salt.state       ][INFO    ][26291] Completed state [glusterfs-server] at time 03:26:48.524239 duration_in_ms=19539.281
2017-12-10 03:26:48,529 [salt.state       ][INFO    ][26291] Running state [bc] at time 03:26:48.528572
2017-12-10 03:26:48,529 [salt.state       ][INFO    ][26291] Executing state pkg.installed for bc
2017-12-10 03:26:48,779 [salt.loaded.int.module.cmdmod][INFO    ][26291] 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-12-10 03:26:51,496 [salt.loaded.int.module.cmdmod][INFO    ][26291] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-10 03:26:51,523 [salt.state       ][INFO    ][26291] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-12-10 03:26:51,532 [salt.state       ][INFO    ][26291] Loading fresh modules for state activity
2017-12-10 03:26:51,546 [salt.state       ][INFO    ][26291] Completed state [bc] at time 03:26:51.545686 duration_in_ms=3017.113
2017-12-10 03:26:51,609 [salt.state       ][INFO    ][26291] Running state [glusterfs-server] at time 03:26:51.609450
2017-12-10 03:26:51,610 [salt.state       ][INFO    ][26291] Executing state service.running for glusterfs-server
2017-12-10 03:26:51,611 [salt.loaded.int.module.cmdmod][INFO    ][26291] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-12-10 03:26:51,620 [salt.loaded.int.module.cmdmod][INFO    ][26291] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-12-10 03:26:51,626 [salt.loaded.int.module.cmdmod][INFO    ][26291] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-12-10 03:26:51,634 [salt.state       ][INFO    ][26291] The service glusterfs-server is already running
2017-12-10 03:26:51,634 [salt.state       ][INFO    ][26291] Completed state [glusterfs-server] at time 03:26:51.633735 duration_in_ms=24.285
2017-12-10 03:26:51,635 [salt.state       ][INFO    ][26291] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 03:26:51.635035
2017-12-10 03:26:51,635 [salt.state       ][INFO    ][26291] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-12-10 03:26:51,648 [salt.state       ][INFO    ][26291] File changed:
New file
2017-12-10 03:26:51,648 [salt.state       ][INFO    ][26291] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 03:26:51.648359 duration_in_ms=13.323
2017-12-10 03:26:51,649 [salt.state       ][INFO    ][26291] Running state [/srv/glusterfs/glance] at time 03:26:51.648537
2017-12-10 03:26:51,649 [salt.state       ][INFO    ][26291] Executing state file.directory for /srv/glusterfs/glance
2017-12-10 03:26:51,664 [salt.state       ][INFO    ][26291] {'/srv/glusterfs/glance': 'New Dir'}
2017-12-10 03:26:51,664 [salt.state       ][INFO    ][26291] Completed state [/srv/glusterfs/glance] at time 03:26:51.664104 duration_in_ms=15.566
2017-12-10 03:26:51,664 [salt.state       ][INFO    ][26291] Running state [/srv/glusterfs/keystone-keys] at time 03:26:51.664262
2017-12-10 03:26:51,664 [salt.state       ][INFO    ][26291] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-12-10 03:26:51,665 [salt.state       ][INFO    ][26291] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-12-10 03:26:51,665 [salt.state       ][INFO    ][26291] Completed state [/srv/glusterfs/keystone-keys] at time 03:26:51.664949 duration_in_ms=0.688
2017-12-10 03:26:51,665 [salt.state       ][INFO    ][26291] Running state [/srv/glusterfs/nova_instances] at time 03:26:51.665085
2017-12-10 03:26:51,665 [salt.state       ][INFO    ][26291] Executing state file.directory for /srv/glusterfs/nova_instances
2017-12-10 03:26:51,666 [salt.state       ][INFO    ][26291] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-12-10 03:26:51,666 [salt.state       ][INFO    ][26291] Completed state [/srv/glusterfs/nova_instances] at time 03:26:51.665667 duration_in_ms=0.582
2017-12-10 03:26:51,666 [salt.state       ][INFO    ][26291] Running state [/srv/glusterfs/keystone-credential-keys] at time 03:26:51.665811
2017-12-10 03:26:51,666 [salt.state       ][INFO    ][26291] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-12-10 03:26:51,666 [salt.state       ][INFO    ][26291] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-12-10 03:26:51,667 [salt.state       ][INFO    ][26291] Completed state [/srv/glusterfs/keystone-credential-keys] at time 03:26:51.666587 duration_in_ms=0.775
2017-12-10 03:26:51,668 [salt.minion      ][INFO    ][26291] Returning information for job: 20171210032621783991
2017-12-10 03:26:52,410 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command test.ping with jid 20171210032652408292
2017-12-10 03:26:52,422 [salt.minion      ][INFO    ][29108] Starting a new job with PID 29108
2017-12-10 03:26:52,448 [salt.minion      ][INFO    ][29108] Returning information for job: 20171210032652408292
2017-12-10 03:27:35,386 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command state.sls with jid 20171210032735377071
2017-12-10 03:27:35,400 [salt.minion      ][INFO    ][29746] Starting a new job with PID 29746
2017-12-10 03:27:35,848 [salt.state       ][INFO    ][29746] Loading fresh modules for state activity
2017-12-10 03:27:35,871 [salt.fileclient  ][INFO    ][29746] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-12-10 03:27:35,906 [salt.fileclient  ][INFO    ][29746] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-12-10 03:27:35,976 [salt.fileclient  ][INFO    ][29746] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-12-10 03:27:35,995 [salt.fileclient  ][INFO    ][29746] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-12-10 03:27:36,269 [salt.state       ][INFO    ][29746] Running state [attr] at time 03:27:36.268797
2017-12-10 03:27:36,269 [salt.state       ][INFO    ][29746] Executing state pkg.installed for attr
2017-12-10 03:27:36,269 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-10 03:27:36,558 [salt.state       ][INFO    ][29746] Package attr is already installed
2017-12-10 03:27:36,558 [salt.state       ][INFO    ][29746] Completed state [attr] at time 03:27:36.557824 duration_in_ms=289.028
2017-12-10 03:27:36,558 [salt.state       ][INFO    ][29746] Running state [glusterfs-server] at time 03:27:36.558019
2017-12-10 03:27:36,558 [salt.state       ][INFO    ][29746] Executing state pkg.installed for glusterfs-server
2017-12-10 03:27:36,561 [salt.state       ][INFO    ][29746] Package glusterfs-server is already installed
2017-12-10 03:27:36,561 [salt.state       ][INFO    ][29746] Completed state [glusterfs-server] at time 03:27:36.560828 duration_in_ms=2.808
2017-12-10 03:27:36,561 [salt.state       ][INFO    ][29746] Running state [bc] at time 03:27:36.560962
2017-12-10 03:27:36,561 [salt.state       ][INFO    ][29746] Executing state pkg.installed for bc
2017-12-10 03:27:36,564 [salt.state       ][INFO    ][29746] Package bc is already installed
2017-12-10 03:27:36,564 [salt.state       ][INFO    ][29746] Completed state [bc] at time 03:27:36.563715 duration_in_ms=2.752
2017-12-10 03:27:36,565 [salt.state       ][INFO    ][29746] Running state [glusterfs-server] at time 03:27:36.564500
2017-12-10 03:27:36,565 [salt.state       ][INFO    ][29746] Executing state service.running for glusterfs-server
2017-12-10 03:27:36,565 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-12-10 03:27:36,574 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-12-10 03:27:36,617 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-12-10 03:27:36,631 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-12-10 03:27:36,639 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-12-10 03:27:36,647 [salt.state       ][INFO    ][29746] The service glusterfs-server is already running
2017-12-10 03:27:36,647 [salt.state       ][INFO    ][29746] Completed state [glusterfs-server] at time 03:27:36.647123 duration_in_ms=82.622
2017-12-10 03:27:36,649 [salt.state       ][INFO    ][29746] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 03:27:36.648560
2017-12-10 03:27:36,649 [salt.state       ][INFO    ][29746] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-12-10 03:27:36,657 [salt.state       ][INFO    ][29746] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-12-10 03:27:36,657 [salt.state       ][INFO    ][29746] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 03:27:36.656743 duration_in_ms=8.183
2017-12-10 03:27:36,657 [salt.state       ][INFO    ][29746] Running state [/srv/glusterfs/glance] at time 03:27:36.656906
2017-12-10 03:27:36,657 [salt.state       ][INFO    ][29746] Executing state file.directory for /srv/glusterfs/glance
2017-12-10 03:27:36,657 [salt.state       ][INFO    ][29746] Directory /srv/glusterfs/glance is in the correct state
2017-12-10 03:27:36,658 [salt.state       ][INFO    ][29746] Completed state [/srv/glusterfs/glance] at time 03:27:36.657535 duration_in_ms=0.629
2017-12-10 03:27:36,658 [salt.state       ][INFO    ][29746] Running state [/srv/glusterfs/keystone-keys] at time 03:27:36.657686
2017-12-10 03:27:36,658 [salt.state       ][INFO    ][29746] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-12-10 03:27:36,658 [salt.state       ][INFO    ][29746] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-12-10 03:27:36,658 [salt.state       ][INFO    ][29746] Completed state [/srv/glusterfs/keystone-keys] at time 03:27:36.658275 duration_in_ms=0.588
2017-12-10 03:27:36,658 [salt.state       ][INFO    ][29746] Running state [/srv/glusterfs/nova_instances] at time 03:27:36.658422
2017-12-10 03:27:36,659 [salt.state       ][INFO    ][29746] Executing state file.directory for /srv/glusterfs/nova_instances
2017-12-10 03:27:36,659 [salt.state       ][INFO    ][29746] Directory /srv/glusterfs/nova_instances is in the correct state
2017-12-10 03:27:36,659 [salt.state       ][INFO    ][29746] Completed state [/srv/glusterfs/nova_instances] at time 03:27:36.659051 duration_in_ms=0.629
2017-12-10 03:27:36,659 [salt.state       ][INFO    ][29746] Running state [/srv/glusterfs/keystone-credential-keys] at time 03:27:36.659211
2017-12-10 03:27:36,659 [salt.state       ][INFO    ][29746] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-12-10 03:27:36,660 [salt.state       ][INFO    ][29746] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-12-10 03:27:36,660 [salt.state       ][INFO    ][29746] Completed state [/srv/glusterfs/keystone-credential-keys] at time 03:27:36.659797 duration_in_ms=0.586
2017-12-10 03:27:36,715 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command ['git', '--version'] in directory '/root'
2017-12-10 03:27:36,797 [salt.state       ][INFO    ][29746] Running state [sleep 5] at time 03:27:36.797339
2017-12-10 03:27:36,798 [salt.state       ][INFO    ][29746] Executing state cmd.wait for sleep 5
2017-12-10 03:27:36,798 [salt.state       ][INFO    ][29746] No changes made for sleep 5
2017-12-10 03:27:36,798 [salt.state       ][INFO    ][29746] Completed state [sleep 5] at time 03:27:36.797904 duration_in_ms=0.565
2017-12-10 03:27:36,798 [salt.state       ][INFO    ][29746] Running state [10.167.4.141] at time 03:27:36.798296
2017-12-10 03:27:36,798 [salt.state       ][INFO    ][29746] Executing state glusterfs.peered for 10.167.4.141
2017-12-10 03:27:36,799 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:36,809 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:36,864 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:36,873 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:36,925 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:36,934 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:36,986 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:36,995 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:37,045 [salt.state       ][INFO    ][29746] Peering with localhost is not needed
2017-12-10 03:27:37,045 [salt.state       ][INFO    ][29746] Completed state [10.167.4.141] at time 03:27:37.044825 duration_in_ms=246.527
2017-12-10 03:27:37,047 [salt.state       ][INFO    ][29746] Running state [10.167.4.142] at time 03:27:37.047061
2017-12-10 03:27:37,047 [salt.state       ][INFO    ][29746] Executing state glusterfs.peered for 10.167.4.142
2017-12-10 03:27:37,048 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:37,056 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:37,106 [salt.state       ][INFO    ][29746] Host 10.167.4.142 already peered
2017-12-10 03:27:37,106 [salt.state       ][INFO    ][29746] Completed state [10.167.4.142] at time 03:27:37.106378 duration_in_ms=59.316
2017-12-10 03:27:37,109 [salt.state       ][INFO    ][29746] Running state [10.167.4.143] at time 03:27:37.108642
2017-12-10 03:27:37,109 [salt.state       ][INFO    ][29746] Executing state glusterfs.peered for 10.167.4.143
2017-12-10 03:27:37,109 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:37,118 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:37,168 [salt.state       ][INFO    ][29746] Host 10.167.4.143 already peered
2017-12-10 03:27:37,168 [salt.state       ][INFO    ][29746] Completed state [10.167.4.143] at time 03:27:37.168414 duration_in_ms=59.771
2017-12-10 03:27:37,169 [salt.state       ][INFO    ][29746] Running state [glance] at time 03:27:37.169223
2017-12-10 03:27:37,169 [salt.state       ][INFO    ][29746] Executing state glusterfs.created for glance
2017-12-10 03:27:37,170 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:37,178 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:37,228 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:37,236 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:37,293 [salt.state       ][INFO    ][29746] Volume glance already exists and is started.
2017-12-10 03:27:37,293 [salt.state       ][INFO    ][29746] Completed state [glance] at time 03:27:37.293360 duration_in_ms=124.137
2017-12-10 03:27:37,296 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 03:27:37.296408
2017-12-10 03:27:37,297 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-12-10 03:27:37,297 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-10 03:27:37,347 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:37,347 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 03:27:37.347391 duration_in_ms=50.983
2017-12-10 03:27:37,348 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 03:27:37.347831
2017-12-10 03:27:37,348 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-12-10 03:27:37,349 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-10 03:27:37,401 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:37,401 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 03:27:37.401028 duration_in_ms=53.196
2017-12-10 03:27:37,401 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 03:27:37.401427
2017-12-10 03:27:37,402 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-12-10 03:27:37,402 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-12-10 03:27:37,452 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:37,453 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 03:27:37.452598 duration_in_ms=51.17
2017-12-10 03:27:37,453 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 03:27:37.453023
2017-12-10 03:27:37,453 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'
2017-12-10 03:27:37,454 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'glance' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-10 03:27:37,504 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:37,504 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 03:27:37.504263 duration_in_ms=51.24
2017-12-10 03:27:37,505 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 03:27:37.504706
2017-12-10 03:27:37,505 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-12-10 03:27:37,505 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-10 03:27:37,555 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:37,555 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 03:27:37.554762 duration_in_ms=50.055
2017-12-10 03:27:37,555 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 03:27:37.555196
2017-12-10 03:27:37,555 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-12-10 03:27:37,556 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-10 03:27:37,605 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:37,615 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 03:27:37.614696 duration_in_ms=59.5
2017-12-10 03:27:37,616 [salt.state       ][INFO    ][29746] Running state [glance] at time 03:27:37.615915
2017-12-10 03:27:37,616 [salt.state       ][INFO    ][29746] Executing state glusterfs.started for glance
2017-12-10 03:27:37,617 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:37,625 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:37,675 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:37,683 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:37,746 [salt.state       ][INFO    ][29746] Volume glance is already started
2017-12-10 03:27:37,746 [salt.state       ][INFO    ][29746] Completed state [glance] at time 03:27:37.746405 duration_in_ms=130.489
2017-12-10 03:27:37,747 [salt.state       ][INFO    ][29746] Running state [keystone-keys] at time 03:27:37.747232
2017-12-10 03:27:37,747 [salt.state       ][INFO    ][29746] Executing state glusterfs.created for keystone-keys
2017-12-10 03:27:37,748 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:37,756 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:37,807 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:37,815 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:37,868 [salt.state       ][INFO    ][29746] Volume keystone-keys already exists and is started.
2017-12-10 03:27:37,868 [salt.state       ][INFO    ][29746] Completed state [keystone-keys] at time 03:27:37.868097 duration_in_ms=120.865
2017-12-10 03:27:37,872 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 03:27:37.871576
2017-12-10 03:27:37,872 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-12-10 03:27:37,872 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-10 03:27:37,924 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:37,925 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 03:27:37.924670 duration_in_ms=53.094
2017-12-10 03:27:37,925 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 03:27:37.925117
2017-12-10 03:27:37,925 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-12-10 03:27:37,926 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-10 03:27:37,978 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:37,978 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 03:27:37.977814 duration_in_ms=52.697
2017-12-10 03:27:37,978 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 03:27:37.978269
2017-12-10 03:27:37,978 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'
2017-12-10 03:27:37,979 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-10 03:27:38,032 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:38,032 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 03:27:38.031780 duration_in_ms=53.51
2017-12-10 03:27:38,032 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 03:27:38.032378
2017-12-10 03:27:38,033 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-12-10 03:27:38,033 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-10 03:27:38,095 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:38,095 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 03:27:38.095037 duration_in_ms=62.659
2017-12-10 03:27:38,096 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 03:27:38.095595
2017-12-10 03:27:38,096 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-12-10 03:27:38,096 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-12-10 03:27:38,150 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:38,151 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 03:27:38.150592 duration_in_ms=54.997
2017-12-10 03:27:38,151 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 03:27:38.151025
2017-12-10 03:27:38,151 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-12-10 03:27:38,152 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-10 03:27:38,204 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:38,205 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 03:27:38.204580 duration_in_ms=53.555
2017-12-10 03:27:38,206 [salt.state       ][INFO    ][29746] Running state [keystone-keys] at time 03:27:38.205736
2017-12-10 03:27:38,206 [salt.state       ][INFO    ][29746] Executing state glusterfs.started for keystone-keys
2017-12-10 03:27:38,206 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:38,214 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:38,266 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:38,275 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:38,335 [salt.state       ][INFO    ][29746] Volume keystone-keys is already started
2017-12-10 03:27:38,335 [salt.state       ][INFO    ][29746] Completed state [keystone-keys] at time 03:27:38.334954 duration_in_ms=129.217
2017-12-10 03:27:38,336 [salt.state       ][INFO    ][29746] Running state [nova_instances] at time 03:27:38.336197
2017-12-10 03:27:38,337 [salt.state       ][INFO    ][29746] Executing state glusterfs.created for nova_instances
2017-12-10 03:27:38,337 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:38,347 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:38,400 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:38,408 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:38,465 [salt.state       ][INFO    ][29746] Volume nova_instances already exists and is started.
2017-12-10 03:27:38,466 [salt.state       ][INFO    ][29746] Completed state [nova_instances] at time 03:27:38.465500 duration_in_ms=129.303
2017-12-10 03:27:38,469 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 03:27:38.469124
2017-12-10 03:27:38,469 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-12-10 03:27:38,470 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-10 03:27:38,523 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:38,523 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 03:27:38.522777 duration_in_ms=53.652
2017-12-10 03:27:38,523 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 03:27:38.523262
2017-12-10 03:27:38,524 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'
2017-12-10 03:27:38,524 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-10 03:27:38,579 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:38,579 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 03:27:38.579129 duration_in_ms=55.866
2017-12-10 03:27:38,580 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 03:27:38.579603
2017-12-10 03:27:38,580 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'True'
2017-12-10 03:27:38,580 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-10 03:27:38,633 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:38,634 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 03:27:38.633475 duration_in_ms=53.871
2017-12-10 03:27:38,634 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 03:27:38.633944
2017-12-10 03:27:38,634 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'
2017-12-10 03:27:38,635 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-10 03:27:38,685 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:38,686 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 03:27:38.685629 duration_in_ms=51.684
2017-12-10 03:27:38,686 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 03:27:38.686405
2017-12-10 03:27:38,687 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'True'
2017-12-10 03:27:38,687 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: True'' in directory '/root'
2017-12-10 03:27:38,744 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:38,744 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 03:27:38.743893 duration_in_ms=57.488
2017-12-10 03:27:38,744 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 03:27:38.744380
2017-12-10 03:27:38,745 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-12-10 03:27:38,745 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-10 03:27:38,798 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:38,798 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 03:27:38.798378 duration_in_ms=53.998
2017-12-10 03:27:38,800 [salt.state       ][INFO    ][29746] Running state [nova_instances] at time 03:27:38.799481
2017-12-10 03:27:38,800 [salt.state       ][INFO    ][29746] Executing state glusterfs.started for nova_instances
2017-12-10 03:27:38,800 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:38,808 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:38,860 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:38,868 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:38,924 [salt.state       ][INFO    ][29746] Volume nova_instances is already started
2017-12-10 03:27:38,924 [salt.state       ][INFO    ][29746] Completed state [nova_instances] at time 03:27:38.923770 duration_in_ms=124.288
2017-12-10 03:27:38,925 [salt.state       ][INFO    ][29746] Running state [keystone-credential-keys] at time 03:27:38.924709
2017-12-10 03:27:38,925 [salt.state       ][INFO    ][29746] Executing state glusterfs.created for keystone-credential-keys
2017-12-10 03:27:38,925 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:38,934 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:38,985 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:38,993 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:39,048 [salt.state       ][INFO    ][29746] Volume keystone-credential-keys already exists and is started.
2017-12-10 03:27:39,048 [salt.state       ][INFO    ][29746] Completed state [keystone-credential-keys] at time 03:27:39.048406 duration_in_ms=123.697
2017-12-10 03:27:39,052 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 03:27:39.052175
2017-12-10 03:27:39,052 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-12-10 03:27:39,053 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-10 03:27:39,105 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:39,106 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 03:27:39.105481 duration_in_ms=53.304
2017-12-10 03:27:39,106 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 03:27:39.105942
2017-12-10 03:27:39,106 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'
2017-12-10 03:27:39,107 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-10 03:27:39,157 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:39,157 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 03:27:39.156788 duration_in_ms=50.846
2017-12-10 03:27:39,157 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 03:27:39.157256
2017-12-10 03:27:39,157 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-12-10 03:27:39,158 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-10 03:27:39,210 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:39,210 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 03:27:39.209702 duration_in_ms=52.445
2017-12-10 03:27:39,210 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 03:27:39.210148
2017-12-10 03:27:39,210 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-12-10 03:27:39,211 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-12-10 03:27:39,261 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:39,261 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 03:27:39.260881 duration_in_ms=50.732
2017-12-10 03:27:39,261 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 03:27:39.261324
2017-12-10 03:27:39,262 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-12-10 03:27:39,262 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-10 03:27:39,312 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:39,312 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 03:27:39.312424 duration_in_ms=51.099
2017-12-10 03:27:39,313 [salt.state       ][INFO    ][29746] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 03:27:39.312901
2017-12-10 03:27:39,313 [salt.state       ][INFO    ][29746] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-12-10 03:27:39,314 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-10 03:27:39,364 [salt.state       ][INFO    ][29746] unless execution succeeded
2017-12-10 03:27:39,365 [salt.state       ][INFO    ][29746] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 03:27:39.364490 duration_in_ms=51.588
2017-12-10 03:27:39,366 [salt.state       ][INFO    ][29746] Running state [keystone-credential-keys] at time 03:27:39.365578
2017-12-10 03:27:39,366 [salt.state       ][INFO    ][29746] Executing state glusterfs.started for keystone-credential-keys
2017-12-10 03:27:39,366 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:39,374 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:39,424 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --version' in directory '/root'
2017-12-10 03:27:39,432 [salt.loaded.int.module.cmdmod][INFO    ][29746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-10 03:27:39,487 [salt.state       ][INFO    ][29746] Volume keystone-credential-keys is already started
2017-12-10 03:27:39,487 [salt.state       ][INFO    ][29746] Completed state [keystone-credential-keys] at time 03:27:39.487383 duration_in_ms=121.805
2017-12-10 03:27:39,490 [salt.minion      ][INFO    ][29746] Returning information for job: 20171210032735377071
2017-12-10 03:27:40,270 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command test.ping with jid 20171210032740267701
2017-12-10 03:27:40,285 [salt.minion      ][INFO    ][30283] Starting a new job with PID 30283
2017-12-10 03:27:40,312 [salt.minion      ][INFO    ][30283] Returning information for job: 20171210032740267701
2017-12-10 03:27:40,973 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command cmd.run with jid 20171210032740965761
2017-12-10 03:27:40,987 [salt.minion      ][INFO    ][30288] Starting a new job with PID 30288
2017-12-10 03:27:40,992 [salt.loaded.int.module.cmdmod][INFO    ][30288] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-12-10 03:27:41,107 [salt.minion      ][INFO    ][30288] Returning information for job: 20171210032740965761
2017-12-10 03:51:13,060 [salt.utils.schedule][INFO    ][16600] Running scheduled job: __mine_interval
2017-12-10 04:51:13,060 [salt.utils.schedule][INFO    ][16600] Running scheduled job: __mine_interval
2017-12-10 05:24:12,163 [salt.minion      ][INFO    ][16600] User sudo_ubuntu Executing command cp.push_dir with jid 20171210052412158567
2017-12-10 05:24:12,182 [salt.minion      ][INFO    ][31700] Starting a new job with PID 31700
