2017-12-05 10:22:52,953 [salt.cli.daemons ][WARNING ][2130] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-12-05 10:23:07,663 [salt.state       ][ERROR   ][3605] No contents found in top file
2017-12-05 10:24:38,643 [salt.utils.parsers][WARNING ][2130] Minion received a SIGTERM. Exiting.
2017-12-05 10:29:23,750 [salt.cli.daemons ][WARNING ][3372] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-12-05 10:29:48,264 [py.warnings      ][WARNING ][3688] /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-05 10:30:48,457 [salt.loaded.int.module.cmdmod][ERROR   ][3688] 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-05 10:30:55,280 [salt.transport.ipc][ERROR   ][3372] Exception occurred while handling stream: [Errno 0] Success
2017-12-05 10:31:25,622 [salt.loaded.int.module.cmdmod][INFO    ][15170] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-12-05 10:31:25,630 [salt.loaded.int.module.cmdmod][INFO    ][15170] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-12-05 10:31:25,640 [salt.loaded.int.module.cmdmod][INFO    ][15170] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-12-05 10:31:25,649 [salt.utils.parsers][WARNING ][3372] Minion received a SIGTERM. Exiting.
2017-12-05 10:31:25,949 [salt.cli.daemons ][INFO    ][15249] Setting up the Salt Minion "kvm01.baremetal-mcp-ocata-ovs-ha.local"
2017-12-05 10:31:26,577 [salt.minion      ][INFO    ][15249] Creating minion process manager
2017-12-05 10:31:26,577 [salt.cli.daemons ][WARNING ][15249] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-12-05 10:31:26,578 [salt.cli.daemons ][INFO    ][15249] The Salt Minion is starting up
2017-12-05 10:31:26,578 [salt.minion      ][INFO    ][15249] Minion is starting as user 'root'
2017-12-05 10:31:26,578 [salt.utils.event ][INFO    ][15249] Starting pull socket on /var/run/salt/minion/minion_event_fd678c5357_pull.ipc
2017-12-05 10:31:27,710 [salt.loaded.int.module.cmdmod][INFO    ][15249] Executing command ['date', '+%z'] in directory '/root'
2017-12-05 10:31:27,721 [salt.utils.schedule][INFO    ][15249] Updating job settings for scheduled job: __mine_interval
2017-12-05 10:31:27,724 [salt.minion      ][INFO    ][15249] Added mine.update to scheduler
2017-12-05 10:31:27,743 [salt.minion      ][INFO    ][15249] Minion is ready to receive requests!
2017-12-05 10:31:28,745 [salt.utils.schedule][INFO    ][15249] Running scheduled job: __mine_interval
2017-12-05 10:31:35,171 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.find_job with jid 20171205103135157517
2017-12-05 10:31:35,180 [salt.minion      ][INFO    ][15346] Starting a new job with PID 15346
2017-12-05 10:31:35,188 [salt.minion      ][INFO    ][15346] Returning information for job: 20171205103135157517
2017-12-05 10:31:45,347 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.find_job with jid 20171205103145334690
2017-12-05 10:31:45,356 [salt.minion      ][INFO    ][15351] Starting a new job with PID 15351
2017-12-05 10:31:45,364 [salt.minion      ][INFO    ][15351] Returning information for job: 20171205103145334690
2017-12-05 10:31:55,515 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.find_job with jid 20171205103155501555
2017-12-05 10:31:55,524 [salt.minion      ][INFO    ][15356] Starting a new job with PID 15356
2017-12-05 10:31:55,532 [salt.minion      ][INFO    ][15356] Returning information for job: 20171205103155501555
2017-12-05 10:32:05,682 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.find_job with jid 20171205103205670045
2017-12-05 10:32:05,692 [salt.minion      ][INFO    ][15362] Starting a new job with PID 15362
2017-12-05 10:32:05,701 [salt.minion      ][INFO    ][15362] Returning information for job: 20171205103205670045
2017-12-05 10:32:15,864 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.find_job with jid 20171205103215849425
2017-12-05 10:32:15,872 [salt.minion      ][INFO    ][15367] Starting a new job with PID 15367
2017-12-05 10:32:15,881 [salt.minion      ][INFO    ][15367] Returning information for job: 20171205103215849425
2017-12-05 10:32:26,087 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.find_job with jid 20171205103226049202
2017-12-05 10:32:26,097 [salt.minion      ][INFO    ][15372] Starting a new job with PID 15372
2017-12-05 10:32:26,105 [salt.minion      ][INFO    ][15372] Returning information for job: 20171205103226049202
2017-12-05 10:32:36,203 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.find_job with jid 20171205103236186475
2017-12-05 10:32:36,212 [salt.minion      ][INFO    ][15377] Starting a new job with PID 15377
2017-12-05 10:32:36,219 [salt.minion      ][INFO    ][15377] Returning information for job: 20171205103236186475
2017-12-05 10:32:46,415 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.find_job with jid 20171205103246396910
2017-12-05 10:32:46,425 [salt.minion      ][INFO    ][15382] Starting a new job with PID 15382
2017-12-05 10:32:46,432 [salt.minion      ][INFO    ][15382] Returning information for job: 20171205103246396910
2017-12-05 10:32:56,617 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.find_job with jid 20171205103256603110
2017-12-05 10:32:56,626 [salt.minion      ][INFO    ][15387] Starting a new job with PID 15387
2017-12-05 10:32:56,634 [salt.minion      ][INFO    ][15387] Returning information for job: 20171205103256603110
2017-12-05 10:33:06,649 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.find_job with jid 20171205103306634814
2017-12-05 10:33:06,659 [salt.minion      ][INFO    ][15392] Starting a new job with PID 15392
2017-12-05 10:33:06,667 [salt.minion      ][INFO    ][15392] Returning information for job: 20171205103306634814
2017-12-05 10:33:17,641 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command state.sls with jid 20171205103317627297
2017-12-05 10:33:17,651 [salt.minion      ][INFO    ][15397] Starting a new job with PID 15397
2017-12-05 10:33:20,010 [salt.state       ][INFO    ][15397] Loading fresh modules for state activity
2017-12-05 10:33:20,041 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-12-05 10:33:20,061 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-12-05 10:33:20,090 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-05 10:33:20,172 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-12-05 10:33:20,186 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-12-05 10:33:20,213 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-05 10:33:20,346 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-05 10:33:20,368 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-05 10:33:20,426 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-05 10:33:20,454 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-05 10:33:20,470 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-05 10:33:20,490 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-05 10:33:20,499 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-05 10:33:20,510 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-05 10:33:20,532 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-05 10:33:20,550 [py.warnings      ][WARNING ][15397] /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-05 10:33:20,551 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'salt-minion --version' in directory '/root'
2017-12-05 10:33:20,797 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'salt-minion --version' in directory '/root'
2017-12-05 10:33:21,055 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-05 10:33:21,078 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-05 10:33:21,093 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-05 10:33:21,109 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-05 10:33:21,155 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-05 10:33:21,170 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-05 10:33:21,194 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-05 10:33:21,209 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-05 10:33:21,230 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-05 10:33:21,273 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-05 10:33:21,342 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-12-05 10:33:21,375 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-05 10:33:21,443 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-05 10:33:21,464 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-05 10:33:21,521 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-05 10:33:21,548 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-05 10:33:21,566 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-05 10:33:21,588 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-05 10:33:21,596 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-05 10:33:21,605 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-05 10:33:21,623 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-05 10:33:21,642 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'salt-minion --version' in directory '/root'
2017-12-05 10:33:21,884 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'salt-minion --version' in directory '/root'
2017-12-05 10:33:22,142 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-05 10:33:22,166 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-05 10:33:22,182 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-05 10:33:22,198 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-05 10:33:22,245 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-05 10:33:22,263 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-05 10:33:22,292 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-05 10:33:22,311 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-05 10:33:22,334 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-05 10:33:22,377 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-05 10:33:22,465 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-12-05 10:33:22,541 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-05 10:33:22,875 [salt.state       ][INFO    ][15397] Running state [salt-minion] at time 10:33:22.875349
2017-12-05 10:33:22,876 [salt.state       ][INFO    ][15397] Executing state pkg.installed for salt-minion
2017-12-05 10:33:22,876 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-05 10:33:23,127 [salt.state       ][INFO    ][15397] Package salt-minion is already installed
2017-12-05 10:33:23,127 [salt.state       ][INFO    ][15397] Completed state [salt-minion] at time 10:33:23.127041 duration_in_ms=251.692
2017-12-05 10:33:23,127 [salt.state       ][INFO    ][15397] Running state [salt_minion_dependency_packages] at time 10:33:23.127313
2017-12-05 10:33:23,128 [salt.state       ][INFO    ][15397] Executing state pkg.installed for salt_minion_dependency_packages
2017-12-05 10:33:23,131 [salt.state       ][INFO    ][15397] All specified packages are already installed
2017-12-05 10:33:23,132 [salt.state       ][INFO    ][15397] Completed state [salt_minion_dependency_packages] at time 10:33:23.131513 duration_in_ms=4.199
2017-12-05 10:33:23,133 [salt.state       ][INFO    ][15397] Running state [/etc/salt/minion.d/minion.conf] at time 10:33:23.133187
2017-12-05 10:33:23,133 [salt.state       ][INFO    ][15397] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-12-05 10:33:23,158 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-12-05 10:33:23,222 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-05 10:33:23,276 [salt.fileclient  ][INFO    ][15397] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-05 10:33:23,323 [salt.state       ][INFO    ][15397] File /etc/salt/minion.d/minion.conf is in the correct state
2017-12-05 10:33:23,323 [salt.state       ][INFO    ][15397] Completed state [/etc/salt/minion.d/minion.conf] at time 10:33:23.323055 duration_in_ms=189.868
2017-12-05 10:33:23,323 [salt.state       ][INFO    ][15397] Running state [/etc/salt/minion.d/_virt.conf] at time 10:33:23.323446
2017-12-05 10:33:23,324 [salt.state       ][INFO    ][15397] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-12-05 10:33:23,324 [salt.state       ][INFO    ][15397] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-12-05 10:33:23,325 [salt.state       ][INFO    ][15397] Completed state [/etc/salt/minion.d/_virt.conf] at time 10:33:23.324475 duration_in_ms=1.029
2017-12-05 10:33:23,326 [salt.state       ][INFO    ][15397] Running state [salt-minion] at time 10:33:23.326116
2017-12-05 10:33:23,326 [salt.state       ][INFO    ][15397] Executing state service.running for salt-minion
2017-12-05 10:33:23,327 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-12-05 10:33:23,340 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-12-05 10:33:23,347 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-12-05 10:33:23,354 [salt.state       ][INFO    ][15397] The service salt-minion is already running
2017-12-05 10:33:23,354 [salt.state       ][INFO    ][15397] Completed state [salt-minion] at time 10:33:23.353845 duration_in_ms=27.729
2017-12-05 10:33:23,355 [salt.state       ][INFO    ][15397] Running state [/etc/salt/grains.d] at time 10:33:23.355435
2017-12-05 10:33:23,356 [salt.state       ][INFO    ][15397] Executing state file.directory for /etc/salt/grains.d
2017-12-05 10:33:23,356 [salt.state       ][INFO    ][15397] Directory /etc/salt/grains.d is in the correct state
2017-12-05 10:33:23,357 [salt.state       ][INFO    ][15397] Completed state [/etc/salt/grains.d] at time 10:33:23.356460 duration_in_ms=1.025
2017-12-05 10:33:23,357 [salt.state       ][INFO    ][15397] Running state [/etc/salt/grains] at time 10:33:23.357018
2017-12-05 10:33:23,357 [salt.state       ][INFO    ][15397] Executing state file.managed for /etc/salt/grains
2017-12-05 10:33:23,358 [salt.state       ][INFO    ][15397] File /etc/salt/grains exists with proper permissions. No changes made.
2017-12-05 10:33:23,358 [salt.state       ][INFO    ][15397] Completed state [/etc/salt/grains] at time 10:33:23.357773 duration_in_ms=0.754
2017-12-05 10:33:23,358 [salt.state       ][INFO    ][15397] Running state [/etc/salt/grains.d/placeholder] at time 10:33:23.358186
2017-12-05 10:33:23,358 [salt.state       ][INFO    ][15397] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-12-05 10:33:23,359 [salt.state       ][INFO    ][15397] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-12-05 10:33:23,359 [salt.state       ][INFO    ][15397] Completed state [/etc/salt/grains.d/placeholder] at time 10:33:23.358931 duration_in_ms=0.745
2017-12-05 10:33:23,359 [salt.state       ][INFO    ][15397] Running state [/etc/salt/grains.d/sphinx] at time 10:33:23.359307
2017-12-05 10:33:23,360 [salt.state       ][INFO    ][15397] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-12-05 10:33:23,360 [salt.state       ][INFO    ][15397] File /etc/salt/grains.d/sphinx is in the correct state
2017-12-05 10:33:23,360 [salt.state       ][INFO    ][15397] Completed state [/etc/salt/grains.d/sphinx] at time 10:33:23.360438 duration_in_ms=1.131
2017-12-05 10:33:23,361 [salt.state       ][INFO    ][15397] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 10:33:23.361170
2017-12-05 10:33:23,361 [salt.state       ][INFO    ][15397] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-05 10:33:23,362 [salt.state       ][INFO    ][15397] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-05 10:33:23,362 [salt.state       ][INFO    ][15397] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 10:33:23.361745 duration_in_ms=0.575
2017-12-05 10:33:23,362 [salt.state       ][INFO    ][15397] Running state [/etc/salt/grains.d/dns_records] at time 10:33:23.362133
2017-12-05 10:33:23,362 [salt.state       ][INFO    ][15397] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-12-05 10:33:23,363 [salt.state       ][INFO    ][15397] File /etc/salt/grains.d/dns_records is in the correct state
2017-12-05 10:33:23,363 [salt.state       ][INFO    ][15397] Completed state [/etc/salt/grains.d/dns_records] at time 10:33:23.363107 duration_in_ms=0.974
2017-12-05 10:33:23,364 [salt.state       ][INFO    ][15397] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 10:33:23.363853
2017-12-05 10:33:23,364 [salt.state       ][INFO    ][15397] 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-05 10:33:23,364 [salt.state       ][INFO    ][15397] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-12-05 10:33:23,364 [salt.state       ][INFO    ][15397] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 10:33:23.364431 duration_in_ms=0.578
2017-12-05 10:33:23,365 [salt.state       ][INFO    ][15397] Running state [/etc/salt/grains.d/salt] at time 10:33:23.364786
2017-12-05 10:33:23,365 [salt.state       ][INFO    ][15397] Executing state file.managed for /etc/salt/grains.d/salt
2017-12-05 10:33:23,366 [salt.state       ][INFO    ][15397] File /etc/salt/grains.d/salt is in the correct state
2017-12-05 10:33:23,366 [salt.state       ][INFO    ][15397] Completed state [/etc/salt/grains.d/salt] at time 10:33:23.365801 duration_in_ms=1.016
2017-12-05 10:33:23,367 [salt.state       ][INFO    ][15397] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 10:33:23.366573
2017-12-05 10:33:23,367 [salt.state       ][INFO    ][15397] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-05 10:33:23,367 [salt.state       ][INFO    ][15397] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-05 10:33:23,367 [salt.state       ][INFO    ][15397] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 10:33:23.367144 duration_in_ms=0.57
2017-12-05 10:33:23,369 [salt.state       ][INFO    ][15397] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 10:33:23.368678
2017-12-05 10:33:23,369 [salt.state       ][INFO    ][15397] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-05 10:33:23,369 [salt.state       ][INFO    ][15397] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-05 10:33:23,369 [salt.state       ][INFO    ][15397] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 10:33:23.369269 duration_in_ms=0.591
2017-12-05 10:33:23,370 [salt.state       ][INFO    ][15397] Running state [mine.update] at time 10:33:23.369786
2017-12-05 10:33:23,370 [salt.state       ][INFO    ][15397] Executing state module.wait for mine.update
2017-12-05 10:33:23,370 [salt.state       ][INFO    ][15397] No changes made for mine.update
2017-12-05 10:33:23,370 [salt.state       ][INFO    ][15397] Completed state [mine.update] at time 10:33:23.370379 duration_in_ms=0.593
2017-12-05 10:33:23,371 [salt.state       ][INFO    ][15397] Running state [ca-certificates] at time 10:33:23.370555
2017-12-05 10:33:23,371 [salt.state       ][INFO    ][15397] Executing state pkg.installed for ca-certificates
2017-12-05 10:33:23,375 [salt.state       ][INFO    ][15397] Package ca-certificates is already installed
2017-12-05 10:33:23,375 [salt.state       ][INFO    ][15397] Completed state [ca-certificates] at time 10:33:23.374860 duration_in_ms=4.304
2017-12-05 10:33:23,375 [salt.state       ][INFO    ][15397] Running state [update-ca-certificates] at time 10:33:23.375410
2017-12-05 10:33:23,376 [salt.state       ][INFO    ][15397] Executing state cmd.wait for update-ca-certificates
2017-12-05 10:33:23,376 [salt.state       ][INFO    ][15397] No changes made for update-ca-certificates
2017-12-05 10:33:23,376 [salt.state       ][INFO    ][15397] Completed state [update-ca-certificates] at time 10:33:23.375965 duration_in_ms=0.555
2017-12-05 10:33:23,376 [salt.state       ][INFO    ][15397] Running state [libvirt-dev] at time 10:33:23.376134
2017-12-05 10:33:23,376 [salt.state       ][INFO    ][15397] Executing state pkg.installed for libvirt-dev
2017-12-05 10:33:23,387 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-12-05 10:33:24,644 [salt.loaded.int.module.cmdmod][INFO    ][15397] 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-05 10:33:27,759 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.find_job with jid 20171205103327742329
2017-12-05 10:33:27,768 [salt.minion      ][INFO    ][15927] Starting a new job with PID 15927
2017-12-05 10:33:27,779 [salt.minion      ][INFO    ][15927] Returning information for job: 20171205103327742329
2017-12-05 10:33:33,427 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-05 10:33:33,454 [salt.state       ][INFO    ][15397] 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-101.124'
'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-05 10:33:33,465 [salt.state       ][INFO    ][15397] Loading fresh modules for state activity
2017-12-05 10:33:33,482 [salt.state       ][INFO    ][15397] Completed state [libvirt-dev] at time 10:33:33.482381 duration_in_ms=10106.246
2017-12-05 10:33:33,488 [salt.state       ][INFO    ][15397] Running state [pkg-config] at time 10:33:33.488267
2017-12-05 10:33:33,488 [salt.state       ][INFO    ][15397] Executing state pkg.installed for pkg-config
2017-12-05 10:33:33,741 [salt.state       ][INFO    ][15397] Package pkg-config is already installed
2017-12-05 10:33:33,742 [salt.state       ][INFO    ][15397] Completed state [pkg-config] at time 10:33:33.741556 duration_in_ms=253.288
2017-12-05 10:33:33,742 [salt.state       ][INFO    ][15397] Running state [virtng.init] at time 10:33:33.742076
2017-12-05 10:33:33,742 [salt.state       ][INFO    ][15397] Executing state module.run for virtng.init
2017-12-05 10:33:33,791 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command ['git', '--version'] in directory '/root'
2017-12-05 10:33:33,829 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'virsh list --all --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-05 10:33:37,829 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.find_job with jid 20171205103337814656
2017-12-05 10:33:37,838 [salt.minion      ][INFO    ][21069] Starting a new job with PID 21069
2017-12-05 10:33:37,848 [salt.minion      ][INFO    ][21069] Returning information for job: 20171205103337814656
2017-12-05 10:33:48,046 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.find_job with jid 20171205103348028134
2017-12-05 10:33:48,057 [salt.minion      ][INFO    ][21074] Starting a new job with PID 21074
2017-12-05 10:33:48,068 [salt.minion      ][INFO    ][21074] Returning information for job: 20171205103348028134
2017-12-05 10:33:58,234 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.find_job with jid 20171205103358215228
2017-12-05 10:33:58,244 [salt.minion      ][INFO    ][21079] Starting a new job with PID 21079
2017-12-05 10:33:58,255 [salt.minion      ][INFO    ][21079] Returning information for job: 20171205103358215228
2017-12-05 10:33:59,889 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-12-05 10:33:59,925 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-05 10:34:00,025 [salt.loaded.int.module.cmdmod][ERROR   ][15397] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-05 10:34:00,025 [salt.loaded.int.module.cmdmod][ERROR   ][15397] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-05 10:34:00,026 [salt.loaded.int.module.cmdmod][INFO    ][15397] 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-05 10:34:00,041 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-05 10:34:00,047 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-12-05 10:34:01,117 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-12-05 10:34:01,825 [salt.loaded.ext.module.seedng][INFO    ][15397] salt-minion pre-installed on image, configuring as prx01.baremetal-mcp-ocata-ovs-ha.local
2017-12-05 10:34:01,831 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'mount -l' in directory '/root'
2017-12-05 10:34:01,843 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-12-05 10:34:01,892 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-12-05 10:34:03,978 [salt.state       ][INFO    ][15397] {'ret': True}
2017-12-05 10:34:03,979 [salt.state       ][INFO    ][15397] Completed state [virtng.init] at time 10:34:03.978710 duration_in_ms=30236.633
2017-12-05 10:34:03,979 [salt.state       ][INFO    ][15397] Running state [virt.set_autostart] at time 10:34:03.979031
2017-12-05 10:34:03,979 [salt.state       ][INFO    ][15397] Executing state module.run for virt.set_autostart
2017-12-05 10:34:03,982 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'virsh list --autostart --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-05 10:34:04,001 [salt.state       ][INFO    ][15397] {'ret': True}
2017-12-05 10:34:04,001 [salt.state       ][INFO    ][15397] Completed state [virt.set_autostart] at time 10:34:04.001172 duration_in_ms=22.141
2017-12-05 10:34:04,001 [salt.state       ][INFO    ][15397] Running state [virtng.init] at time 10:34:04.001415
2017-12-05 10:34:04,002 [salt.state       ][INFO    ][15397] Executing state module.run for virtng.init
2017-12-05 10:34:04,004 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'virsh list --all --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-05 10:34:04,802 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-12-05 10:34:04,808 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-05 10:34:04,813 [salt.loaded.int.module.cmdmod][ERROR   ][15397] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-05 10:34:04,813 [salt.loaded.int.module.cmdmod][ERROR   ][15397] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-05 10:34:04,814 [salt.loaded.int.module.cmdmod][INFO    ][15397] 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-05 10:34:04,827 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-05 10:34:04,834 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-12-05 10:34:05,866 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-12-05 10:34:06,614 [salt.loaded.ext.module.seedng][INFO    ][15397] salt-minion pre-installed on image, configuring as mdb01.baremetal-mcp-ocata-ovs-ha.local
2017-12-05 10:34:06,619 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'mount -l' in directory '/root'
2017-12-05 10:34:06,629 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-12-05 10:34:06,676 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-12-05 10:34:08,271 [salt.state       ][INFO    ][15397] {'ret': True}
2017-12-05 10:34:08,272 [salt.state       ][INFO    ][15397] Completed state [virtng.init] at time 10:34:08.271765 duration_in_ms=4270.349
2017-12-05 10:34:08,272 [salt.state       ][INFO    ][15397] Running state [virt.set_autostart] at time 10:34:08.272063
2017-12-05 10:34:08,272 [salt.state       ][INFO    ][15397] Executing state module.run for virt.set_autostart
2017-12-05 10:34:08,275 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'virsh list --autostart --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-05 10:34:08,296 [salt.state       ][INFO    ][15397] {'ret': True}
2017-12-05 10:34:08,297 [salt.state       ][INFO    ][15397] Completed state [virt.set_autostart] at time 10:34:08.296598 duration_in_ms=24.534
2017-12-05 10:34:08,297 [salt.state       ][INFO    ][15397] Running state [virtng.init] at time 10:34:08.296831
2017-12-05 10:34:08,297 [salt.state       ][INFO    ][15397] Executing state module.run for virtng.init
2017-12-05 10:34:08,300 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'virsh list --all --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-05 10:34:08,427 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.find_job with jid 20171205103408407722
2017-12-05 10:34:08,438 [salt.minion      ][INFO    ][21852] Starting a new job with PID 21852
2017-12-05 10:34:08,450 [salt.minion      ][INFO    ][21852] Returning information for job: 20171205103408407722
2017-12-05 10:34:09,583 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-12-05 10:34:09,590 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-05 10:34:09,595 [salt.loaded.int.module.cmdmod][ERROR   ][15397] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-05 10:34:09,595 [salt.loaded.int.module.cmdmod][ERROR   ][15397] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-05 10:34:09,595 [salt.loaded.int.module.cmdmod][INFO    ][15397] 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-05 10:34:09,609 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-05 10:34:09,617 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-12-05 10:34:10,667 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-12-05 10:34:11,341 [salt.loaded.ext.module.seedng][INFO    ][15397] salt-minion pre-installed on image, configuring as ctl01.baremetal-mcp-ocata-ovs-ha.local
2017-12-05 10:34:11,346 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'mount -l' in directory '/root'
2017-12-05 10:34:11,358 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-12-05 10:34:11,423 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-12-05 10:34:12,992 [salt.state       ][INFO    ][15397] {'ret': True}
2017-12-05 10:34:12,993 [salt.state       ][INFO    ][15397] Completed state [virtng.init] at time 10:34:12.992776 duration_in_ms=4695.945
2017-12-05 10:34:12,993 [salt.state       ][INFO    ][15397] Running state [virt.set_autostart] at time 10:34:12.993049
2017-12-05 10:34:12,993 [salt.state       ][INFO    ][15397] Executing state module.run for virt.set_autostart
2017-12-05 10:34:12,996 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'virsh list --autostart --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-05 10:34:13,015 [salt.state       ][INFO    ][15397] {'ret': True}
2017-12-05 10:34:13,015 [salt.state       ][INFO    ][15397] Completed state [virt.set_autostart] at time 10:34:13.014998 duration_in_ms=21.949
2017-12-05 10:34:13,015 [salt.state       ][INFO    ][15397] Running state [virtng.init] at time 10:34:13.015243
2017-12-05 10:34:13,015 [salt.state       ][INFO    ][15397] Executing state module.run for virtng.init
2017-12-05 10:34:13,018 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'virsh list --all --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-05 10:34:13,765 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-12-05 10:34:13,772 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-05 10:34:13,776 [salt.loaded.int.module.cmdmod][ERROR   ][15397] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-05 10:34:13,776 [salt.loaded.int.module.cmdmod][ERROR   ][15397] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-05 10:34:13,777 [salt.loaded.int.module.cmdmod][INFO    ][15397] 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-05 10:34:13,792 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-05 10:34:13,799 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-12-05 10:34:14,847 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-12-05 10:34:15,573 [salt.loaded.ext.module.seedng][INFO    ][15397] salt-minion pre-installed on image, configuring as msg01.baremetal-mcp-ocata-ovs-ha.local
2017-12-05 10:34:15,579 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'mount -l' in directory '/root'
2017-12-05 10:34:15,591 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-12-05 10:34:15,653 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-12-05 10:34:17,181 [salt.state       ][INFO    ][15397] {'ret': True}
2017-12-05 10:34:17,182 [salt.state       ][INFO    ][15397] Completed state [virtng.init] at time 10:34:17.181578 duration_in_ms=4166.334
2017-12-05 10:34:17,182 [salt.state       ][INFO    ][15397] Running state [virt.set_autostart] at time 10:34:17.181910
2017-12-05 10:34:17,182 [salt.state       ][INFO    ][15397] Executing state module.run for virt.set_autostart
2017-12-05 10:34:17,185 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'virsh list --autostart --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-05 10:34:17,205 [salt.state       ][INFO    ][15397] {'ret': True}
2017-12-05 10:34:17,206 [salt.state       ][INFO    ][15397] Completed state [virt.set_autostart] at time 10:34:17.205723 duration_in_ms=23.813
2017-12-05 10:34:17,206 [salt.state       ][INFO    ][15397] Running state [virtng.init] at time 10:34:17.206007
2017-12-05 10:34:17,206 [salt.state       ][INFO    ][15397] Executing state module.run for virtng.init
2017-12-05 10:34:17,209 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'virsh list --all --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-05 10:34:17,962 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-12-05 10:34:17,968 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-05 10:34:17,973 [salt.loaded.int.module.cmdmod][ERROR   ][15397] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-05 10:34:17,973 [salt.loaded.int.module.cmdmod][ERROR   ][15397] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-05 10:34:17,974 [salt.loaded.int.module.cmdmod][INFO    ][15397] 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-05 10:34:17,987 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-05 10:34:17,994 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-12-05 10:34:18,604 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.find_job with jid 20171205103418591387
2017-12-05 10:34:18,614 [salt.minion      ][INFO    ][22435] Starting a new job with PID 22435
2017-12-05 10:34:18,625 [salt.minion      ][INFO    ][22435] Returning information for job: 20171205103418591387
2017-12-05 10:34:19,050 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-12-05 10:34:19,797 [salt.loaded.ext.module.seedng][INFO    ][15397] salt-minion pre-installed on image, configuring as dbs01.baremetal-mcp-ocata-ovs-ha.local
2017-12-05 10:34:19,802 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'mount -l' in directory '/root'
2017-12-05 10:34:19,813 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-12-05 10:34:19,866 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-12-05 10:34:21,425 [salt.state       ][INFO    ][15397] {'ret': True}
2017-12-05 10:34:21,426 [salt.state       ][INFO    ][15397] Completed state [virtng.init] at time 10:34:21.425709 duration_in_ms=4219.702
2017-12-05 10:34:21,426 [salt.state       ][INFO    ][15397] Running state [virt.set_autostart] at time 10:34:21.426001
2017-12-05 10:34:21,426 [salt.state       ][INFO    ][15397] Executing state module.run for virt.set_autostart
2017-12-05 10:34:21,429 [salt.loaded.int.module.cmdmod][INFO    ][15397] Executing command 'virsh list --autostart --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-05 10:34:21,449 [salt.state       ][INFO    ][15397] {'ret': True}
2017-12-05 10:34:21,449 [salt.state       ][INFO    ][15397] Completed state [virt.set_autostart] at time 10:34:21.448815 duration_in_ms=22.814
2017-12-05 10:34:21,452 [salt.minion      ][INFO    ][15397] Returning information for job: 20171205103317627297
2017-12-05 10:34:22,391 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command pillar.get with jid 20171205103422375182
2017-12-05 10:34:22,399 [salt.minion      ][INFO    ][22678] Starting a new job with PID 22678
2017-12-05 10:34:22,403 [salt.minion      ][INFO    ][22678] Returning information for job: 20171205103422375182
2017-12-05 10:35:17,920 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171205103517908013
2017-12-05 10:35:17,933 [salt.minion      ][INFO    ][22686] Starting a new job with PID 22686
2017-12-05 10:35:21,798 [salt.state       ][INFO    ][22686] Loading fresh modules for state activity
2017-12-05 10:35:21,824 [salt.state       ][ERROR   ][22686] No contents found in top file
2017-12-05 10:35:21,824 [salt.utils.extmods][INFO    ][22686] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-12-05 10:35:21,827 [salt.utils.extmods][INFO    ][22686] Syncing beacons for environment 'base'
2017-12-05 10:35:21,827 [salt.utils.extmods][INFO    ][22686] Loading cache from salt://_beacons, for base)
2017-12-05 10:35:21,828 [salt.fileclient  ][INFO    ][22686] Caching directory '_beacons/' for environment 'base'
2017-12-05 10:35:21,889 [salt.utils.extmods][INFO    ][22686] Syncing modules for environment 'base'
2017-12-05 10:35:21,889 [salt.utils.extmods][INFO    ][22686] Loading cache from salt://_modules, for base)
2017-12-05 10:35:21,889 [salt.fileclient  ][INFO    ][22686] Caching directory '_modules/' for environment 'base'
2017-12-05 10:35:22,931 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/avinetworks.py'
2017-12-05 10:35:22,944 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-12-05 10:35:22,966 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-12-05 10:35:22,985 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-12-05 10:35:22,997 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-12-05 10:35:23,215 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-12-05 10:35:23,246 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-12-05 10:35:23,262 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-12-05 10:35:23,274 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-12-05 10:35:23,313 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-12-05 10:35:23,341 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-12-05 10:35:23,352 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-12-05 10:35:23,365 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-12-05 10:35:23,380 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-12-05 10:35:23,409 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-12-05 10:35:23,421 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-12-05 10:35:23,431 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-12-05 10:35:23,445 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-12-05 10:35:23,486 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-12-05 10:35:23,506 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-12-05 10:35:23,532 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-12-05 10:35:23,551 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-12-05 10:35:23,572 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-12-05 10:35:23,591 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-12-05 10:35:23,619 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-12-05 10:35:23,642 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-12-05 10:35:23,662 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-12-05 10:35:23,694 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-12-05 10:35:23,706 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-12-05 10:35:23,738 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-12-05 10:35:23,752 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-12-05 10:35:23,778 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-12-05 10:35:23,801 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-12-05 10:35:23,818 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-12-05 10:35:23,822 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-12-05 10:35:23,822 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/avinetworks.py' to '/var/cache/salt/minion/extmods/modules/avinetworks.py'
2017-12-05 10:35:23,822 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-12-05 10:35:23,823 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-12-05 10:35:23,823 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-12-05 10:35:23,823 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-12-05 10:35:23,824 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-12-05 10:35:23,824 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-12-05 10:35:23,824 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-12-05 10:35:23,824 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-12-05 10:35:23,825 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-12-05 10:35:23,825 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-12-05 10:35:23,825 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-12-05 10:35:23,825 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-12-05 10:35:23,826 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-12-05 10:35:23,826 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-12-05 10:35:23,826 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-12-05 10:35:23,826 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-12-05 10:35:23,827 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-12-05 10:35:23,827 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-12-05 10:35:23,827 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-12-05 10:35:23,827 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-12-05 10:35:23,828 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-12-05 10:35:23,828 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-12-05 10:35:23,829 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-12-05 10:35:23,829 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-12-05 10:35:23,829 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-12-05 10:35:23,829 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-12-05 10:35:23,830 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-12-05 10:35:23,830 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-12-05 10:35:23,830 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-12-05 10:35:23,830 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-12-05 10:35:23,831 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-12-05 10:35:23,831 [salt.utils.extmods][INFO    ][22686] 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-05 10:35:23,831 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-12-05 10:35:23,831 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-12-05 10:35:23,837 [salt.utils.extmods][INFO    ][22686] Syncing states for environment 'base'
2017-12-05 10:35:23,837 [salt.utils.extmods][INFO    ][22686] Loading cache from salt://_states, for base)
2017-12-05 10:35:23,838 [salt.fileclient  ][INFO    ][22686] Caching directory '_states/' for environment 'base'
2017-12-05 10:35:23,905 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/avinetworks.py'
2017-12-05 10:35:23,921 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-12-05 10:35:23,958 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-12-05 10:35:23,980 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-12-05 10:35:23,994 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-12-05 10:35:24,024 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-12-05 10:35:24,039 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-12-05 10:35:24,054 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-12-05 10:35:24,070 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-12-05 10:35:24,085 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/helm_repos.py'
2017-12-05 10:35:24,102 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-12-05 10:35:24,120 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-12-05 10:35:24,149 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-12-05 10:35:24,169 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/jenkins_globalenvprop.py'
2017-12-05 10:35:24,183 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-12-05 10:35:24,195 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-12-05 10:35:24,252 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-12-05 10:35:24,267 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-12-05 10:35:24,277 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-12-05 10:35:24,303 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-12-05 10:35:24,375 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-12-05 10:35:24,395 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-12-05 10:35:24,417 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-12-05 10:35:24,434 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-12-05 10:35:24,458 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-12-05 10:35:24,475 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-12-05 10:35:24,518 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-12-05 10:35:24,540 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-12-05 10:35:24,556 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-12-05 10:35:24,569 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-12-05 10:35:24,588 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-12-05 10:35:24,613 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-12-05 10:35:24,617 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/avinetworks.py' to '/var/cache/salt/minion/extmods/states/avinetworks.py'
2017-12-05 10:35:24,618 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-12-05 10:35:24,618 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-12-05 10:35:24,618 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-12-05 10:35:24,619 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-12-05 10:35:24,619 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-12-05 10:35:24,619 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-12-05 10:35:24,620 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-12-05 10:35:24,620 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-12-05 10:35:24,620 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/helm_repos.py' to '/var/cache/salt/minion/extmods/states/helm_repos.py'
2017-12-05 10:35:24,620 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-12-05 10:35:24,621 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-12-05 10:35:24,621 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-12-05 10:35:24,621 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/jenkins_globalenvprop.py' to '/var/cache/salt/minion/extmods/states/jenkins_globalenvprop.py'
2017-12-05 10:35:24,621 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-12-05 10:35:24,621 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-12-05 10:35:24,622 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-12-05 10:35:24,622 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-12-05 10:35:24,622 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-12-05 10:35:24,622 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-12-05 10:35:24,623 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-12-05 10:35:24,623 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-12-05 10:35:24,623 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-12-05 10:35:24,623 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-12-05 10:35:24,624 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-12-05 10:35:24,624 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-12-05 10:35:24,624 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-12-05 10:35:24,624 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-12-05 10:35:24,625 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-12-05 10:35:24,625 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-12-05 10:35:24,625 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-12-05 10:35:24,626 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-12-05 10:35:24,626 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-12-05 10:35:24,629 [salt.utils.extmods][INFO    ][22686] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-12-05 10:35:24,632 [salt.utils.extmods][INFO    ][22686] Syncing sdb for environment 'base'
2017-12-05 10:35:24,632 [salt.utils.extmods][INFO    ][22686] Loading cache from salt://_sdb, for base)
2017-12-05 10:35:24,633 [salt.fileclient  ][INFO    ][22686] Caching directory '_sdb/' for environment 'base'
2017-12-05 10:35:24,706 [salt.utils.extmods][INFO    ][22686] Syncing grains for environment 'base'
2017-12-05 10:35:24,706 [salt.utils.extmods][INFO    ][22686] Loading cache from salt://_grains, for base)
2017-12-05 10:35:24,706 [salt.fileclient  ][INFO    ][22686] Caching directory '_grains/' for environment 'base'
2017-12-05 10:35:24,765 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-12-05 10:35:24,837 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-12-05 10:35:24,862 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-12-05 10:35:24,874 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-12-05 10:35:24,892 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-12-05 10:35:24,916 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-12-05 10:35:24,933 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-12-05 10:35:24,941 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-12-05 10:35:24,953 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-12-05 10:35:24,976 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-12-05 10:35:25,003 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-12-05 10:35:25,024 [salt.fileclient  ][INFO    ][22686] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-12-05 10:35:25,028 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-12-05 10:35:25,029 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-12-05 10:35:25,029 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-12-05 10:35:25,029 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-12-05 10:35:25,029 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-12-05 10:35:25,030 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-12-05 10:35:25,030 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-12-05 10:35:25,030 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-12-05 10:35:25,030 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-12-05 10:35:25,031 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-12-05 10:35:25,031 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-12-05 10:35:25,031 [salt.utils.extmods][INFO    ][22686] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-12-05 10:35:25,033 [salt.utils.extmods][INFO    ][22686] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-12-05 10:35:25,036 [salt.utils.extmods][INFO    ][22686] Syncing renderers for environment 'base'
2017-12-05 10:35:25,036 [salt.utils.extmods][INFO    ][22686] Loading cache from salt://_renderers, for base)
2017-12-05 10:35:25,036 [salt.fileclient  ][INFO    ][22686] Caching directory '_renderers/' for environment 'base'
2017-12-05 10:35:25,093 [salt.utils.extmods][INFO    ][22686] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-12-05 10:35:25,096 [salt.utils.extmods][INFO    ][22686] Syncing returners for environment 'base'
2017-12-05 10:35:25,096 [salt.utils.extmods][INFO    ][22686] Loading cache from salt://_returners, for base)
2017-12-05 10:35:25,097 [salt.fileclient  ][INFO    ][22686] Caching directory '_returners/' for environment 'base'
2017-12-05 10:35:25,144 [salt.utils.extmods][INFO    ][22686] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-12-05 10:35:25,146 [salt.utils.extmods][INFO    ][22686] Syncing output for environment 'base'
2017-12-05 10:35:25,147 [salt.utils.extmods][INFO    ][22686] Loading cache from salt://_output, for base)
2017-12-05 10:35:25,147 [salt.fileclient  ][INFO    ][22686] Caching directory '_output/' for environment 'base'
2017-12-05 10:35:25,195 [salt.utils.extmods][INFO    ][22686] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-12-05 10:35:25,197 [salt.utils.extmods][INFO    ][22686] Syncing utils for environment 'base'
2017-12-05 10:35:25,197 [salt.utils.extmods][INFO    ][22686] Loading cache from salt://_utils, for base)
2017-12-05 10:35:25,198 [salt.fileclient  ][INFO    ][22686] Caching directory '_utils/' for environment 'base'
2017-12-05 10:35:25,233 [salt.utils.extmods][INFO    ][22686] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-12-05 10:35:25,236 [salt.utils.extmods][INFO    ][22686] Syncing log_handlers for environment 'base'
2017-12-05 10:35:25,236 [salt.utils.extmods][INFO    ][22686] Loading cache from salt://_log_handlers, for base)
2017-12-05 10:35:25,236 [salt.fileclient  ][INFO    ][22686] Caching directory '_log_handlers/' for environment 'base'
2017-12-05 10:35:25,272 [salt.utils.extmods][INFO    ][22686] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-12-05 10:35:25,275 [salt.utils.extmods][INFO    ][22686] Syncing proxy for environment 'base'
2017-12-05 10:35:25,275 [salt.utils.extmods][INFO    ][22686] Loading cache from salt://_proxy, for base)
2017-12-05 10:35:25,276 [salt.fileclient  ][INFO    ][22686] Caching directory '_proxy/' for environment 'base'
2017-12-05 10:35:25,330 [salt.utils.extmods][INFO    ][22686] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-12-05 10:35:25,332 [salt.utils.extmods][INFO    ][22686] Syncing engines for environment 'base'
2017-12-05 10:35:25,333 [salt.utils.extmods][INFO    ][22686] Loading cache from salt://_engines, for base)
2017-12-05 10:35:25,333 [salt.fileclient  ][INFO    ][22686] Caching directory '_engines/' for environment 'base'
2017-12-05 10:35:25,378 [salt.minion      ][INFO    ][22686] Returning information for job: 20171205103517908013
2017-12-05 10:42:47,522 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command test.ping with jid 20171205104247516302
2017-12-05 10:42:47,537 [salt.minion      ][INFO    ][22833] Starting a new job with PID 22833
2017-12-05 10:42:47,573 [salt.minion      ][INFO    ][22833] Returning information for job: 20171205104247516302
2017-12-05 10:44:35,064 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command state.sls with jid 20171205104435058704
2017-12-05 10:44:35,075 [salt.minion      ][INFO    ][22881] Starting a new job with PID 22881
2017-12-05 10:44:35,688 [salt.state       ][INFO    ][22881] Loading fresh modules for state activity
2017-12-05 10:44:35,720 [salt.fileclient  ][INFO    ][22881] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-12-05 10:44:35,738 [salt.fileclient  ][INFO    ][22881] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-12-05 10:44:35,771 [salt.fileclient  ][INFO    ][22881] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-05 10:44:36,103 [salt.state       ][INFO    ][22881] Running state [lsof] at time 10:44:36.103451
2017-12-05 10:44:36,104 [salt.state       ][INFO    ][22881] Executing state pkg.installed for lsof
2017-12-05 10:44:36,104 [salt.loaded.int.module.cmdmod][INFO    ][22881] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-05 10:44:36,382 [salt.state       ][INFO    ][22881] Package lsof is already installed
2017-12-05 10:44:36,382 [salt.state       ][INFO    ][22881] Completed state [lsof] at time 10:44:36.382266 duration_in_ms=278.815
2017-12-05 10:44:36,383 [salt.state       ][INFO    ][22881] Running state [keepalived] at time 10:44:36.382529
2017-12-05 10:44:36,383 [salt.state       ][INFO    ][22881] Executing state pkg.installed for keepalived
2017-12-05 10:44:36,400 [salt.loaded.int.module.cmdmod][INFO    ][22881] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-12-05 10:44:37,826 [salt.loaded.int.module.cmdmod][INFO    ][22881] 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-05 10:44:41,044 [salt.loaded.int.module.cmdmod][INFO    ][22881] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-05 10:44:41,071 [salt.state       ][INFO    ][22881] 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-05 10:44:41,082 [salt.state       ][INFO    ][22881] Loading fresh modules for state activity
2017-12-05 10:44:41,099 [salt.state       ][INFO    ][22881] Completed state [keepalived] at time 10:44:41.098652 duration_in_ms=4716.123
2017-12-05 10:44:41,101 [salt.state       ][INFO    ][22881] Running state [/etc/keepalived/keepalived.conf] at time 10:44:41.101042
2017-12-05 10:44:41,101 [salt.state       ][INFO    ][22881] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-12-05 10:44:41,134 [salt.fileclient  ][INFO    ][22881] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-12-05 10:44:41,178 [salt.fileclient  ][INFO    ][22881] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-05 10:44:41,186 [salt.state       ][INFO    ][22881] File changed:
New file
2017-12-05 10:44:41,186 [salt.state       ][INFO    ][22881] Completed state [/etc/keepalived/keepalived.conf] at time 10:44:41.185750 duration_in_ms=84.708
2017-12-05 10:44:41,253 [salt.state       ][INFO    ][22881] Running state [keepalived] at time 10:44:41.252613
2017-12-05 10:44:41,253 [salt.state       ][INFO    ][22881] Executing state service.running for keepalived
2017-12-05 10:44:41,255 [salt.loaded.int.module.cmdmod][INFO    ][22881] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-12-05 10:44:41,264 [salt.loaded.int.module.cmdmod][INFO    ][22881] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-12-05 10:44:41,272 [salt.loaded.int.module.cmdmod][INFO    ][22881] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-05 10:44:41,279 [salt.loaded.int.module.cmdmod][INFO    ][22881] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-05 10:44:41,285 [salt.loaded.int.module.cmdmod][INFO    ][22881] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-12-05 10:44:41,315 [salt.loaded.int.module.cmdmod][INFO    ][22881] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-12-05 10:44:41,324 [salt.loaded.int.module.cmdmod][INFO    ][22881] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-05 10:44:41,332 [salt.loaded.int.module.cmdmod][INFO    ][22881] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-05 10:44:41,338 [salt.state       ][INFO    ][22881] {'keepalived': True}
2017-12-05 10:44:41,339 [salt.state       ][INFO    ][22881] Completed state [keepalived] at time 10:44:41.338449 duration_in_ms=85.837
2017-12-05 10:44:41,339 [salt.minion      ][INFO    ][22881] Returning information for job: 20171205104435058704
2017-12-05 10:44:42,216 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command pillar.get with jid 20171205104442215958
2017-12-05 10:44:42,230 [salt.minion      ][INFO    ][24288] Starting a new job with PID 24288
2017-12-05 10:44:42,234 [salt.minion      ][INFO    ][24288] Returning information for job: 20171205104442215958
2017-12-05 10:49:30,338 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command state.sls with jid 20171205104930332799
2017-12-05 10:49:30,348 [salt.minion      ][INFO    ][24363] Starting a new job with PID 24363
2017-12-05 10:49:32,962 [salt.state       ][INFO    ][24363] Loading fresh modules for state activity
2017-12-05 10:49:32,987 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-12-05 10:49:33,013 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-12-05 10:49:33,318 [salt.state       ][INFO    ][24363] Running state [attr] at time 10:49:33.318010
2017-12-05 10:49:33,318 [salt.state       ][INFO    ][24363] Executing state pkg.installed for attr
2017-12-05 10:49:33,319 [salt.loaded.int.module.cmdmod][INFO    ][24363] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-05 10:49:33,590 [salt.loaded.int.module.cmdmod][INFO    ][24363] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-12-05 10:49:35,007 [salt.loaded.int.module.cmdmod][INFO    ][24363] 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-05 10:49:36,607 [salt.loaded.int.module.cmdmod][INFO    ][24363] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-05 10:49:36,634 [salt.state       ][INFO    ][24363] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-12-05 10:49:36,645 [salt.state       ][INFO    ][24363] Loading fresh modules for state activity
2017-12-05 10:49:36,661 [salt.state       ][INFO    ][24363] Completed state [attr] at time 10:49:36.660907 duration_in_ms=3342.897
2017-12-05 10:49:36,666 [salt.state       ][INFO    ][24363] Running state [glusterfs-server] at time 10:49:36.666443
2017-12-05 10:49:36,667 [salt.state       ][INFO    ][24363] Executing state pkg.installed for glusterfs-server
2017-12-05 10:49:36,884 [salt.loaded.int.module.cmdmod][INFO    ][24363] 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-05 10:49:40,445 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command saltutil.find_job with jid 20171205104940433635
2017-12-05 10:49:40,456 [salt.minion      ][INFO    ][25189] Starting a new job with PID 25189
2017-12-05 10:49:40,467 [salt.minion      ][INFO    ][25189] Returning information for job: 20171205104940433635
2017-12-05 10:49:47,065 [salt.loaded.int.module.cmdmod][INFO    ][24363] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-05 10:49:47,093 [salt.state       ][INFO    ][24363] Made the following changes:
'keyutils' changed from 'absent' to '1.5.9-8ubuntu1'
'glusterfs-client' changed from 'absent' to '3.10.8-ubuntu1~xenial1'
'rpcbind' changed from 'absent' to '0.2.3-0.2'
'libpython2.7' changed from 'absent' to '2.7.12-1ubuntu0~16.04.2'
'nfs-common' changed from 'absent' to '1:1.2.8-9ubuntu12.1'
'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-05 10:49:47,103 [salt.state       ][INFO    ][24363] Loading fresh modules for state activity
2017-12-05 10:49:47,120 [salt.state       ][INFO    ][24363] Completed state [glusterfs-server] at time 10:49:47.119847 duration_in_ms=10453.402
2017-12-05 10:49:47,125 [salt.state       ][INFO    ][24363] Running state [bc] at time 10:49:47.125356
2017-12-05 10:49:47,126 [salt.state       ][INFO    ][24363] Executing state pkg.installed for bc
2017-12-05 10:49:47,423 [salt.loaded.int.module.cmdmod][INFO    ][24363] 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-05 10:49:49,050 [salt.loaded.int.module.cmdmod][INFO    ][24363] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-05 10:49:49,081 [salt.state       ][INFO    ][24363] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-12-05 10:49:49,091 [salt.state       ][INFO    ][24363] Loading fresh modules for state activity
2017-12-05 10:49:49,108 [salt.state       ][INFO    ][24363] Completed state [bc] at time 10:49:49.108008 duration_in_ms=1982.651
2017-12-05 10:49:49,183 [salt.state       ][INFO    ][24363] Running state [glusterfs-server] at time 10:49:49.183003
2017-12-05 10:49:49,183 [salt.state       ][INFO    ][24363] Executing state service.running for glusterfs-server
2017-12-05 10:49:49,185 [salt.loaded.int.module.cmdmod][INFO    ][24363] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-12-05 10:49:49,194 [salt.loaded.int.module.cmdmod][INFO    ][24363] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-12-05 10:49:49,200 [salt.loaded.int.module.cmdmod][INFO    ][24363] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-12-05 10:49:49,208 [salt.state       ][INFO    ][24363] The service glusterfs-server is already running
2017-12-05 10:49:49,209 [salt.state       ][INFO    ][24363] Completed state [glusterfs-server] at time 10:49:49.208660 duration_in_ms=25.657
2017-12-05 10:49:49,211 [salt.state       ][INFO    ][24363] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:49:49.210812
2017-12-05 10:49:49,211 [salt.state       ][INFO    ][24363] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-12-05 10:49:49,216 [salt.state       ][INFO    ][24363] File changed:
New file
2017-12-05 10:49:49,216 [salt.state       ][INFO    ][24363] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:49:49.216222 duration_in_ms=5.409
2017-12-05 10:49:49,216 [salt.state       ][INFO    ][24363] Running state [/srv/glusterfs/glance] at time 10:49:49.216419
2017-12-05 10:49:49,217 [salt.state       ][INFO    ][24363] Executing state file.directory for /srv/glusterfs/glance
2017-12-05 10:49:49,218 [salt.state       ][INFO    ][24363] {'/srv/glusterfs/glance': 'New Dir'}
2017-12-05 10:49:49,218 [salt.state       ][INFO    ][24363] Completed state [/srv/glusterfs/glance] at time 10:49:49.217767 duration_in_ms=1.347
2017-12-05 10:49:49,218 [salt.state       ][INFO    ][24363] Running state [/srv/glusterfs/keystone-keys] at time 10:49:49.218040
2017-12-05 10:49:49,218 [salt.state       ][INFO    ][24363] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-12-05 10:49:49,219 [salt.state       ][INFO    ][24363] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-12-05 10:49:49,219 [salt.state       ][INFO    ][24363] Completed state [/srv/glusterfs/keystone-keys] at time 10:49:49.218923 duration_in_ms=0.869
2017-12-05 10:49:49,219 [salt.state       ][INFO    ][24363] Running state [/srv/glusterfs/nova_instances] at time 10:49:49.219101
2017-12-05 10:49:49,219 [salt.state       ][INFO    ][24363] Executing state file.directory for /srv/glusterfs/nova_instances
2017-12-05 10:49:49,220 [salt.state       ][INFO    ][24363] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-12-05 10:49:49,220 [salt.state       ][INFO    ][24363] Completed state [/srv/glusterfs/nova_instances] at time 10:49:49.219899 duration_in_ms=0.797
2017-12-05 10:49:49,220 [salt.state       ][INFO    ][24363] Running state [/srv/glusterfs/keystone-credential-keys] at time 10:49:49.220076
2017-12-05 10:49:49,220 [salt.state       ][INFO    ][24363] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-12-05 10:49:49,221 [salt.state       ][INFO    ][24363] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-12-05 10:49:49,221 [salt.state       ][INFO    ][24363] Completed state [/srv/glusterfs/keystone-credential-keys] at time 10:49:49.220873 duration_in_ms=0.797
2017-12-05 10:49:49,222 [salt.minion      ][INFO    ][24363] Returning information for job: 20171205104930332799
2017-12-05 10:49:50,444 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command test.ping with jid 20171205104950442670
2017-12-05 10:49:50,455 [salt.minion      ][INFO    ][27138] Starting a new job with PID 27138
2017-12-05 10:49:50,488 [salt.minion      ][INFO    ][27138] Returning information for job: 20171205104950442670
2017-12-05 10:50:15,362 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command state.sls with jid 20171205105015357522
2017-12-05 10:50:15,374 [salt.minion      ][INFO    ][27773] Starting a new job with PID 27773
2017-12-05 10:50:17,693 [salt.state       ][INFO    ][27773] Loading fresh modules for state activity
2017-12-05 10:50:17,721 [salt.fileclient  ][INFO    ][27773] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-12-05 10:50:17,763 [salt.fileclient  ][INFO    ][27773] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-12-05 10:50:17,848 [salt.fileclient  ][INFO    ][27773] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-12-05 10:50:17,872 [salt.fileclient  ][INFO    ][27773] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-12-05 10:50:18,173 [salt.state       ][INFO    ][27773] Running state [attr] at time 10:50:18.172922
2017-12-05 10:50:18,173 [salt.state       ][INFO    ][27773] Executing state pkg.installed for attr
2017-12-05 10:50:18,174 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-05 10:50:18,438 [salt.state       ][INFO    ][27773] Package attr is already installed
2017-12-05 10:50:18,439 [salt.state       ][INFO    ][27773] Completed state [attr] at time 10:50:18.438631 duration_in_ms=265.709
2017-12-05 10:50:18,439 [salt.state       ][INFO    ][27773] Running state [glusterfs-server] at time 10:50:18.438905
2017-12-05 10:50:18,439 [salt.state       ][INFO    ][27773] Executing state pkg.installed for glusterfs-server
2017-12-05 10:50:18,443 [salt.state       ][INFO    ][27773] Package glusterfs-server is already installed
2017-12-05 10:50:18,443 [salt.state       ][INFO    ][27773] Completed state [glusterfs-server] at time 10:50:18.442915 duration_in_ms=4.009
2017-12-05 10:50:18,443 [salt.state       ][INFO    ][27773] Running state [bc] at time 10:50:18.443112
2017-12-05 10:50:18,443 [salt.state       ][INFO    ][27773] Executing state pkg.installed for bc
2017-12-05 10:50:18,447 [salt.state       ][INFO    ][27773] Package bc is already installed
2017-12-05 10:50:18,447 [salt.state       ][INFO    ][27773] Completed state [bc] at time 10:50:18.446992 duration_in_ms=3.88
2017-12-05 10:50:18,448 [salt.state       ][INFO    ][27773] Running state [glusterfs-server] at time 10:50:18.448021
2017-12-05 10:50:18,448 [salt.state       ][INFO    ][27773] Executing state service.running for glusterfs-server
2017-12-05 10:50:18,449 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-12-05 10:50:18,458 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-12-05 10:50:18,506 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-12-05 10:50:18,520 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-12-05 10:50:18,527 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-12-05 10:50:18,535 [salt.state       ][INFO    ][27773] The service glusterfs-server is already running
2017-12-05 10:50:18,536 [salt.state       ][INFO    ][27773] Completed state [glusterfs-server] at time 10:50:18.535536 duration_in_ms=87.513
2017-12-05 10:50:18,537 [salt.state       ][INFO    ][27773] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:50:18.537130
2017-12-05 10:50:18,537 [salt.state       ][INFO    ][27773] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-12-05 10:50:18,540 [salt.state       ][INFO    ][27773] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-12-05 10:50:18,540 [salt.state       ][INFO    ][27773] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:50:18.539710 duration_in_ms=2.579
2017-12-05 10:50:18,540 [salt.state       ][INFO    ][27773] Running state [/srv/glusterfs/glance] at time 10:50:18.539996
2017-12-05 10:50:18,540 [salt.state       ][INFO    ][27773] Executing state file.directory for /srv/glusterfs/glance
2017-12-05 10:50:18,541 [salt.state       ][INFO    ][27773] Directory /srv/glusterfs/glance is in the correct state
2017-12-05 10:50:18,541 [salt.state       ][INFO    ][27773] Completed state [/srv/glusterfs/glance] at time 10:50:18.540923 duration_in_ms=0.927
2017-12-05 10:50:18,541 [salt.state       ][INFO    ][27773] Running state [/srv/glusterfs/keystone-keys] at time 10:50:18.541182
2017-12-05 10:50:18,541 [salt.state       ][INFO    ][27773] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-12-05 10:50:18,542 [salt.state       ][INFO    ][27773] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-12-05 10:50:18,542 [salt.state       ][INFO    ][27773] Completed state [/srv/glusterfs/keystone-keys] at time 10:50:18.542120 duration_in_ms=0.937
2017-12-05 10:50:18,542 [salt.state       ][INFO    ][27773] Running state [/srv/glusterfs/nova_instances] at time 10:50:18.542392
2017-12-05 10:50:18,543 [salt.state       ][INFO    ][27773] Executing state file.directory for /srv/glusterfs/nova_instances
2017-12-05 10:50:18,543 [salt.state       ][INFO    ][27773] Directory /srv/glusterfs/nova_instances is in the correct state
2017-12-05 10:50:18,543 [salt.state       ][INFO    ][27773] Completed state [/srv/glusterfs/nova_instances] at time 10:50:18.543280 duration_in_ms=0.887
2017-12-05 10:50:18,544 [salt.state       ][INFO    ][27773] Running state [/srv/glusterfs/keystone-credential-keys] at time 10:50:18.543535
2017-12-05 10:50:18,544 [salt.state       ][INFO    ][27773] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-12-05 10:50:18,544 [salt.state       ][INFO    ][27773] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-12-05 10:50:18,544 [salt.state       ][INFO    ][27773] Completed state [/srv/glusterfs/keystone-credential-keys] at time 10:50:18.544414 duration_in_ms=0.879
2017-12-05 10:50:18,611 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command ['git', '--version'] in directory '/root'
2017-12-05 10:50:18,691 [salt.state       ][INFO    ][27773] Running state [sleep 5] at time 10:50:18.690851
2017-12-05 10:50:18,691 [salt.state       ][INFO    ][27773] Executing state cmd.wait for sleep 5
2017-12-05 10:50:18,692 [salt.state       ][INFO    ][27773] No changes made for sleep 5
2017-12-05 10:50:18,692 [salt.state       ][INFO    ][27773] Completed state [sleep 5] at time 10:50:18.691890 duration_in_ms=1.04
2017-12-05 10:50:18,693 [salt.state       ][INFO    ][27773] Running state [192.168.10.143] at time 10:50:18.692529
2017-12-05 10:50:18,693 [salt.state       ][INFO    ][27773] Executing state glusterfs.peered for 192.168.10.143
2017-12-05 10:50:18,693 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:18,702 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:18,758 [salt.state       ][INFO    ][27773] Host 192.168.10.143 already peered
2017-12-05 10:50:18,758 [salt.state       ][INFO    ][27773] Completed state [192.168.10.143] at time 10:50:18.758063 duration_in_ms=65.532
2017-12-05 10:50:18,761 [salt.state       ][INFO    ][27773] Running state [192.168.10.142] at time 10:50:18.761056
2017-12-05 10:50:18,761 [salt.state       ][INFO    ][27773] Executing state glusterfs.peered for 192.168.10.142
2017-12-05 10:50:18,762 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:18,770 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:18,825 [salt.state       ][INFO    ][27773] Host 192.168.10.142 already peered
2017-12-05 10:50:18,826 [salt.state       ][INFO    ][27773] Completed state [192.168.10.142] at time 10:50:18.825645 duration_in_ms=64.589
2017-12-05 10:50:18,829 [salt.state       ][INFO    ][27773] Running state [192.168.10.141] at time 10:50:18.828591
2017-12-05 10:50:18,829 [salt.state       ][INFO    ][27773] Executing state glusterfs.peered for 192.168.10.141
2017-12-05 10:50:18,829 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:18,837 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:18,892 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:18,900 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:18,956 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:18,964 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:19,018 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:19,026 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:19,081 [salt.state       ][INFO    ][27773] Peering with localhost is not needed
2017-12-05 10:50:19,081 [salt.state       ][INFO    ][27773] Completed state [192.168.10.141] at time 10:50:19.081383 duration_in_ms=252.792
2017-12-05 10:50:19,083 [salt.state       ][INFO    ][27773] Running state [glance] at time 10:50:19.082557
2017-12-05 10:50:19,083 [salt.state       ][INFO    ][27773] Executing state glusterfs.created for glance
2017-12-05 10:50:19,083 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:19,090 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:19,144 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:19,152 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:19,211 [salt.state       ][INFO    ][27773] Volume glance already exists and is started.
2017-12-05 10:50:19,211 [salt.state       ][INFO    ][27773] Completed state [glance] at time 10:50:19.211312 duration_in_ms=128.755
2017-12-05 10:50:19,216 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 10:50:19.215495
2017-12-05 10:50:19,216 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-12-05 10:50:19,216 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-05 10:50:19,270 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:19,270 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 10:50:19.270026 duration_in_ms=54.53
2017-12-05 10:50:19,271 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 10:50:19.270671
2017-12-05 10:50:19,271 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-12-05 10:50:19,271 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-05 10:50:19,327 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:19,327 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 10:50:19.327126 duration_in_ms=56.455
2017-12-05 10:50:19,328 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 10:50:19.327749
2017-12-05 10:50:19,328 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-12-05 10:50:19,328 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-12-05 10:50:19,385 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:19,385 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 10:50:19.385446 duration_in_ms=57.697
2017-12-05 10:50:19,386 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 10:50:19.386149
2017-12-05 10:50:19,386 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'
2017-12-05 10:50:19,387 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'glance' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-05 10:50:19,442 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:19,442 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 10:50:19.442306 duration_in_ms=56.157
2017-12-05 10:50:19,443 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 10:50:19.442920
2017-12-05 10:50:19,443 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-12-05 10:50:19,444 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-05 10:50:19,497 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:19,497 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 10:50:19.496883 duration_in_ms=53.963
2017-12-05 10:50:19,497 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 10:50:19.497445
2017-12-05 10:50:19,498 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-12-05 10:50:19,498 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-05 10:50:19,554 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:19,555 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 10:50:19.554680 duration_in_ms=57.234
2017-12-05 10:50:19,556 [salt.state       ][INFO    ][27773] Running state [glance] at time 10:50:19.556223
2017-12-05 10:50:19,557 [salt.state       ][INFO    ][27773] Executing state glusterfs.started for glance
2017-12-05 10:50:19,557 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:19,565 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:19,620 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:19,627 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:19,686 [salt.state       ][INFO    ][27773] Volume glance is already started
2017-12-05 10:50:19,687 [salt.state       ][INFO    ][27773] Completed state [glance] at time 10:50:19.686453 duration_in_ms=130.23
2017-12-05 10:50:19,688 [salt.state       ][INFO    ][27773] Running state [keystone-keys] at time 10:50:19.687534
2017-12-05 10:50:19,688 [salt.state       ][INFO    ][27773] Executing state glusterfs.created for keystone-keys
2017-12-05 10:50:19,688 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:19,696 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:19,751 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:19,758 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:19,817 [salt.state       ][INFO    ][27773] Volume keystone-keys already exists and is started.
2017-12-05 10:50:19,817 [salt.state       ][INFO    ][27773] Completed state [keystone-keys] at time 10:50:19.816859 duration_in_ms=129.324
2017-12-05 10:50:19,822 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 10:50:19.821499
2017-12-05 10:50:19,822 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-12-05 10:50:19,824 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-05 10:50:19,884 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:19,885 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 10:50:19.884455 duration_in_ms=62.955
2017-12-05 10:50:19,886 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:50:19.885644
2017-12-05 10:50:19,886 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-12-05 10:50:19,887 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-05 10:50:19,944 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:19,944 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:50:19.943735 duration_in_ms=58.09
2017-12-05 10:50:19,944 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 10:50:19.944315
2017-12-05 10:50:19,945 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'
2017-12-05 10:50:19,945 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-05 10:50:19,999 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:19,999 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 10:50:19.999325 duration_in_ms=55.01
2017-12-05 10:50:19,1000 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:50:19.999929
2017-12-05 10:50:20,000 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-12-05 10:50:20,001 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-05 10:50:20,056 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:20,056 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:50:20.056427 duration_in_ms=56.498
2017-12-05 10:50:20,057 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 10:50:20.057157
2017-12-05 10:50:20,058 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-12-05 10:50:20,058 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-12-05 10:50:20,114 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:20,114 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 10:50:20.114378 duration_in_ms=57.221
2017-12-05 10:50:20,115 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 10:50:20.114991
2017-12-05 10:50:20,115 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-12-05 10:50:20,116 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-05 10:50:20,172 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:20,173 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 10:50:20.172594 duration_in_ms=57.602
2017-12-05 10:50:20,174 [salt.state       ][INFO    ][27773] Running state [keystone-keys] at time 10:50:20.174151
2017-12-05 10:50:20,174 [salt.state       ][INFO    ][27773] Executing state glusterfs.started for keystone-keys
2017-12-05 10:50:20,175 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:20,183 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:20,239 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:20,247 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:20,306 [salt.state       ][INFO    ][27773] Volume keystone-keys is already started
2017-12-05 10:50:20,306 [salt.state       ][INFO    ][27773] Completed state [keystone-keys] at time 10:50:20.305898 duration_in_ms=131.746
2017-12-05 10:50:20,307 [salt.state       ][INFO    ][27773] Running state [nova_instances] at time 10:50:20.307230
2017-12-05 10:50:20,308 [salt.state       ][INFO    ][27773] Executing state glusterfs.created for nova_instances
2017-12-05 10:50:20,308 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:20,316 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:20,371 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:20,379 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:20,438 [salt.state       ][INFO    ][27773] Volume nova_instances already exists and is started.
2017-12-05 10:50:20,438 [salt.state       ][INFO    ][27773] Completed state [nova_instances] at time 10:50:20.438193 duration_in_ms=130.962
2017-12-05 10:50:20,443 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 10:50:20.443000
2017-12-05 10:50:20,443 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-12-05 10:50:20,444 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-05 10:50:20,499 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:20,499 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 10:50:20.499341 duration_in_ms=56.341
2017-12-05 10:50:20,500 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 10:50:20.500017
2017-12-05 10:50:20,500 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'
2017-12-05 10:50:20,501 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-05 10:50:20,557 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:20,557 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 10:50:20.557143 duration_in_ms=57.126
2017-12-05 10:50:20,558 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 10:50:20.557741
2017-12-05 10:50:20,558 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'True'
2017-12-05 10:50:20,559 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-05 10:50:20,615 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:20,615 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 10:50:20.614992 duration_in_ms=57.25
2017-12-05 10:50:20,616 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 10:50:20.615635
2017-12-05 10:50:20,616 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'
2017-12-05 10:50:20,616 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-05 10:50:20,670 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:20,670 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 10:50:20.669763 duration_in_ms=54.128
2017-12-05 10:50:20,670 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 10:50:20.670392
2017-12-05 10:50:20,671 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'True'
2017-12-05 10:50:20,671 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: True'' in directory '/root'
2017-12-05 10:50:20,727 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:20,727 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 10:50:20.727404 duration_in_ms=57.012
2017-12-05 10:50:20,728 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 10:50:20.727997
2017-12-05 10:50:20,728 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-12-05 10:50:20,729 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-05 10:50:20,784 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:20,784 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 10:50:20.784212 duration_in_ms=56.214
2017-12-05 10:50:20,786 [salt.state       ][INFO    ][27773] Running state [nova_instances] at time 10:50:20.785646
2017-12-05 10:50:20,786 [salt.state       ][INFO    ][27773] Executing state glusterfs.started for nova_instances
2017-12-05 10:50:20,786 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:20,794 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:20,851 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:20,858 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:20,918 [salt.state       ][INFO    ][27773] Volume nova_instances is already started
2017-12-05 10:50:20,918 [salt.state       ][INFO    ][27773] Completed state [nova_instances] at time 10:50:20.917873 duration_in_ms=132.227
2017-12-05 10:50:20,919 [salt.state       ][INFO    ][27773] Running state [keystone-credential-keys] at time 10:50:20.919206
2017-12-05 10:50:20,920 [salt.state       ][INFO    ][27773] Executing state glusterfs.created for keystone-credential-keys
2017-12-05 10:50:20,920 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:20,928 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:20,984 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:20,992 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:21,050 [salt.state       ][INFO    ][27773] Volume keystone-credential-keys already exists and is started.
2017-12-05 10:50:21,051 [salt.state       ][INFO    ][27773] Completed state [keystone-credential-keys] at time 10:50:21.050671 duration_in_ms=131.466
2017-12-05 10:50:21,056 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 10:50:21.055469
2017-12-05 10:50:21,056 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-12-05 10:50:21,056 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-05 10:50:21,112 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:21,112 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 10:50:21.112407 duration_in_ms=56.925
2017-12-05 10:50:21,113 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 10:50:21.113013
2017-12-05 10:50:21,113 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'
2017-12-05 10:50:21,114 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-05 10:50:21,169 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:21,169 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 10:50:21.169114 duration_in_ms=56.091
2017-12-05 10:50:21,170 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:50:21.169676
2017-12-05 10:50:21,170 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-12-05 10:50:21,171 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-05 10:50:21,227 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:21,227 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:50:21.227342 duration_in_ms=57.666
2017-12-05 10:50:21,228 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 10:50:21.227995
2017-12-05 10:50:21,228 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-12-05 10:50:21,229 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-12-05 10:50:21,284 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:21,284 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 10:50:21.284414 duration_in_ms=56.418
2017-12-05 10:50:21,285 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:50:21.284979
2017-12-05 10:50:21,285 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-12-05 10:50:21,286 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-05 10:50:21,342 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:21,343 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:50:21.342491 duration_in_ms=57.511
2017-12-05 10:50:21,343 [salt.state       ][INFO    ][27773] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 10:50:21.343047
2017-12-05 10:50:21,343 [salt.state       ][INFO    ][27773] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-12-05 10:50:21,344 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-05 10:50:21,399 [salt.state       ][INFO    ][27773] unless execution succeeded
2017-12-05 10:50:21,399 [salt.state       ][INFO    ][27773] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 10:50:21.399215 duration_in_ms=56.169
2017-12-05 10:50:21,401 [salt.state       ][INFO    ][27773] Running state [keystone-credential-keys] at time 10:50:21.400637
2017-12-05 10:50:21,401 [salt.state       ][INFO    ][27773] Executing state glusterfs.started for keystone-credential-keys
2017-12-05 10:50:21,401 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:21,408 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:21,464 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --version' in directory '/root'
2017-12-05 10:50:21,472 [salt.loaded.int.module.cmdmod][INFO    ][27773] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-05 10:50:21,531 [salt.state       ][INFO    ][27773] Volume keystone-credential-keys is already started
2017-12-05 10:50:21,532 [salt.state       ][INFO    ][27773] Completed state [keystone-credential-keys] at time 10:50:21.531701 duration_in_ms=131.063
2017-12-05 10:50:21,534 [salt.minion      ][INFO    ][27773] Returning information for job: 20171205105015357522
2017-12-05 10:50:22,558 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command test.ping with jid 20171205105022557549
2017-12-05 10:50:22,570 [salt.minion      ][INFO    ][28300] Starting a new job with PID 28300
2017-12-05 10:50:22,604 [salt.minion      ][INFO    ][28300] Returning information for job: 20171205105022557549
2017-12-05 10:50:23,246 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command cmd.run with jid 20171205105023243524
2017-12-05 10:50:23,258 [salt.minion      ][INFO    ][28305] Starting a new job with PID 28305
2017-12-05 10:50:23,262 [salt.loaded.int.module.cmdmod][INFO    ][28305] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-12-05 10:50:23,380 [salt.minion      ][INFO    ][28305] Returning information for job: 20171205105023243524
2017-12-05 11:31:28,744 [salt.utils.schedule][INFO    ][15249] Running scheduled job: __mine_interval
2017-12-05 11:52:17,543 [salt.minion      ][INFO    ][15249] User sudo_ubuntu Executing command cp.push_dir with jid 20171205115217538782
2017-12-05 11:52:17,559 [salt.minion      ][INFO    ][29099] Starting a new job with PID 29099
