2017-12-04 18:25:43,819 [salt.cli.daemons ][WARNING ][2380] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-12-04 19:16:30,243 [salt.state       ][ERROR   ][3753] No contents found in top file
2017-12-04 19:18:06,076 [salt.utils.parsers][WARNING ][2380] Minion received a SIGTERM. Exiting.
2017-12-04 19:20:31,774 [salt.cli.daemons ][WARNING ][3281] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-12-04 19:21:08,367 [py.warnings      ][WARNING ][3432] /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-04 19:22:01,680 [salt.loaded.int.module.cmdmod][ERROR   ][3432] 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-04 19:22:41,175 [salt.transport.ipc][ERROR   ][3281] Exception occurred while handling stream: [Errno 0] Success
2017-12-04 19:23:16,958 [salt.loaded.int.module.cmdmod][INFO    ][16855] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-12-04 19:23:16,966 [salt.loaded.int.module.cmdmod][INFO    ][16855] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-12-04 19:23:16,977 [salt.loaded.int.module.cmdmod][INFO    ][16855] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-12-04 19:23:16,985 [salt.utils.parsers][WARNING ][3281] Minion received a SIGTERM. Exiting.
2017-12-04 19:23:17,244 [salt.cli.daemons ][INFO    ][16936] Setting up the Salt Minion "kvm01.baremetal-mcp-ocata-ovs-ha.local"
2017-12-04 19:23:17,781 [salt.minion      ][INFO    ][16936] Creating minion process manager
2017-12-04 19:23:17,782 [salt.cli.daemons ][WARNING ][16936] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-12-04 19:23:17,782 [salt.cli.daemons ][INFO    ][16936] The Salt Minion is starting up
2017-12-04 19:23:17,782 [salt.minion      ][INFO    ][16936] Minion is starting as user 'root'
2017-12-04 19:23:17,782 [salt.utils.event ][INFO    ][16936] Starting pull socket on /var/run/salt/minion/minion_event_fd678c5357_pull.ipc
2017-12-04 19:23:18,657 [salt.loaded.int.module.cmdmod][INFO    ][16936] Executing command ['date', '+%z'] in directory '/root'
2017-12-04 19:23:18,669 [salt.utils.schedule][INFO    ][16936] Updating job settings for scheduled job: __mine_interval
2017-12-04 19:23:18,671 [salt.minion      ][INFO    ][16936] Added mine.update to scheduler
2017-12-04 19:23:18,706 [salt.minion      ][INFO    ][16936] Minion is ready to receive requests!
2017-12-04 19:23:19,707 [salt.utils.schedule][INFO    ][16936] Running scheduled job: __mine_interval
2017-12-04 19:23:20,057 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command state.apply with jid 20171204192320048209
2017-12-04 19:23:20,071 [salt.minion      ][INFO    ][17032] Starting a new job with PID 17032
2017-12-04 19:23:22,510 [salt.state       ][INFO    ][17032] Loading fresh modules for state activity
2017-12-04 19:23:22,530 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/init.sls'
2017-12-04 19:23:22,552 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-12-04 19:23:22,565 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-12-04 19:23:22,587 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-04 19:23:22,641 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-04 19:23:22,658 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-04 19:23:22,700 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-04 19:23:22,720 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-04 19:23:22,734 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-04 19:23:22,751 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-04 19:23:22,758 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-04 19:23:22,770 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-04 19:23:22,789 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-04 19:23:22,804 [py.warnings      ][WARNING ][17032] /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-04 19:23:22,804 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'salt-minion --version' in directory '/root'
2017-12-04 19:23:23,052 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'salt-minion --version' in directory '/root'
2017-12-04 19:23:23,300 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-04 19:23:23,321 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-04 19:23:23,332 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-04 19:23:23,343 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-04 19:23:23,412 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-04 19:23:23,425 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-04 19:23:23,445 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-04 19:23:23,456 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-04 19:23:23,473 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-04 19:23:23,506 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-04 19:23:23,559 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-12-04 19:23:23,580 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-04 19:23:23,634 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-04 19:23:23,653 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-04 19:23:23,694 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-04 19:23:23,715 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-04 19:23:23,729 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-04 19:23:23,746 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-04 19:23:23,753 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-04 19:23:23,763 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-04 19:23:23,786 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-04 19:23:23,801 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'salt-minion --version' in directory '/root'
2017-12-04 19:23:24,039 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'salt-minion --version' in directory '/root'
2017-12-04 19:23:24,296 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-04 19:23:24,315 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-04 19:23:24,327 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-04 19:23:24,338 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-04 19:23:24,372 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-04 19:23:24,387 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-04 19:23:24,409 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-04 19:23:24,421 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-04 19:23:24,438 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-04 19:23:24,474 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-04 19:23:24,536 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-12-04 19:23:24,589 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-04 19:23:24,634 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-12-04 19:23:24,649 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-12-04 19:23:24,671 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-04 19:23:24,990 [salt.state       ][INFO    ][17032] Running state [salt-minion] at time 19:23:24.990396
2017-12-04 19:23:24,991 [salt.state       ][INFO    ][17032] Executing state pkg.installed for salt-minion
2017-12-04 19:23:24,991 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-04 19:23:25,245 [salt.state       ][INFO    ][17032] Package salt-minion is already installed
2017-12-04 19:23:25,246 [salt.state       ][INFO    ][17032] Completed state [salt-minion] at time 19:23:25.245629 duration_in_ms=255.232
2017-12-04 19:23:25,246 [salt.state       ][INFO    ][17032] Running state [salt_minion_dependency_packages] at time 19:23:25.245866
2017-12-04 19:23:25,246 [salt.state       ][INFO    ][17032] Executing state pkg.installed for salt_minion_dependency_packages
2017-12-04 19:23:25,249 [salt.state       ][INFO    ][17032] All specified packages are already installed
2017-12-04 19:23:25,249 [salt.state       ][INFO    ][17032] Completed state [salt_minion_dependency_packages] at time 19:23:25.248778 duration_in_ms=2.911
2017-12-04 19:23:25,250 [salt.state       ][INFO    ][17032] Running state [/etc/salt/minion.d/minion.conf] at time 19:23:25.250392
2017-12-04 19:23:25,251 [salt.state       ][INFO    ][17032] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-12-04 19:23:25,272 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-12-04 19:23:25,317 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-04 19:23:25,366 [salt.fileclient  ][INFO    ][17032] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-04 19:23:25,399 [salt.state       ][INFO    ][17032] File /etc/salt/minion.d/minion.conf is in the correct state
2017-12-04 19:23:25,400 [salt.state       ][INFO    ][17032] Completed state [/etc/salt/minion.d/minion.conf] at time 19:23:25.399545 duration_in_ms=149.153
2017-12-04 19:23:25,400 [salt.state       ][INFO    ][17032] Running state [/etc/salt/minion.d/_virt.conf] at time 19:23:25.399816
2017-12-04 19:23:25,400 [salt.state       ][INFO    ][17032] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-12-04 19:23:25,403 [salt.state       ][INFO    ][17032] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-12-04 19:23:25,403 [salt.state       ][INFO    ][17032] Completed state [/etc/salt/minion.d/_virt.conf] at time 19:23:25.403233 duration_in_ms=3.417
2017-12-04 19:23:25,405 [salt.state       ][INFO    ][17032] Running state [salt-minion] at time 19:23:25.404555
2017-12-04 19:23:25,405 [salt.state       ][INFO    ][17032] Executing state service.running for salt-minion
2017-12-04 19:23:25,405 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-12-04 19:23:25,422 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-12-04 19:23:25,430 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-12-04 19:23:25,437 [salt.state       ][INFO    ][17032] The service salt-minion is already running
2017-12-04 19:23:25,437 [salt.state       ][INFO    ][17032] Completed state [salt-minion] at time 19:23:25.437185 duration_in_ms=32.629
2017-12-04 19:23:25,439 [salt.state       ][INFO    ][17032] Running state [/etc/salt/grains.d] at time 19:23:25.438535
2017-12-04 19:23:25,439 [salt.state       ][INFO    ][17032] Executing state file.directory for /etc/salt/grains.d
2017-12-04 19:23:25,439 [salt.state       ][INFO    ][17032] Directory /etc/salt/grains.d is in the correct state
2017-12-04 19:23:25,440 [salt.state       ][INFO    ][17032] Completed state [/etc/salt/grains.d] at time 19:23:25.439530 duration_in_ms=0.995
2017-12-04 19:23:25,440 [salt.state       ][INFO    ][17032] Running state [/etc/salt/grains] at time 19:23:25.440005
2017-12-04 19:23:25,440 [salt.state       ][INFO    ][17032] Executing state file.managed for /etc/salt/grains
2017-12-04 19:23:25,441 [salt.state       ][INFO    ][17032] File /etc/salt/grains exists with proper permissions. No changes made.
2017-12-04 19:23:25,441 [salt.state       ][INFO    ][17032] Completed state [/etc/salt/grains] at time 19:23:25.440761 duration_in_ms=0.756
2017-12-04 19:23:25,441 [salt.state       ][INFO    ][17032] Running state [/etc/salt/grains.d/placeholder] at time 19:23:25.441071
2017-12-04 19:23:25,441 [salt.state       ][INFO    ][17032] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-12-04 19:23:25,442 [salt.state       ][INFO    ][17032] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-12-04 19:23:25,442 [salt.state       ][INFO    ][17032] Completed state [/etc/salt/grains.d/placeholder] at time 19:23:25.441820 duration_in_ms=0.749
2017-12-04 19:23:25,442 [salt.state       ][INFO    ][17032] Running state [/etc/salt/grains.d/sphinx] at time 19:23:25.442147
2017-12-04 19:23:25,442 [salt.state       ][INFO    ][17032] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-12-04 19:23:25,450 [salt.state       ][INFO    ][17032] File /etc/salt/grains.d/sphinx is in the correct state
2017-12-04 19:23:25,450 [salt.state       ][INFO    ][17032] Completed state [/etc/salt/grains.d/sphinx] at time 19:23:25.450087 duration_in_ms=7.939
2017-12-04 19:23:25,451 [salt.state       ][INFO    ][17032] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 19:23:25.450691
2017-12-04 19:23:25,451 [salt.state       ][INFO    ][17032] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-04 19:23:25,451 [salt.state       ][INFO    ][17032] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-04 19:23:25,451 [salt.state       ][INFO    ][17032] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 19:23:25.451303 duration_in_ms=0.612
2017-12-04 19:23:25,452 [salt.state       ][INFO    ][17032] Running state [/etc/salt/grains.d/dns_records] at time 19:23:25.451632
2017-12-04 19:23:25,452 [salt.state       ][INFO    ][17032] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-12-04 19:23:25,456 [salt.state       ][INFO    ][17032] File /etc/salt/grains.d/dns_records is in the correct state
2017-12-04 19:23:25,456 [salt.state       ][INFO    ][17032] Completed state [/etc/salt/grains.d/dns_records] at time 19:23:25.455951 duration_in_ms=4.318
2017-12-04 19:23:25,457 [salt.state       ][INFO    ][17032] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 19:23:25.456548
2017-12-04 19:23:25,457 [salt.state       ][INFO    ][17032] 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-04 19:23:25,457 [salt.state       ][INFO    ][17032] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-12-04 19:23:25,457 [salt.state       ][INFO    ][17032] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 19:23:25.457158 duration_in_ms=0.611
2017-12-04 19:23:25,458 [salt.state       ][INFO    ][17032] Running state [/etc/salt/grains.d/salt] at time 19:23:25.457472
2017-12-04 19:23:25,458 [salt.state       ][INFO    ][17032] Executing state file.managed for /etc/salt/grains.d/salt
2017-12-04 19:23:25,462 [salt.state       ][INFO    ][17032] File /etc/salt/grains.d/salt is in the correct state
2017-12-04 19:23:25,462 [salt.state       ][INFO    ][17032] Completed state [/etc/salt/grains.d/salt] at time 19:23:25.461873 duration_in_ms=4.401
2017-12-04 19:23:25,462 [salt.state       ][INFO    ][17032] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 19:23:25.462450
2017-12-04 19:23:25,463 [salt.state       ][INFO    ][17032] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-04 19:23:25,463 [salt.state       ][INFO    ][17032] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-04 19:23:25,463 [salt.state       ][INFO    ][17032] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 19:23:25.463057 duration_in_ms=0.607
2017-12-04 19:23:25,464 [salt.state       ][INFO    ][17032] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 19:23:25.464311
2017-12-04 19:23:25,465 [salt.state       ][INFO    ][17032] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-04 19:23:25,465 [salt.state       ][INFO    ][17032] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-04 19:23:25,465 [salt.state       ][INFO    ][17032] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 19:23:25.464929 duration_in_ms=0.618
2017-12-04 19:23:25,465 [salt.state       ][INFO    ][17032] Running state [mine.update] at time 19:23:25.465384
2017-12-04 19:23:25,466 [salt.state       ][INFO    ][17032] Executing state module.wait for mine.update
2017-12-04 19:23:25,466 [salt.state       ][INFO    ][17032] No changes made for mine.update
2017-12-04 19:23:25,466 [salt.state       ][INFO    ][17032] Completed state [mine.update] at time 19:23:25.465952 duration_in_ms=0.569
2017-12-04 19:23:25,466 [salt.state       ][INFO    ][17032] Running state [ca-certificates] at time 19:23:25.466138
2017-12-04 19:23:25,466 [salt.state       ][INFO    ][17032] Executing state pkg.installed for ca-certificates
2017-12-04 19:23:25,469 [salt.state       ][INFO    ][17032] Package ca-certificates is already installed
2017-12-04 19:23:25,470 [salt.state       ][INFO    ][17032] Completed state [ca-certificates] at time 19:23:25.469554 duration_in_ms=3.415
2017-12-04 19:23:25,470 [salt.state       ][INFO    ][17032] Running state [update-ca-certificates] at time 19:23:25.470022
2017-12-04 19:23:25,470 [salt.state       ][INFO    ][17032] Executing state cmd.wait for update-ca-certificates
2017-12-04 19:23:25,470 [salt.state       ][INFO    ][17032] No changes made for update-ca-certificates
2017-12-04 19:23:25,471 [salt.state       ][INFO    ][17032] Completed state [update-ca-certificates] at time 19:23:25.470617 duration_in_ms=0.595
2017-12-04 19:23:25,471 [salt.state       ][INFO    ][17032] Running state [libvirt-dev] at time 19:23:25.470804
2017-12-04 19:23:25,471 [salt.state       ][INFO    ][17032] Executing state pkg.installed for libvirt-dev
2017-12-04 19:23:25,479 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-12-04 19:23:26,962 [salt.loaded.int.module.cmdmod][INFO    ][17032] 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-04 19:23:30,141 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command saltutil.find_job with jid 20171204192330133223
2017-12-04 19:23:30,156 [salt.minion      ][INFO    ][17505] Starting a new job with PID 17505
2017-12-04 19:23:30,165 [salt.minion      ][INFO    ][17505] Returning information for job: 20171204192330133223
2017-12-04 19:23:40,328 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command saltutil.find_job with jid 20171204192340319045
2017-12-04 19:23:40,344 [salt.minion      ][INFO    ][17658] Starting a new job with PID 17658
2017-12-04 19:23:40,353 [salt.minion      ][INFO    ][17658] Returning information for job: 20171204192340319045
2017-12-04 19:23:50,521 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command saltutil.find_job with jid 20171204192350512712
2017-12-04 19:23:50,536 [salt.minion      ][INFO    ][17854] Starting a new job with PID 17854
2017-12-04 19:23:50,546 [salt.minion      ][INFO    ][17854] Returning information for job: 20171204192350512712
2017-12-04 19:24:00,724 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command saltutil.find_job with jid 20171204192400716047
2017-12-04 19:24:00,739 [salt.minion      ][INFO    ][22642] Starting a new job with PID 22642
2017-12-04 19:24:00,765 [salt.minion      ][INFO    ][22642] Returning information for job: 20171204192400716047
2017-12-04 19:24:01,455 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-04 19:24:01,480 [salt.state       ][INFO    ][17032] 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-04 19:24:01,489 [salt.state       ][INFO    ][17032] Loading fresh modules for state activity
2017-12-04 19:24:01,501 [salt.state       ][INFO    ][17032] Completed state [libvirt-dev] at time 19:24:01.501273 duration_in_ms=36030.469
2017-12-04 19:24:01,506 [salt.state       ][INFO    ][17032] Running state [pkg-config] at time 19:24:01.506067
2017-12-04 19:24:01,506 [salt.state       ][INFO    ][17032] Executing state pkg.installed for pkg-config
2017-12-04 19:24:01,718 [salt.state       ][INFO    ][17032] Package pkg-config is already installed
2017-12-04 19:24:01,719 [salt.state       ][INFO    ][17032] Completed state [pkg-config] at time 19:24:01.718572 duration_in_ms=212.505
2017-12-04 19:24:01,719 [salt.state       ][INFO    ][17032] Running state [virtng.init] at time 19:24:01.718921
2017-12-04 19:24:01,719 [salt.state       ][INFO    ][17032] Executing state module.run for virtng.init
2017-12-04 19:24:01,754 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command ['git', '--version'] in directory '/root'
2017-12-04 19:24:01,793 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'virsh list --all --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:24:10,935 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command saltutil.find_job with jid 20171204192410931936
2017-12-04 19:24:10,949 [salt.minion      ][INFO    ][22718] Starting a new job with PID 22718
2017-12-04 19:24:10,960 [salt.minion      ][INFO    ][22718] Returning information for job: 20171204192410931936
2017-12-04 19:24:17,540 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-12-04 19:24:17,566 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-04 19:24:17,608 [salt.loaded.int.module.cmdmod][ERROR   ][17032] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-04 19:24:17,609 [salt.loaded.int.module.cmdmod][ERROR   ][17032] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-04 19:24:17,609 [salt.loaded.int.module.cmdmod][INFO    ][17032] 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-04 19:24:17,623 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-04 19:24:17,630 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-12-04 19:24:18,674 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-12-04 19:24:19,202 [salt.loaded.ext.module.seedng][INFO    ][17032] salt-minion pre-installed on image, configuring as prx01.baremetal-mcp-ocata-ovs-ha.local
2017-12-04 19:24:19,208 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'mount -l' in directory '/root'
2017-12-04 19:24:19,272 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-12-04 19:24:20,123 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-12-04 19:24:21,136 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command saltutil.find_job with jid 20171204192421131120
2017-12-04 19:24:21,149 [salt.minion      ][INFO    ][23064] Starting a new job with PID 23064
2017-12-04 19:24:21,159 [salt.minion      ][INFO    ][23064] Returning information for job: 20171204192421131120
2017-12-04 19:24:22,087 [salt.state       ][INFO    ][17032] {'ret': True}
2017-12-04 19:24:22,087 [salt.state       ][INFO    ][17032] Completed state [virtng.init] at time 19:24:22.086938 duration_in_ms=20368.016
2017-12-04 19:24:22,087 [salt.state       ][INFO    ][17032] Running state [virt.set_autostart] at time 19:24:22.087202
2017-12-04 19:24:22,087 [salt.state       ][INFO    ][17032] Executing state module.run for virt.set_autostart
2017-12-04 19:24:22,089 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'virsh list --autostart --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:24:22,106 [salt.state       ][INFO    ][17032] {'ret': True}
2017-12-04 19:24:22,106 [salt.state       ][INFO    ][17032] Completed state [virt.set_autostart] at time 19:24:22.106367 duration_in_ms=19.165
2017-12-04 19:24:22,107 [salt.state       ][INFO    ][17032] Running state [virtng.init] at time 19:24:22.106560
2017-12-04 19:24:22,107 [salt.state       ][INFO    ][17032] Executing state module.run for virtng.init
2017-12-04 19:24:22,109 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'virsh list --all --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:24:24,239 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-12-04 19:24:24,249 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-04 19:24:24,255 [salt.loaded.int.module.cmdmod][ERROR   ][17032] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-04 19:24:24,256 [salt.loaded.int.module.cmdmod][ERROR   ][17032] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-04 19:24:24,256 [salt.loaded.int.module.cmdmod][INFO    ][17032] 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-04 19:24:24,271 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-04 19:24:24,279 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-12-04 19:24:25,328 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-12-04 19:24:25,999 [salt.loaded.ext.module.seedng][INFO    ][17032] salt-minion pre-installed on image, configuring as mdb01.baremetal-mcp-ocata-ovs-ha.local
2017-12-04 19:24:26,004 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'mount -l' in directory '/root'
2017-12-04 19:24:26,181 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-12-04 19:24:28,310 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-12-04 19:24:29,695 [salt.state       ][INFO    ][17032] {'ret': True}
2017-12-04 19:24:29,695 [salt.state       ][INFO    ][17032] Completed state [virtng.init] at time 19:24:29.695240 duration_in_ms=7588.679
2017-12-04 19:24:29,696 [salt.state       ][INFO    ][17032] Running state [virt.set_autostart] at time 19:24:29.695578
2017-12-04 19:24:29,696 [salt.state       ][INFO    ][17032] Executing state module.run for virt.set_autostart
2017-12-04 19:24:29,698 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'virsh list --autostart --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:24:29,717 [salt.state       ][INFO    ][17032] {'ret': True}
2017-12-04 19:24:29,717 [salt.state       ][INFO    ][17032] Completed state [virt.set_autostart] at time 19:24:29.717291 duration_in_ms=21.715
2017-12-04 19:24:29,718 [salt.state       ][INFO    ][17032] Running state [virtng.init] at time 19:24:29.717508
2017-12-04 19:24:29,718 [salt.state       ][INFO    ][17032] Executing state module.run for virtng.init
2017-12-04 19:24:29,720 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'virsh list --all --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:24:31,327 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command saltutil.find_job with jid 20171204192431320489
2017-12-04 19:24:31,341 [salt.minion      ][INFO    ][23442] Starting a new job with PID 23442
2017-12-04 19:24:31,350 [salt.minion      ][INFO    ][23442] Returning information for job: 20171204192431320489
2017-12-04 19:24:31,758 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-12-04 19:24:31,765 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-04 19:24:31,770 [salt.loaded.int.module.cmdmod][ERROR   ][17032] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-04 19:24:31,771 [salt.loaded.int.module.cmdmod][ERROR   ][17032] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-04 19:24:31,771 [salt.loaded.int.module.cmdmod][INFO    ][17032] 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-04 19:24:31,784 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-04 19:24:31,791 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-12-04 19:24:32,840 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-12-04 19:24:33,424 [salt.loaded.ext.module.seedng][INFO    ][17032] salt-minion pre-installed on image, configuring as ctl01.baremetal-mcp-ocata-ovs-ha.local
2017-12-04 19:24:33,429 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'mount -l' in directory '/root'
2017-12-04 19:24:33,514 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-12-04 19:24:40,049 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-12-04 19:24:41,355 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command saltutil.find_job with jid 20171204192441347269
2017-12-04 19:24:41,367 [salt.minion      ][INFO    ][23685] Starting a new job with PID 23685
2017-12-04 19:24:41,376 [salt.minion      ][INFO    ][23685] Returning information for job: 20171204192441347269
2017-12-04 19:24:41,458 [salt.state       ][INFO    ][17032] {'ret': True}
2017-12-04 19:24:41,458 [salt.state       ][INFO    ][17032] Completed state [virtng.init] at time 19:24:41.457988 duration_in_ms=11740.479
2017-12-04 19:24:41,458 [salt.state       ][INFO    ][17032] Running state [virt.set_autostart] at time 19:24:41.458376
2017-12-04 19:24:41,459 [salt.state       ][INFO    ][17032] Executing state module.run for virt.set_autostart
2017-12-04 19:24:41,461 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'virsh list --autostart --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:24:41,479 [salt.state       ][INFO    ][17032] {'ret': True}
2017-12-04 19:24:41,480 [salt.state       ][INFO    ][17032] Completed state [virt.set_autostart] at time 19:24:41.479536 duration_in_ms=21.159
2017-12-04 19:24:41,480 [salt.state       ][INFO    ][17032] Running state [virtng.init] at time 19:24:41.479760
2017-12-04 19:24:41,480 [salt.state       ][INFO    ][17032] Executing state module.run for virtng.init
2017-12-04 19:24:41,482 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'virsh list --all --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:24:43,688 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-12-04 19:24:43,695 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-04 19:24:43,699 [salt.loaded.int.module.cmdmod][ERROR   ][17032] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-04 19:24:43,700 [salt.loaded.int.module.cmdmod][ERROR   ][17032] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-04 19:24:43,700 [salt.loaded.int.module.cmdmod][INFO    ][17032] 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-04 19:24:43,713 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-04 19:24:43,718 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-12-04 19:24:44,756 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-12-04 19:24:45,338 [salt.loaded.ext.module.seedng][INFO    ][17032] salt-minion pre-installed on image, configuring as msg01.baremetal-mcp-ocata-ovs-ha.local
2017-12-04 19:24:45,342 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'mount -l' in directory '/root'
2017-12-04 19:24:45,516 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-12-04 19:24:47,862 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-12-04 19:24:49,274 [salt.state       ][INFO    ][17032] {'ret': True}
2017-12-04 19:24:49,274 [salt.state       ][INFO    ][17032] Completed state [virtng.init] at time 19:24:49.274058 duration_in_ms=7794.298
2017-12-04 19:24:49,274 [salt.state       ][INFO    ][17032] Running state [virt.set_autostart] at time 19:24:49.274308
2017-12-04 19:24:49,275 [salt.state       ][INFO    ][17032] Executing state module.run for virt.set_autostart
2017-12-04 19:24:49,277 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'virsh list --autostart --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:24:49,293 [salt.state       ][INFO    ][17032] {'ret': True}
2017-12-04 19:24:49,293 [salt.state       ][INFO    ][17032] Completed state [virt.set_autostart] at time 19:24:49.293291 duration_in_ms=18.982
2017-12-04 19:24:49,294 [salt.state       ][INFO    ][17032] Running state [virtng.init] at time 19:24:49.293504
2017-12-04 19:24:49,294 [salt.state       ][INFO    ][17032] Executing state module.run for virtng.init
2017-12-04 19:24:49,296 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'virsh list --all --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:24:51,373 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-12-04 19:24:51,381 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-04 19:24:51,387 [salt.loaded.int.module.cmdmod][ERROR   ][17032] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-04 19:24:51,388 [salt.loaded.int.module.cmdmod][ERROR   ][17032] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-04 19:24:51,388 [salt.loaded.int.module.cmdmod][INFO    ][17032] 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-04 19:24:51,403 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-12-04 19:24:51,410 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-12-04 19:24:51,554 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command saltutil.find_job with jid 20171204192451546352
2017-12-04 19:24:51,568 [salt.minion      ][INFO    ][23984] Starting a new job with PID 23984
2017-12-04 19:24:51,577 [salt.minion      ][INFO    ][23984] Returning information for job: 20171204192451546352
2017-12-04 19:24:52,449 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-12-04 19:24:52,976 [salt.loaded.ext.module.seedng][INFO    ][17032] salt-minion pre-installed on image, configuring as dbs01.baremetal-mcp-ocata-ovs-ha.local
2017-12-04 19:24:52,982 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'mount -l' in directory '/root'
2017-12-04 19:24:53,158 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-12-04 19:24:56,423 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-12-04 19:24:57,828 [salt.state       ][INFO    ][17032] {'ret': True}
2017-12-04 19:24:57,829 [salt.state       ][INFO    ][17032] Completed state [virtng.init] at time 19:24:57.828732 duration_in_ms=8535.228
2017-12-04 19:24:57,829 [salt.state       ][INFO    ][17032] Running state [virt.set_autostart] at time 19:24:57.829103
2017-12-04 19:24:57,829 [salt.state       ][INFO    ][17032] Executing state module.run for virt.set_autostart
2017-12-04 19:24:57,831 [salt.loaded.int.module.cmdmod][INFO    ][17032] Executing command 'virsh list --autostart --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:24:57,849 [salt.state       ][INFO    ][17032] {'ret': True}
2017-12-04 19:24:57,849 [salt.state       ][INFO    ][17032] Completed state [virt.set_autostart] at time 19:24:57.849001 duration_in_ms=19.898
2017-12-04 19:24:57,852 [salt.minion      ][INFO    ][17032] Returning information for job: 20171204192320048209
2017-12-04 19:25:10,674 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command state.sls with jid 20171204192510671489
2017-12-04 19:25:10,690 [salt.minion      ][INFO    ][24225] Starting a new job with PID 24225
2017-12-04 19:25:13,149 [salt.state       ][INFO    ][24225] Loading fresh modules for state activity
2017-12-04 19:25:13,179 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-12-04 19:25:13,196 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-12-04 19:25:13,220 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-04 19:25:13,357 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-12-04 19:25:13,372 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-12-04 19:25:13,397 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-04 19:25:13,453 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-04 19:25:13,471 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-04 19:25:13,516 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-04 19:25:13,538 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-04 19:25:13,550 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-04 19:25:13,568 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-04 19:25:13,575 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-04 19:25:13,586 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-04 19:25:13,606 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-04 19:25:13,621 [py.warnings      ][WARNING ][24225] /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-04 19:25:13,621 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'salt-minion --version' in directory '/root'
2017-12-04 19:25:13,847 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'salt-minion --version' in directory '/root'
2017-12-04 19:25:14,083 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-04 19:25:14,102 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-04 19:25:14,112 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-04 19:25:14,125 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-04 19:25:14,168 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-04 19:25:14,181 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-04 19:25:14,200 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-04 19:25:14,213 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-04 19:25:14,230 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-04 19:25:14,262 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-04 19:25:14,316 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-12-04 19:25:14,338 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-04 19:25:14,397 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-04 19:25:14,416 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-04 19:25:14,459 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-04 19:25:14,482 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-04 19:25:14,516 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-04 19:25:14,533 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-04 19:25:14,540 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-04 19:25:14,551 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-04 19:25:14,582 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-04 19:25:14,598 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'salt-minion --version' in directory '/root'
2017-12-04 19:25:14,828 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'salt-minion --version' in directory '/root'
2017-12-04 19:25:15,075 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-04 19:25:15,095 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-04 19:25:15,108 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-04 19:25:15,120 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-04 19:25:15,168 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-04 19:25:15,182 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-04 19:25:15,206 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-04 19:25:15,219 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-04 19:25:15,236 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-04 19:25:15,272 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-04 19:25:15,333 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-12-04 19:25:15,389 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-04 19:25:15,678 [salt.state       ][INFO    ][24225] Running state [salt-minion] at time 19:25:15.678221
2017-12-04 19:25:15,679 [salt.state       ][INFO    ][24225] Executing state pkg.installed for salt-minion
2017-12-04 19:25:15,679 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-04 19:25:15,975 [salt.state       ][INFO    ][24225] Package salt-minion is already installed
2017-12-04 19:25:15,975 [salt.state       ][INFO    ][24225] Completed state [salt-minion] at time 19:25:15.975177 duration_in_ms=296.956
2017-12-04 19:25:15,975 [salt.state       ][INFO    ][24225] Running state [salt_minion_dependency_packages] at time 19:25:15.975416
2017-12-04 19:25:15,976 [salt.state       ][INFO    ][24225] Executing state pkg.installed for salt_minion_dependency_packages
2017-12-04 19:25:15,978 [salt.state       ][INFO    ][24225] All specified packages are already installed
2017-12-04 19:25:15,978 [salt.state       ][INFO    ][24225] Completed state [salt_minion_dependency_packages] at time 19:25:15.978392 duration_in_ms=2.977
2017-12-04 19:25:15,980 [salt.state       ][INFO    ][24225] Running state [/etc/salt/minion.d/minion.conf] at time 19:25:15.980008
2017-12-04 19:25:15,980 [salt.state       ][INFO    ][24225] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-12-04 19:25:16,003 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-12-04 19:25:16,072 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-04 19:25:16,120 [salt.fileclient  ][INFO    ][24225] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-04 19:25:16,154 [salt.state       ][INFO    ][24225] File /etc/salt/minion.d/minion.conf is in the correct state
2017-12-04 19:25:16,154 [salt.state       ][INFO    ][24225] Completed state [/etc/salt/minion.d/minion.conf] at time 19:25:16.154219 duration_in_ms=174.211
2017-12-04 19:25:16,155 [salt.state       ][INFO    ][24225] Running state [/etc/salt/minion.d/_virt.conf] at time 19:25:16.154513
2017-12-04 19:25:16,155 [salt.state       ][INFO    ][24225] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-12-04 19:25:16,191 [salt.state       ][INFO    ][24225] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-12-04 19:25:16,191 [salt.state       ][INFO    ][24225] Completed state [/etc/salt/minion.d/_virt.conf] at time 19:25:16.191005 duration_in_ms=36.492
2017-12-04 19:25:16,193 [salt.state       ][INFO    ][24225] Running state [salt-minion] at time 19:25:16.192513
2017-12-04 19:25:16,193 [salt.state       ][INFO    ][24225] Executing state service.running for salt-minion
2017-12-04 19:25:16,193 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-12-04 19:25:16,212 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-12-04 19:25:16,220 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-12-04 19:25:16,232 [salt.state       ][INFO    ][24225] The service salt-minion is already running
2017-12-04 19:25:16,233 [salt.state       ][INFO    ][24225] Completed state [salt-minion] at time 19:25:16.232569 duration_in_ms=40.055
2017-12-04 19:25:16,234 [salt.state       ][INFO    ][24225] Running state [/etc/salt/grains.d] at time 19:25:16.233856
2017-12-04 19:25:16,234 [salt.state       ][INFO    ][24225] Executing state file.directory for /etc/salt/grains.d
2017-12-04 19:25:16,235 [salt.state       ][INFO    ][24225] Directory /etc/salt/grains.d is in the correct state
2017-12-04 19:25:16,235 [salt.state       ][INFO    ][24225] Completed state [/etc/salt/grains.d] at time 19:25:16.234840 duration_in_ms=0.984
2017-12-04 19:25:16,235 [salt.state       ][INFO    ][24225] Running state [/etc/salt/grains] at time 19:25:16.235303
2017-12-04 19:25:16,236 [salt.state       ][INFO    ][24225] Executing state file.managed for /etc/salt/grains
2017-12-04 19:25:16,236 [salt.state       ][INFO    ][24225] File /etc/salt/grains exists with proper permissions. No changes made.
2017-12-04 19:25:16,236 [salt.state       ][INFO    ][24225] Completed state [/etc/salt/grains] at time 19:25:16.236027 duration_in_ms=0.723
2017-12-04 19:25:16,236 [salt.state       ][INFO    ][24225] Running state [/etc/salt/grains.d/placeholder] at time 19:25:16.236320
2017-12-04 19:25:16,237 [salt.state       ][INFO    ][24225] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-12-04 19:25:16,237 [salt.state       ][INFO    ][24225] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-12-04 19:25:16,237 [salt.state       ][INFO    ][24225] Completed state [/etc/salt/grains.d/placeholder] at time 19:25:16.237023 duration_in_ms=0.703
2017-12-04 19:25:16,237 [salt.state       ][INFO    ][24225] Running state [/etc/salt/grains.d/sphinx] at time 19:25:16.237331
2017-12-04 19:25:16,238 [salt.state       ][INFO    ][24225] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-12-04 19:25:16,258 [salt.state       ][INFO    ][24225] File /etc/salt/grains.d/sphinx is in the correct state
2017-12-04 19:25:16,258 [salt.state       ][INFO    ][24225] Completed state [/etc/salt/grains.d/sphinx] at time 19:25:16.258421 duration_in_ms=21.09
2017-12-04 19:25:16,259 [salt.state       ][INFO    ][24225] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 19:25:16.259077
2017-12-04 19:25:16,259 [salt.state       ][INFO    ][24225] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-04 19:25:16,260 [salt.state       ][INFO    ][24225] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-04 19:25:16,260 [salt.state       ][INFO    ][24225] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 19:25:16.259813 duration_in_ms=0.736
2017-12-04 19:25:16,260 [salt.state       ][INFO    ][24225] Running state [/etc/salt/grains.d/dns_records] at time 19:25:16.260239
2017-12-04 19:25:16,261 [salt.state       ][INFO    ][24225] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-12-04 19:25:16,333 [salt.state       ][INFO    ][24225] File /etc/salt/grains.d/dns_records is in the correct state
2017-12-04 19:25:16,333 [salt.state       ][INFO    ][24225] Completed state [/etc/salt/grains.d/dns_records] at time 19:25:16.333120 duration_in_ms=72.88
2017-12-04 19:25:16,334 [salt.state       ][INFO    ][24225] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 19:25:16.333854
2017-12-04 19:25:16,334 [salt.state       ][INFO    ][24225] 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-04 19:25:16,334 [salt.state       ][INFO    ][24225] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-12-04 19:25:16,335 [salt.state       ][INFO    ][24225] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 19:25:16.334528 duration_in_ms=0.674
2017-12-04 19:25:16,335 [salt.state       ][INFO    ][24225] Running state [/etc/salt/grains.d/salt] at time 19:25:16.334844
2017-12-04 19:25:16,335 [salt.state       ][INFO    ][24225] Executing state file.managed for /etc/salt/grains.d/salt
2017-12-04 19:25:16,375 [salt.state       ][INFO    ][24225] File /etc/salt/grains.d/salt is in the correct state
2017-12-04 19:25:16,375 [salt.state       ][INFO    ][24225] Completed state [/etc/salt/grains.d/salt] at time 19:25:16.375330 duration_in_ms=40.486
2017-12-04 19:25:16,376 [salt.state       ][INFO    ][24225] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 19:25:16.376004
2017-12-04 19:25:16,376 [salt.state       ][INFO    ][24225] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-04 19:25:16,376 [salt.state       ][INFO    ][24225] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-04 19:25:16,377 [salt.state       ][INFO    ][24225] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 19:25:16.376638 duration_in_ms=0.634
2017-12-04 19:25:16,378 [salt.state       ][INFO    ][24225] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 19:25:16.378130
2017-12-04 19:25:16,378 [salt.state       ][INFO    ][24225] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-04 19:25:16,379 [salt.state       ][INFO    ][24225] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-04 19:25:16,379 [salt.state       ][INFO    ][24225] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 19:25:16.378882 duration_in_ms=0.752
2017-12-04 19:25:16,379 [salt.state       ][INFO    ][24225] Running state [mine.update] at time 19:25:16.379356
2017-12-04 19:25:16,380 [salt.state       ][INFO    ][24225] Executing state module.wait for mine.update
2017-12-04 19:25:16,380 [salt.state       ][INFO    ][24225] No changes made for mine.update
2017-12-04 19:25:16,380 [salt.state       ][INFO    ][24225] Completed state [mine.update] at time 19:25:16.379967 duration_in_ms=0.61
2017-12-04 19:25:16,380 [salt.state       ][INFO    ][24225] Running state [ca-certificates] at time 19:25:16.380155
2017-12-04 19:25:16,380 [salt.state       ][INFO    ][24225] Executing state pkg.installed for ca-certificates
2017-12-04 19:25:16,384 [salt.state       ][INFO    ][24225] Package ca-certificates is already installed
2017-12-04 19:25:16,384 [salt.state       ][INFO    ][24225] Completed state [ca-certificates] at time 19:25:16.383783 duration_in_ms=3.628
2017-12-04 19:25:16,384 [salt.state       ][INFO    ][24225] Running state [update-ca-certificates] at time 19:25:16.384277
2017-12-04 19:25:16,385 [salt.state       ][INFO    ][24225] Executing state cmd.wait for update-ca-certificates
2017-12-04 19:25:16,385 [salt.state       ][INFO    ][24225] No changes made for update-ca-certificates
2017-12-04 19:25:16,385 [salt.state       ][INFO    ][24225] Completed state [update-ca-certificates] at time 19:25:16.384884 duration_in_ms=0.607
2017-12-04 19:25:16,385 [salt.state       ][INFO    ][24225] Running state [libvirt-dev] at time 19:25:16.385072
2017-12-04 19:25:16,385 [salt.state       ][INFO    ][24225] Executing state pkg.installed for libvirt-dev
2017-12-04 19:25:16,388 [salt.state       ][INFO    ][24225] Package libvirt-dev is already installed
2017-12-04 19:25:16,388 [salt.state       ][INFO    ][24225] Completed state [libvirt-dev] at time 19:25:16.388172 duration_in_ms=3.101
2017-12-04 19:25:16,388 [salt.state       ][INFO    ][24225] Running state [pkg-config] at time 19:25:16.388363
2017-12-04 19:25:16,389 [salt.state       ][INFO    ][24225] Executing state pkg.installed for pkg-config
2017-12-04 19:25:16,391 [salt.state       ][INFO    ][24225] Package pkg-config is already installed
2017-12-04 19:25:16,391 [salt.state       ][INFO    ][24225] Completed state [pkg-config] at time 19:25:16.391408 duration_in_ms=3.045
2017-12-04 19:25:16,392 [salt.state       ][INFO    ][24225] Running state [virtng.init] at time 19:25:16.391592
2017-12-04 19:25:16,392 [salt.state       ][INFO    ][24225] Executing state module.run for virtng.init
2017-12-04 19:25:16,434 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command ['git', '--version'] in directory '/root'
2017-12-04 19:25:16,529 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'virsh list --all --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:25:16,548 [salt.state       ][INFO    ][24225] unless execution succeeded
2017-12-04 19:25:16,563 [salt.state       ][INFO    ][24225] Completed state [virtng.init] at time 19:25:16.563289 duration_in_ms=171.693
2017-12-04 19:25:16,564 [salt.state       ][INFO    ][24225] Running state [virt.set_autostart] at time 19:25:16.563779
2017-12-04 19:25:16,564 [salt.state       ][INFO    ][24225] Executing state module.run for virt.set_autostart
2017-12-04 19:25:16,566 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'virsh list --autostart --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:25:16,582 [salt.state       ][INFO    ][24225] unless execution succeeded
2017-12-04 19:25:16,582 [salt.state       ][INFO    ][24225] Completed state [virt.set_autostart] at time 19:25:16.581843 duration_in_ms=18.064
2017-12-04 19:25:16,582 [salt.state       ][INFO    ][24225] Running state [virtng.init] at time 19:25:16.582075
2017-12-04 19:25:16,582 [salt.state       ][INFO    ][24225] Executing state module.run for virtng.init
2017-12-04 19:25:16,584 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'virsh list --all --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:25:16,601 [salt.state       ][INFO    ][24225] unless execution succeeded
2017-12-04 19:25:16,601 [salt.state       ][INFO    ][24225] Completed state [virtng.init] at time 19:25:16.601082 duration_in_ms=19.007
2017-12-04 19:25:16,601 [salt.state       ][INFO    ][24225] Running state [virt.set_autostart] at time 19:25:16.601307
2017-12-04 19:25:16,602 [salt.state       ][INFO    ][24225] Executing state module.run for virt.set_autostart
2017-12-04 19:25:16,603 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'virsh list --autostart --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:25:16,617 [salt.state       ][INFO    ][24225] unless execution succeeded
2017-12-04 19:25:16,617 [salt.state       ][INFO    ][24225] Completed state [virt.set_autostart] at time 19:25:16.616840 duration_in_ms=15.533
2017-12-04 19:25:16,617 [salt.state       ][INFO    ][24225] Running state [virtng.init] at time 19:25:16.617050
2017-12-04 19:25:16,617 [salt.state       ][INFO    ][24225] Executing state module.run for virtng.init
2017-12-04 19:25:16,619 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'virsh list --all --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:25:16,632 [salt.state       ][INFO    ][24225] unless execution succeeded
2017-12-04 19:25:16,635 [salt.state       ][INFO    ][24225] Completed state [virtng.init] at time 19:25:16.635388 duration_in_ms=18.337
2017-12-04 19:25:16,636 [salt.state       ][INFO    ][24225] Running state [virt.set_autostart] at time 19:25:16.635807
2017-12-04 19:25:16,636 [salt.state       ][INFO    ][24225] Executing state module.run for virt.set_autostart
2017-12-04 19:25:16,638 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'virsh list --autostart --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:25:16,651 [salt.state       ][INFO    ][24225] unless execution succeeded
2017-12-04 19:25:16,651 [salt.state       ][INFO    ][24225] Completed state [virt.set_autostart] at time 19:25:16.650779 duration_in_ms=14.972
2017-12-04 19:25:16,651 [salt.state       ][INFO    ][24225] Running state [virtng.init] at time 19:25:16.650992
2017-12-04 19:25:16,651 [salt.state       ][INFO    ][24225] Executing state module.run for virtng.init
2017-12-04 19:25:16,653 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'virsh list --all --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:25:16,665 [salt.state       ][INFO    ][24225] unless execution succeeded
2017-12-04 19:25:16,666 [salt.state       ][INFO    ][24225] Completed state [virtng.init] at time 19:25:16.665595 duration_in_ms=14.602
2017-12-04 19:25:16,666 [salt.state       ][INFO    ][24225] Running state [virt.set_autostart] at time 19:25:16.665821
2017-12-04 19:25:16,666 [salt.state       ][INFO    ][24225] Executing state module.run for virt.set_autostart
2017-12-04 19:25:16,668 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'virsh list --autostart --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:25:16,688 [salt.state       ][INFO    ][24225] unless execution succeeded
2017-12-04 19:25:16,688 [salt.state       ][INFO    ][24225] Completed state [virt.set_autostart] at time 19:25:16.688001 duration_in_ms=22.18
2017-12-04 19:25:16,688 [salt.state       ][INFO    ][24225] Running state [virtng.init] at time 19:25:16.688239
2017-12-04 19:25:16,688 [salt.state       ][INFO    ][24225] Executing state module.run for virtng.init
2017-12-04 19:25:16,690 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'virsh list --all --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:25:16,703 [salt.state       ][INFO    ][24225] unless execution succeeded
2017-12-04 19:25:16,703 [salt.state       ][INFO    ][24225] Completed state [virtng.init] at time 19:25:16.702867 duration_in_ms=14.628
2017-12-04 19:25:16,703 [salt.state       ][INFO    ][24225] Running state [virt.set_autostart] at time 19:25:16.703107
2017-12-04 19:25:16,703 [salt.state       ][INFO    ][24225] Executing state module.run for virt.set_autostart
2017-12-04 19:25:16,705 [salt.loaded.int.module.cmdmod][INFO    ][24225] Executing command 'virsh list --autostart --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-04 19:25:16,718 [salt.state       ][INFO    ][24225] unless execution succeeded
2017-12-04 19:25:16,718 [salt.state       ][INFO    ][24225] Completed state [virt.set_autostart] at time 19:25:16.717731 duration_in_ms=14.624
2017-12-04 19:25:16,720 [salt.minion      ][INFO    ][24225] Returning information for job: 20171204192510671489
2017-12-04 19:25:18,301 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command pillar.get with jid 20171204192518300814
2017-12-04 19:25:18,317 [salt.minion      ][INFO    ][24344] Starting a new job with PID 24344
2017-12-04 19:25:18,320 [salt.minion      ][INFO    ][24344] Returning information for job: 20171204192518300814
2017-12-04 19:26:42,444 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171204192642440974
2017-12-04 19:26:42,462 [salt.minion      ][INFO    ][24360] Starting a new job with PID 24360
2017-12-04 19:26:45,990 [salt.state       ][INFO    ][24360] Loading fresh modules for state activity
2017-12-04 19:26:46,011 [salt.state       ][ERROR   ][24360] No contents found in top file
2017-12-04 19:26:46,012 [salt.utils.extmods][INFO    ][24360] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-12-04 19:26:46,015 [salt.utils.extmods][INFO    ][24360] Syncing beacons for environment 'base'
2017-12-04 19:26:46,015 [salt.utils.extmods][INFO    ][24360] Loading cache from salt://_beacons, for base)
2017-12-04 19:26:46,015 [salt.fileclient  ][INFO    ][24360] Caching directory '_beacons/' for environment 'base'
2017-12-04 19:26:46,053 [salt.utils.extmods][INFO    ][24360] Syncing modules for environment 'base'
2017-12-04 19:26:46,053 [salt.utils.extmods][INFO    ][24360] Loading cache from salt://_modules, for base)
2017-12-04 19:26:46,053 [salt.fileclient  ][INFO    ][24360] Caching directory '_modules/' for environment 'base'
2017-12-04 19:26:46,118 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/avinetworks.py'
2017-12-04 19:26:46,131 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-12-04 19:26:46,141 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-12-04 19:26:46,168 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-12-04 19:26:46,262 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-12-04 19:26:46,369 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-12-04 19:26:46,442 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-12-04 19:26:46,464 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-12-04 19:26:46,506 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-12-04 19:26:46,521 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-12-04 19:26:46,543 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-12-04 19:26:46,565 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-12-04 19:26:46,590 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-12-04 19:26:46,623 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-12-04 19:26:46,658 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-12-04 19:26:46,681 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-12-04 19:26:46,725 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-12-04 19:26:46,757 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-12-04 19:26:46,791 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-12-04 19:26:46,816 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-12-04 19:26:46,837 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-12-04 19:26:46,863 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-12-04 19:26:46,888 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-12-04 19:26:46,946 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-12-04 19:26:47,029 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-12-04 19:26:47,073 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-12-04 19:26:47,124 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-12-04 19:26:47,207 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-12-04 19:26:47,271 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-12-04 19:26:47,290 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-12-04 19:26:47,330 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-12-04 19:26:47,375 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-12-04 19:26:47,392 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-12-04 19:26:47,455 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-12-04 19:26:47,458 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-12-04 19:26:47,458 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/avinetworks.py' to '/var/cache/salt/minion/extmods/modules/avinetworks.py'
2017-12-04 19:26:47,459 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-12-04 19:26:47,459 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-12-04 19:26:47,460 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-12-04 19:26:47,460 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-12-04 19:26:47,461 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-12-04 19:26:47,461 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-12-04 19:26:47,463 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-12-04 19:26:47,464 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-12-04 19:26:47,464 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-12-04 19:26:47,465 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-12-04 19:26:47,465 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-12-04 19:26:47,466 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-12-04 19:26:47,466 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-12-04 19:26:47,466 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-12-04 19:26:47,467 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-12-04 19:26:47,467 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-12-04 19:26:47,468 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-12-04 19:26:47,468 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-12-04 19:26:47,469 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-12-04 19:26:47,469 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-12-04 19:26:47,469 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-12-04 19:26:47,474 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-12-04 19:26:47,475 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-12-04 19:26:47,475 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-12-04 19:26:47,475 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-12-04 19:26:47,476 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-12-04 19:26:47,476 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-12-04 19:26:47,477 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-12-04 19:26:47,477 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-12-04 19:26:47,478 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-12-04 19:26:47,478 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-12-04 19:26:47,478 [salt.utils.extmods][INFO    ][24360] 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-04 19:26:47,479 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-12-04 19:26:47,479 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-12-04 19:26:47,484 [salt.utils.extmods][INFO    ][24360] Syncing states for environment 'base'
2017-12-04 19:26:47,484 [salt.utils.extmods][INFO    ][24360] Loading cache from salt://_states, for base)
2017-12-04 19:26:47,484 [salt.fileclient  ][INFO    ][24360] Caching directory '_states/' for environment 'base'
2017-12-04 19:26:47,538 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/avinetworks.py'
2017-12-04 19:26:47,597 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-12-04 19:26:47,737 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-12-04 19:26:47,810 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-12-04 19:26:47,928 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-12-04 19:26:48,023 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-12-04 19:26:48,038 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-12-04 19:26:48,072 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-12-04 19:26:48,085 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-12-04 19:26:48,098 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/helm_repos.py'
2017-12-04 19:26:48,110 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-12-04 19:26:48,122 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-12-04 19:26:48,134 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-12-04 19:26:48,806 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/jenkins_globalenvprop.py'
2017-12-04 19:26:48,819 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-12-04 19:26:49,156 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-12-04 19:26:49,188 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-12-04 19:26:49,199 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-12-04 19:26:49,750 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-12-04 19:26:49,757 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-12-04 19:26:49,984 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-12-04 19:26:49,998 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-12-04 19:26:50,005 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-12-04 19:26:50,013 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-12-04 19:26:50,041 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-12-04 19:26:50,049 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-12-04 19:26:50,340 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-12-04 19:26:50,349 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-12-04 19:26:50,358 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-12-04 19:26:50,366 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-12-04 19:26:50,374 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-12-04 19:26:50,382 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-12-04 19:26:50,384 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/avinetworks.py' to '/var/cache/salt/minion/extmods/states/avinetworks.py'
2017-12-04 19:26:50,385 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-12-04 19:26:50,385 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-12-04 19:26:50,385 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-12-04 19:26:50,386 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-12-04 19:26:50,386 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-12-04 19:26:50,386 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-12-04 19:26:50,387 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-12-04 19:26:50,387 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-12-04 19:26:50,387 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/helm_repos.py' to '/var/cache/salt/minion/extmods/states/helm_repos.py'
2017-12-04 19:26:50,388 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-12-04 19:26:50,388 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-12-04 19:26:50,388 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-12-04 19:26:50,388 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/jenkins_globalenvprop.py' to '/var/cache/salt/minion/extmods/states/jenkins_globalenvprop.py'
2017-12-04 19:26:50,389 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-12-04 19:26:50,389 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-12-04 19:26:50,389 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-12-04 19:26:50,390 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-12-04 19:26:50,390 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-12-04 19:26:50,390 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-12-04 19:26:50,391 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-12-04 19:26:50,391 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-12-04 19:26:50,391 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-12-04 19:26:50,391 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-12-04 19:26:50,392 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-12-04 19:26:50,392 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-12-04 19:26:50,392 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-12-04 19:26:50,393 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-12-04 19:26:50,393 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-12-04 19:26:50,393 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-12-04 19:26:50,394 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-12-04 19:26:50,394 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-12-04 19:26:50,394 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-12-04 19:26:50,397 [salt.utils.extmods][INFO    ][24360] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-12-04 19:26:50,399 [salt.utils.extmods][INFO    ][24360] Syncing sdb for environment 'base'
2017-12-04 19:26:50,400 [salt.utils.extmods][INFO    ][24360] Loading cache from salt://_sdb, for base)
2017-12-04 19:26:50,400 [salt.fileclient  ][INFO    ][24360] Caching directory '_sdb/' for environment 'base'
2017-12-04 19:26:50,421 [salt.utils.extmods][INFO    ][24360] Syncing grains for environment 'base'
2017-12-04 19:26:50,421 [salt.utils.extmods][INFO    ][24360] Loading cache from salt://_grains, for base)
2017-12-04 19:26:50,421 [salt.fileclient  ][INFO    ][24360] Caching directory '_grains/' for environment 'base'
2017-12-04 19:26:50,445 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-12-04 19:26:50,490 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-12-04 19:26:50,501 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-12-04 19:26:50,510 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-12-04 19:26:50,519 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-12-04 19:26:50,528 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-12-04 19:26:50,539 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-12-04 19:26:50,549 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-12-04 19:26:50,560 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-12-04 19:26:50,569 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-12-04 19:26:50,579 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-12-04 19:26:50,591 [salt.fileclient  ][INFO    ][24360] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-12-04 19:26:50,594 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-12-04 19:26:50,594 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-12-04 19:26:50,595 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-12-04 19:26:50,595 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-12-04 19:26:50,597 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-12-04 19:26:50,598 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-12-04 19:26:50,598 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-12-04 19:26:50,598 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-12-04 19:26:50,599 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-12-04 19:26:50,599 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-12-04 19:26:50,599 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-12-04 19:26:50,599 [salt.utils.extmods][INFO    ][24360] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-12-04 19:26:50,600 [salt.utils.extmods][INFO    ][24360] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-12-04 19:26:50,603 [salt.utils.extmods][INFO    ][24360] Syncing renderers for environment 'base'
2017-12-04 19:26:50,603 [salt.utils.extmods][INFO    ][24360] Loading cache from salt://_renderers, for base)
2017-12-04 19:26:50,603 [salt.fileclient  ][INFO    ][24360] Caching directory '_renderers/' for environment 'base'
2017-12-04 19:26:50,635 [salt.utils.extmods][INFO    ][24360] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-12-04 19:26:50,637 [salt.utils.extmods][INFO    ][24360] Syncing returners for environment 'base'
2017-12-04 19:26:50,637 [salt.utils.extmods][INFO    ][24360] Loading cache from salt://_returners, for base)
2017-12-04 19:26:50,638 [salt.fileclient  ][INFO    ][24360] Caching directory '_returners/' for environment 'base'
2017-12-04 19:26:50,673 [salt.utils.extmods][INFO    ][24360] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-12-04 19:26:50,675 [salt.utils.extmods][INFO    ][24360] Syncing output for environment 'base'
2017-12-04 19:26:50,675 [salt.utils.extmods][INFO    ][24360] Loading cache from salt://_output, for base)
2017-12-04 19:26:50,676 [salt.fileclient  ][INFO    ][24360] Caching directory '_output/' for environment 'base'
2017-12-04 19:26:50,710 [salt.utils.extmods][INFO    ][24360] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-12-04 19:26:50,713 [salt.utils.extmods][INFO    ][24360] Syncing utils for environment 'base'
2017-12-04 19:26:50,713 [salt.utils.extmods][INFO    ][24360] Loading cache from salt://_utils, for base)
2017-12-04 19:26:50,713 [salt.fileclient  ][INFO    ][24360] Caching directory '_utils/' for environment 'base'
2017-12-04 19:26:50,746 [salt.utils.extmods][INFO    ][24360] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-12-04 19:26:50,748 [salt.utils.extmods][INFO    ][24360] Syncing log_handlers for environment 'base'
2017-12-04 19:26:50,748 [salt.utils.extmods][INFO    ][24360] Loading cache from salt://_log_handlers, for base)
2017-12-04 19:26:50,748 [salt.fileclient  ][INFO    ][24360] Caching directory '_log_handlers/' for environment 'base'
2017-12-04 19:26:50,781 [salt.utils.extmods][INFO    ][24360] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-12-04 19:26:50,783 [salt.utils.extmods][INFO    ][24360] Syncing proxy for environment 'base'
2017-12-04 19:26:50,783 [salt.utils.extmods][INFO    ][24360] Loading cache from salt://_proxy, for base)
2017-12-04 19:26:50,783 [salt.fileclient  ][INFO    ][24360] Caching directory '_proxy/' for environment 'base'
2017-12-04 19:26:50,820 [salt.utils.extmods][INFO    ][24360] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-12-04 19:26:50,822 [salt.utils.extmods][INFO    ][24360] Syncing engines for environment 'base'
2017-12-04 19:26:50,822 [salt.utils.extmods][INFO    ][24360] Loading cache from salt://_engines, for base)
2017-12-04 19:26:50,822 [salt.fileclient  ][INFO    ][24360] Caching directory '_engines/' for environment 'base'
2017-12-04 19:26:50,852 [salt.minion      ][INFO    ][24360] Returning information for job: 20171204192642440974
2017-12-04 19:37:48,216 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command test.ping with jid 20171204193748214661
2017-12-04 19:37:48,232 [salt.minion      ][INFO    ][24570] Starting a new job with PID 24570
2017-12-04 19:37:48,262 [salt.minion      ][INFO    ][24570] Returning information for job: 20171204193748214661
2017-12-04 19:41:20,410 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command state.sls with jid 20171204194120404729
2017-12-04 19:41:20,425 [salt.minion      ][INFO    ][24630] Starting a new job with PID 24630
2017-12-04 19:41:20,951 [salt.state       ][INFO    ][24630] Loading fresh modules for state activity
2017-12-04 19:41:20,976 [salt.fileclient  ][INFO    ][24630] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-12-04 19:41:20,992 [salt.fileclient  ][INFO    ][24630] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-12-04 19:41:21,017 [salt.fileclient  ][INFO    ][24630] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-04 19:41:21,309 [salt.state       ][INFO    ][24630] Running state [lsof] at time 19:41:21.308661
2017-12-04 19:41:21,309 [salt.state       ][INFO    ][24630] Executing state pkg.installed for lsof
2017-12-04 19:41:21,309 [salt.loaded.int.module.cmdmod][INFO    ][24630] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-04 19:41:21,592 [salt.state       ][INFO    ][24630] Package lsof is already installed
2017-12-04 19:41:21,592 [salt.state       ][INFO    ][24630] Completed state [lsof] at time 19:41:21.591722 duration_in_ms=283.061
2017-12-04 19:41:21,592 [salt.state       ][INFO    ][24630] Running state [keepalived] at time 19:41:21.591922
2017-12-04 19:41:21,592 [salt.state       ][INFO    ][24630] Executing state pkg.installed for keepalived
2017-12-04 19:41:21,606 [salt.loaded.int.module.cmdmod][INFO    ][24630] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-12-04 19:41:23,092 [salt.loaded.int.module.cmdmod][INFO    ][24630] 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-04 19:41:30,453 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command saltutil.find_job with jid 20171204194130448161
2017-12-04 19:41:30,467 [salt.minion      ][INFO    ][25953] Starting a new job with PID 25953
2017-12-04 19:41:30,509 [salt.minion      ][INFO    ][25953] Returning information for job: 20171204194130448161
2017-12-04 19:41:30,817 [salt.loaded.int.module.cmdmod][INFO    ][24630] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-04 19:41:30,841 [salt.state       ][INFO    ][24630] 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-04 19:41:30,849 [salt.state       ][INFO    ][24630] Loading fresh modules for state activity
2017-12-04 19:41:30,862 [salt.state       ][INFO    ][24630] Completed state [keepalived] at time 19:41:30.861828 duration_in_ms=9269.906
2017-12-04 19:41:30,864 [salt.state       ][INFO    ][24630] Running state [/etc/keepalived/keepalived.conf] at time 19:41:30.863750
2017-12-04 19:41:30,864 [salt.state       ][INFO    ][24630] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-12-04 19:41:30,891 [salt.fileclient  ][INFO    ][24630] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-12-04 19:41:30,924 [salt.fileclient  ][INFO    ][24630] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-04 19:41:30,930 [salt.state       ][INFO    ][24630] File changed:
New file
2017-12-04 19:41:30,930 [salt.state       ][INFO    ][24630] Completed state [/etc/keepalived/keepalived.conf] at time 19:41:30.929776 duration_in_ms=66.026
2017-12-04 19:41:30,988 [salt.state       ][INFO    ][24630] Running state [keepalived] at time 19:41:30.988332
2017-12-04 19:41:30,989 [salt.state       ][INFO    ][24630] Executing state service.running for keepalived
2017-12-04 19:41:30,990 [salt.loaded.int.module.cmdmod][INFO    ][24630] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-12-04 19:41:30,998 [salt.loaded.int.module.cmdmod][INFO    ][24630] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-12-04 19:41:31,006 [salt.loaded.int.module.cmdmod][INFO    ][24630] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-04 19:41:31,013 [salt.loaded.int.module.cmdmod][INFO    ][24630] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-04 19:41:31,020 [salt.loaded.int.module.cmdmod][INFO    ][24630] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-12-04 19:41:31,075 [salt.loaded.int.module.cmdmod][INFO    ][24630] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-12-04 19:41:31,082 [salt.loaded.int.module.cmdmod][INFO    ][24630] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-04 19:41:31,088 [salt.loaded.int.module.cmdmod][INFO    ][24630] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-04 19:41:31,096 [salt.state       ][INFO    ][24630] {'keepalived': True}
2017-12-04 19:41:31,096 [salt.state       ][INFO    ][24630] Completed state [keepalived] at time 19:41:31.095900 duration_in_ms=107.568
2017-12-04 19:41:31,097 [salt.minion      ][INFO    ][24630] Returning information for job: 20171204194120404729
2017-12-04 19:41:47,239 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command pillar.get with jid 20171204194147234331
2017-12-04 19:41:47,272 [salt.minion      ][INFO    ][26050] Starting a new job with PID 26050
2017-12-04 19:41:47,278 [salt.minion      ][INFO    ][26050] Returning information for job: 20171204194147234331
2017-12-04 19:47:04,779 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command state.sls with jid 20171204194704774831
2017-12-04 19:47:04,795 [salt.minion      ][INFO    ][26133] Starting a new job with PID 26133
2017-12-04 19:47:06,499 [salt.state       ][INFO    ][26133] Loading fresh modules for state activity
2017-12-04 19:47:06,522 [salt.fileclient  ][INFO    ][26133] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-12-04 19:47:06,544 [salt.fileclient  ][INFO    ][26133] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-12-04 19:47:06,808 [salt.state       ][INFO    ][26133] Running state [attr] at time 19:47:06.807990
2017-12-04 19:47:06,808 [salt.state       ][INFO    ][26133] Executing state pkg.installed for attr
2017-12-04 19:47:06,809 [salt.loaded.int.module.cmdmod][INFO    ][26133] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-04 19:47:07,074 [salt.loaded.int.module.cmdmod][INFO    ][26133] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-12-04 19:47:08,548 [salt.loaded.int.module.cmdmod][INFO    ][26133] 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-04 19:47:11,069 [salt.loaded.int.module.cmdmod][INFO    ][26133] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-04 19:47:11,092 [salt.state       ][INFO    ][26133] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-12-04 19:47:11,100 [salt.state       ][INFO    ][26133] Loading fresh modules for state activity
2017-12-04 19:47:11,112 [salt.state       ][INFO    ][26133] Completed state [attr] at time 19:47:11.111617 duration_in_ms=4303.627
2017-12-04 19:47:11,116 [salt.state       ][INFO    ][26133] Running state [glusterfs-server] at time 19:47:11.115900
2017-12-04 19:47:11,116 [salt.state       ][INFO    ][26133] Executing state pkg.installed for glusterfs-server
2017-12-04 19:47:11,299 [salt.loaded.int.module.cmdmod][INFO    ][26133] 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-04 19:47:14,870 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command saltutil.find_job with jid 20171204194714864617
2017-12-04 19:47:14,885 [salt.minion      ][INFO    ][26973] Starting a new job with PID 26973
2017-12-04 19:47:14,896 [salt.minion      ][INFO    ][26973] Returning information for job: 20171204194714864617
2017-12-04 19:47:25,071 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command saltutil.find_job with jid 20171204194725064584
2017-12-04 19:47:25,085 [salt.minion      ][INFO    ][27052] Starting a new job with PID 27052
2017-12-04 19:47:25,104 [salt.minion      ][INFO    ][27052] Returning information for job: 20171204194725064584
2017-12-04 19:47:35,275 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command saltutil.find_job with jid 20171204194735267864
2017-12-04 19:47:35,287 [salt.minion      ][INFO    ][28469] Starting a new job with PID 28469
2017-12-04 19:47:35,297 [salt.minion      ][INFO    ][28469] Returning information for job: 20171204194735267864
2017-12-04 19:47:35,950 [salt.loaded.int.module.cmdmod][INFO    ][26133] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-04 19:47:35,974 [salt.state       ][INFO    ][26133] 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-04 19:47:35,980 [salt.state       ][INFO    ][26133] Loading fresh modules for state activity
2017-12-04 19:47:35,993 [salt.state       ][INFO    ][26133] Completed state [glusterfs-server] at time 19:47:35.992466 duration_in_ms=24876.566
2017-12-04 19:47:35,997 [salt.state       ][INFO    ][26133] Running state [bc] at time 19:47:35.996653
2017-12-04 19:47:35,997 [salt.state       ][INFO    ][26133] Executing state pkg.installed for bc
2017-12-04 19:47:36,234 [salt.loaded.int.module.cmdmod][INFO    ][26133] 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-04 19:47:39,071 [salt.loaded.int.module.cmdmod][INFO    ][26133] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-04 19:47:39,099 [salt.state       ][INFO    ][26133] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-12-04 19:47:39,113 [salt.state       ][INFO    ][26133] Loading fresh modules for state activity
2017-12-04 19:47:39,128 [salt.state       ][INFO    ][26133] Completed state [bc] at time 19:47:39.127802 duration_in_ms=3131.148
2017-12-04 19:47:39,192 [salt.state       ][INFO    ][26133] Running state [glusterfs-server] at time 19:47:39.191832
2017-12-04 19:47:39,192 [salt.state       ][INFO    ][26133] Executing state service.running for glusterfs-server
2017-12-04 19:47:39,194 [salt.loaded.int.module.cmdmod][INFO    ][26133] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-12-04 19:47:39,203 [salt.loaded.int.module.cmdmod][INFO    ][26133] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-12-04 19:47:39,209 [salt.loaded.int.module.cmdmod][INFO    ][26133] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-12-04 19:47:39,217 [salt.state       ][INFO    ][26133] The service glusterfs-server is already running
2017-12-04 19:47:39,218 [salt.state       ][INFO    ][26133] Completed state [glusterfs-server] at time 19:47:39.217519 duration_in_ms=25.686
2017-12-04 19:47:39,219 [salt.state       ][INFO    ][26133] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 19:47:39.218935
2017-12-04 19:47:39,219 [salt.state       ][INFO    ][26133] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-12-04 19:47:39,235 [salt.state       ][INFO    ][26133] File changed:
New file
2017-12-04 19:47:39,235 [salt.state       ][INFO    ][26133] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 19:47:39.234751 duration_in_ms=15.816
2017-12-04 19:47:39,235 [salt.state       ][INFO    ][26133] Running state [/srv/glusterfs/glance] at time 19:47:39.234933
2017-12-04 19:47:39,235 [salt.state       ][INFO    ][26133] Executing state file.directory for /srv/glusterfs/glance
2017-12-04 19:47:39,251 [salt.state       ][INFO    ][26133] {'/srv/glusterfs/glance': 'New Dir'}
2017-12-04 19:47:39,251 [salt.state       ][INFO    ][26133] Completed state [/srv/glusterfs/glance] at time 19:47:39.250947 duration_in_ms=16.014
2017-12-04 19:47:39,251 [salt.state       ][INFO    ][26133] Running state [/srv/glusterfs/keystone-keys] at time 19:47:39.251109
2017-12-04 19:47:39,251 [salt.state       ][INFO    ][26133] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-12-04 19:47:39,252 [salt.state       ][INFO    ][26133] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-12-04 19:47:39,252 [salt.state       ][INFO    ][26133] Completed state [/srv/glusterfs/keystone-keys] at time 19:47:39.251781 duration_in_ms=0.671
2017-12-04 19:47:39,252 [salt.state       ][INFO    ][26133] Running state [/srv/glusterfs/nova_instances] at time 19:47:39.251941
2017-12-04 19:47:39,252 [salt.state       ][INFO    ][26133] Executing state file.directory for /srv/glusterfs/nova_instances
2017-12-04 19:47:39,252 [salt.state       ][INFO    ][26133] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-12-04 19:47:39,253 [salt.state       ][INFO    ][26133] Completed state [/srv/glusterfs/nova_instances] at time 19:47:39.252577 duration_in_ms=0.637
2017-12-04 19:47:39,253 [salt.state       ][INFO    ][26133] Running state [/srv/glusterfs/keystone-credential-keys] at time 19:47:39.252718
2017-12-04 19:47:39,253 [salt.state       ][INFO    ][26133] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-12-04 19:47:39,253 [salt.state       ][INFO    ][26133] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-12-04 19:47:39,253 [salt.state       ][INFO    ][26133] Completed state [/srv/glusterfs/keystone-credential-keys] at time 19:47:39.253363 duration_in_ms=0.646
2017-12-04 19:47:39,254 [salt.minion      ][INFO    ][26133] Returning information for job: 20171204194704774831
2017-12-04 19:47:47,335 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command test.ping with jid 20171204194747330652
2017-12-04 19:47:47,349 [salt.minion      ][INFO    ][28944] Starting a new job with PID 28944
2017-12-04 19:47:47,375 [salt.minion      ][INFO    ][28944] Returning information for job: 20171204194747330652
2017-12-04 19:48:30,115 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command state.sls with jid 20171204194830109328
2017-12-04 19:48:30,131 [salt.minion      ][INFO    ][29581] Starting a new job with PID 29581
2017-12-04 19:48:30,562 [salt.state       ][INFO    ][29581] Loading fresh modules for state activity
2017-12-04 19:48:30,586 [salt.fileclient  ][INFO    ][29581] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-12-04 19:48:30,621 [salt.fileclient  ][INFO    ][29581] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-12-04 19:48:30,692 [salt.fileclient  ][INFO    ][29581] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-12-04 19:48:30,711 [salt.fileclient  ][INFO    ][29581] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-12-04 19:48:30,993 [salt.state       ][INFO    ][29581] Running state [attr] at time 19:48:30.992561
2017-12-04 19:48:30,993 [salt.state       ][INFO    ][29581] Executing state pkg.installed for attr
2017-12-04 19:48:30,993 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-04 19:48:31,262 [salt.state       ][INFO    ][29581] Package attr is already installed
2017-12-04 19:48:31,262 [salt.state       ][INFO    ][29581] Completed state [attr] at time 19:48:31.262368 duration_in_ms=269.808
2017-12-04 19:48:31,263 [salt.state       ][INFO    ][29581] Running state [glusterfs-server] at time 19:48:31.262577
2017-12-04 19:48:31,263 [salt.state       ][INFO    ][29581] Executing state pkg.installed for glusterfs-server
2017-12-04 19:48:31,265 [salt.state       ][INFO    ][29581] Package glusterfs-server is already installed
2017-12-04 19:48:31,266 [salt.state       ][INFO    ][29581] Completed state [glusterfs-server] at time 19:48:31.265487 duration_in_ms=2.91
2017-12-04 19:48:31,266 [salt.state       ][INFO    ][29581] Running state [bc] at time 19:48:31.265642
2017-12-04 19:48:31,266 [salt.state       ][INFO    ][29581] Executing state pkg.installed for bc
2017-12-04 19:48:31,268 [salt.state       ][INFO    ][29581] Package bc is already installed
2017-12-04 19:48:31,268 [salt.state       ][INFO    ][29581] Completed state [bc] at time 19:48:31.268456 duration_in_ms=2.814
2017-12-04 19:48:31,269 [salt.state       ][INFO    ][29581] Running state [glusterfs-server] at time 19:48:31.269260
2017-12-04 19:48:31,269 [salt.state       ][INFO    ][29581] Executing state service.running for glusterfs-server
2017-12-04 19:48:31,270 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-12-04 19:48:31,280 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-12-04 19:48:31,321 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-12-04 19:48:31,335 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-12-04 19:48:31,341 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-12-04 19:48:31,349 [salt.state       ][INFO    ][29581] The service glusterfs-server is already running
2017-12-04 19:48:31,350 [salt.state       ][INFO    ][29581] Completed state [glusterfs-server] at time 19:48:31.349468 duration_in_ms=80.207
2017-12-04 19:48:31,351 [salt.state       ][INFO    ][29581] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 19:48:31.350924
2017-12-04 19:48:31,351 [salt.state       ][INFO    ][29581] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-12-04 19:48:31,365 [salt.state       ][INFO    ][29581] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-12-04 19:48:31,365 [salt.state       ][INFO    ][29581] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 19:48:31.364654 duration_in_ms=13.73
2017-12-04 19:48:31,365 [salt.state       ][INFO    ][29581] Running state [/srv/glusterfs/glance] at time 19:48:31.364871
2017-12-04 19:48:31,365 [salt.state       ][INFO    ][29581] Executing state file.directory for /srv/glusterfs/glance
2017-12-04 19:48:31,365 [salt.state       ][INFO    ][29581] Directory /srv/glusterfs/glance is in the correct state
2017-12-04 19:48:31,366 [salt.state       ][INFO    ][29581] Completed state [/srv/glusterfs/glance] at time 19:48:31.365562 duration_in_ms=0.691
2017-12-04 19:48:31,366 [salt.state       ][INFO    ][29581] Running state [/srv/glusterfs/keystone-keys] at time 19:48:31.365741
2017-12-04 19:48:31,366 [salt.state       ][INFO    ][29581] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-12-04 19:48:31,366 [salt.state       ][INFO    ][29581] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-12-04 19:48:31,366 [salt.state       ][INFO    ][29581] Completed state [/srv/glusterfs/keystone-keys] at time 19:48:31.366418 duration_in_ms=0.677
2017-12-04 19:48:31,367 [salt.state       ][INFO    ][29581] Running state [/srv/glusterfs/nova_instances] at time 19:48:31.366590
2017-12-04 19:48:31,367 [salt.state       ][INFO    ][29581] Executing state file.directory for /srv/glusterfs/nova_instances
2017-12-04 19:48:31,367 [salt.state       ][INFO    ][29581] Directory /srv/glusterfs/nova_instances is in the correct state
2017-12-04 19:48:31,367 [salt.state       ][INFO    ][29581] Completed state [/srv/glusterfs/nova_instances] at time 19:48:31.367276 duration_in_ms=0.685
2017-12-04 19:48:31,367 [salt.state       ][INFO    ][29581] Running state [/srv/glusterfs/keystone-credential-keys] at time 19:48:31.367451
2017-12-04 19:48:31,368 [salt.state       ][INFO    ][29581] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-12-04 19:48:31,368 [salt.state       ][INFO    ][29581] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-12-04 19:48:31,368 [salt.state       ][INFO    ][29581] Completed state [/srv/glusterfs/keystone-credential-keys] at time 19:48:31.368167 duration_in_ms=0.716
2017-12-04 19:48:31,460 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command ['git', '--version'] in directory '/root'
2017-12-04 19:48:31,504 [salt.state       ][INFO    ][29581] Running state [sleep 5] at time 19:48:31.504029
2017-12-04 19:48:31,504 [salt.state       ][INFO    ][29581] Executing state cmd.wait for sleep 5
2017-12-04 19:48:31,505 [salt.state       ][INFO    ][29581] No changes made for sleep 5
2017-12-04 19:48:31,505 [salt.state       ][INFO    ][29581] Completed state [sleep 5] at time 19:48:31.504616 duration_in_ms=0.587
2017-12-04 19:48:31,505 [salt.state       ][INFO    ][29581] Running state [10.167.4.141] at time 19:48:31.505035
2017-12-04 19:48:31,505 [salt.state       ][INFO    ][29581] Executing state glusterfs.peered for 10.167.4.141
2017-12-04 19:48:31,506 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:31,514 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:31,568 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:31,577 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:31,632 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:31,639 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:31,694 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:31,702 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:31,753 [salt.state       ][INFO    ][29581] Peering with localhost is not needed
2017-12-04 19:48:31,753 [salt.state       ][INFO    ][29581] Completed state [10.167.4.141] at time 19:48:31.753389 duration_in_ms=248.353
2017-12-04 19:48:31,756 [salt.state       ][INFO    ][29581] Running state [10.167.4.142] at time 19:48:31.755671
2017-12-04 19:48:31,756 [salt.state       ][INFO    ][29581] Executing state glusterfs.peered for 10.167.4.142
2017-12-04 19:48:31,756 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:31,764 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:31,816 [salt.state       ][INFO    ][29581] Host 10.167.4.142 already peered
2017-12-04 19:48:31,816 [salt.state       ][INFO    ][29581] Completed state [10.167.4.142] at time 19:48:31.816011 duration_in_ms=60.34
2017-12-04 19:48:31,818 [salt.state       ][INFO    ][29581] Running state [10.167.4.143] at time 19:48:31.818152
2017-12-04 19:48:31,818 [salt.state       ][INFO    ][29581] Executing state glusterfs.peered for 10.167.4.143
2017-12-04 19:48:31,819 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:31,827 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:31,878 [salt.state       ][INFO    ][29581] Host 10.167.4.143 already peered
2017-12-04 19:48:31,878 [salt.state       ][INFO    ][29581] Completed state [10.167.4.143] at time 19:48:31.878398 duration_in_ms=60.246
2017-12-04 19:48:31,879 [salt.state       ][INFO    ][29581] Running state [glance] at time 19:48:31.879362
2017-12-04 19:48:31,880 [salt.state       ][INFO    ][29581] Executing state glusterfs.created for glance
2017-12-04 19:48:31,880 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:31,888 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:31,938 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:31,945 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:31,1000 [salt.state       ][INFO    ][29581] Volume glance already exists and is started.
2017-12-04 19:48:31,1000 [salt.state       ][INFO    ][29581] Completed state [glance] at time 19:48:31.999864 duration_in_ms=120.502
2017-12-04 19:48:32,003 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 19:48:32.002947
2017-12-04 19:48:32,003 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-12-04 19:48:32,004 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-04 19:48:32,055 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:32,056 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 19:48:32.055510 duration_in_ms=52.563
2017-12-04 19:48:32,056 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 19:48:32.055994
2017-12-04 19:48:32,056 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-12-04 19:48:32,057 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-04 19:48:32,106 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:32,107 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 19:48:32.106628 duration_in_ms=50.633
2017-12-04 19:48:32,107 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 19:48:32.107085
2017-12-04 19:48:32,107 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-12-04 19:48:32,108 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-12-04 19:48:32,163 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:32,163 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 19:48:32.162790 duration_in_ms=55.705
2017-12-04 19:48:32,163 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 19:48:32.163275
2017-12-04 19:48:32,164 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'
2017-12-04 19:48:32,164 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'glance' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-04 19:48:32,216 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:32,216 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 19:48:32.216094 duration_in_ms=52.819
2017-12-04 19:48:32,217 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 19:48:32.216511
2017-12-04 19:48:32,217 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-12-04 19:48:32,217 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-04 19:48:32,268 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:32,268 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 19:48:32.268093 duration_in_ms=51.581
2017-12-04 19:48:32,269 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 19:48:32.268576
2017-12-04 19:48:32,269 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-12-04 19:48:32,269 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-04 19:48:32,323 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:32,337 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 19:48:32.336678 duration_in_ms=68.102
2017-12-04 19:48:32,338 [salt.state       ][INFO    ][29581] Running state [glance] at time 19:48:32.337907
2017-12-04 19:48:32,338 [salt.state       ][INFO    ][29581] Executing state glusterfs.started for glance
2017-12-04 19:48:32,339 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:32,347 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:32,397 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:32,405 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:32,461 [salt.state       ][INFO    ][29581] Volume glance is already started
2017-12-04 19:48:32,461 [salt.state       ][INFO    ][29581] Completed state [glance] at time 19:48:32.460883 duration_in_ms=122.974
2017-12-04 19:48:32,462 [salt.state       ][INFO    ][29581] Running state [keystone-keys] at time 19:48:32.461703
2017-12-04 19:48:32,462 [salt.state       ][INFO    ][29581] Executing state glusterfs.created for keystone-keys
2017-12-04 19:48:32,462 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:32,471 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:32,523 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:32,531 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:32,584 [salt.state       ][INFO    ][29581] Volume keystone-keys already exists and is started.
2017-12-04 19:48:32,584 [salt.state       ][INFO    ][29581] Completed state [keystone-keys] at time 19:48:32.584388 duration_in_ms=122.684
2017-12-04 19:48:32,588 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 19:48:32.587779
2017-12-04 19:48:32,588 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-12-04 19:48:32,588 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-04 19:48:32,639 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:32,639 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 19:48:32.638803 duration_in_ms=51.024
2017-12-04 19:48:32,639 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 19:48:32.639258
2017-12-04 19:48:32,639 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-12-04 19:48:32,640 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-04 19:48:32,694 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:32,694 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 19:48:32.693771 duration_in_ms=54.513
2017-12-04 19:48:32,694 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 19:48:32.694236
2017-12-04 19:48:32,694 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'
2017-12-04 19:48:32,695 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-04 19:48:32,747 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:32,747 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 19:48:32.746891 duration_in_ms=52.654
2017-12-04 19:48:32,747 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 19:48:32.747342
2017-12-04 19:48:32,748 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-12-04 19:48:32,748 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-04 19:48:32,799 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:32,799 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 19:48:32.799253 duration_in_ms=51.911
2017-12-04 19:48:32,800 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 19:48:32.799726
2017-12-04 19:48:32,800 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-12-04 19:48:32,800 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-12-04 19:48:32,855 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:32,855 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 19:48:32.855189 duration_in_ms=55.461
2017-12-04 19:48:32,856 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 19:48:32.855634
2017-12-04 19:48:32,856 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-12-04 19:48:32,856 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-04 19:48:32,907 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:32,907 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 19:48:32.907408 duration_in_ms=51.774
2017-12-04 19:48:32,909 [salt.state       ][INFO    ][29581] Running state [keystone-keys] at time 19:48:32.908490
2017-12-04 19:48:32,909 [salt.state       ][INFO    ][29581] Executing state glusterfs.started for keystone-keys
2017-12-04 19:48:32,909 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:32,916 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:32,968 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:32,977 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:33,032 [salt.state       ][INFO    ][29581] Volume keystone-keys is already started
2017-12-04 19:48:33,032 [salt.state       ][INFO    ][29581] Completed state [keystone-keys] at time 19:48:33.032432 duration_in_ms=123.942
2017-12-04 19:48:33,033 [salt.state       ][INFO    ][29581] Running state [nova_instances] at time 19:48:33.033235
2017-12-04 19:48:33,033 [salt.state       ][INFO    ][29581] Executing state glusterfs.created for nova_instances
2017-12-04 19:48:33,034 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:33,042 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:33,093 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:33,101 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:33,157 [salt.state       ][INFO    ][29581] Volume nova_instances already exists and is started.
2017-12-04 19:48:33,157 [salt.state       ][INFO    ][29581] Completed state [nova_instances] at time 19:48:33.157443 duration_in_ms=124.207
2017-12-04 19:48:33,161 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 19:48:33.160673
2017-12-04 19:48:33,161 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-12-04 19:48:33,161 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-04 19:48:33,213 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:33,213 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 19:48:33.212840 duration_in_ms=52.167
2017-12-04 19:48:33,213 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 19:48:33.213357
2017-12-04 19:48:33,214 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'
2017-12-04 19:48:33,214 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-04 19:48:33,266 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:33,266 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 19:48:33.266030 duration_in_ms=52.673
2017-12-04 19:48:33,267 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 19:48:33.266540
2017-12-04 19:48:33,267 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'True'
2017-12-04 19:48:33,267 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-04 19:48:33,321 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:33,321 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 19:48:33.320839 duration_in_ms=54.3
2017-12-04 19:48:33,321 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 19:48:33.321269
2017-12-04 19:48:33,322 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'
2017-12-04 19:48:33,322 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-04 19:48:33,373 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:33,374 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 19:48:33.373666 duration_in_ms=52.397
2017-12-04 19:48:33,374 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 19:48:33.374091
2017-12-04 19:48:33,374 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'True'
2017-12-04 19:48:33,375 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: True'' in directory '/root'
2017-12-04 19:48:33,429 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:33,430 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 19:48:33.429688 duration_in_ms=55.597
2017-12-04 19:48:33,431 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 19:48:33.430878
2017-12-04 19:48:33,431 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-12-04 19:48:33,432 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-04 19:48:33,485 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:33,485 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 19:48:33.485448 duration_in_ms=54.57
2017-12-04 19:48:33,487 [salt.state       ][INFO    ][29581] Running state [nova_instances] at time 19:48:33.486532
2017-12-04 19:48:33,487 [salt.state       ][INFO    ][29581] Executing state glusterfs.started for nova_instances
2017-12-04 19:48:33,487 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:33,496 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:33,546 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:33,555 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:33,627 [salt.state       ][INFO    ][29581] Volume nova_instances is already started
2017-12-04 19:48:33,627 [salt.state       ][INFO    ][29581] Completed state [nova_instances] at time 19:48:33.626948 duration_in_ms=140.416
2017-12-04 19:48:33,628 [salt.state       ][INFO    ][29581] Running state [keystone-credential-keys] at time 19:48:33.627997
2017-12-04 19:48:33,628 [salt.state       ][INFO    ][29581] Executing state glusterfs.created for keystone-credential-keys
2017-12-04 19:48:33,629 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:33,638 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:33,691 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:33,700 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:33,756 [salt.state       ][INFO    ][29581] Volume keystone-credential-keys already exists and is started.
2017-12-04 19:48:33,756 [salt.state       ][INFO    ][29581] Completed state [keystone-credential-keys] at time 19:48:33.756242 duration_in_ms=128.245
2017-12-04 19:48:33,760 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 19:48:33.759986
2017-12-04 19:48:33,760 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-12-04 19:48:33,761 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-04 19:48:33,812 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:33,812 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 19:48:33.812209 duration_in_ms=52.222
2017-12-04 19:48:33,813 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 19:48:33.812710
2017-12-04 19:48:33,813 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'
2017-12-04 19:48:33,813 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-04 19:48:33,865 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:33,865 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 19:48:33.864903 duration_in_ms=52.193
2017-12-04 19:48:33,865 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 19:48:33.865437
2017-12-04 19:48:33,866 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-12-04 19:48:33,866 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-04 19:48:33,919 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:33,920 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 19:48:33.919518 duration_in_ms=54.08
2017-12-04 19:48:33,920 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 19:48:33.920071
2017-12-04 19:48:33,920 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-12-04 19:48:33,921 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-12-04 19:48:33,973 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:33,973 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 19:48:33.972969 duration_in_ms=52.898
2017-12-04 19:48:33,973 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 19:48:33.973449
2017-12-04 19:48:33,974 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-12-04 19:48:33,974 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-04 19:48:34,025 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:34,025 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 19:48:34.024914 duration_in_ms=51.464
2017-12-04 19:48:34,025 [salt.state       ][INFO    ][29581] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 19:48:34.025366
2017-12-04 19:48:34,026 [salt.state       ][INFO    ][29581] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-12-04 19:48:34,026 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-04 19:48:34,077 [salt.state       ][INFO    ][29581] unless execution succeeded
2017-12-04 19:48:34,077 [salt.state       ][INFO    ][29581] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 19:48:34.076929 duration_in_ms=51.562
2017-12-04 19:48:34,078 [salt.state       ][INFO    ][29581] Running state [keystone-credential-keys] at time 19:48:34.078022
2017-12-04 19:48:34,078 [salt.state       ][INFO    ][29581] Executing state glusterfs.started for keystone-credential-keys
2017-12-04 19:48:34,079 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:34,087 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:34,139 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --version' in directory '/root'
2017-12-04 19:48:34,147 [salt.loaded.int.module.cmdmod][INFO    ][29581] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-04 19:48:34,203 [salt.state       ][INFO    ][29581] Volume keystone-credential-keys is already started
2017-12-04 19:48:34,203 [salt.state       ][INFO    ][29581] Completed state [keystone-credential-keys] at time 19:48:34.203445 duration_in_ms=125.423
2017-12-04 19:48:34,205 [salt.minion      ][INFO    ][29581] Returning information for job: 20171204194830109328
2017-12-04 19:48:35,380 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command test.ping with jid 20171204194835373653
2017-12-04 19:48:35,396 [salt.minion      ][INFO    ][30109] Starting a new job with PID 30109
2017-12-04 19:48:35,518 [salt.minion      ][INFO    ][30109] Returning information for job: 20171204194835373653
2017-12-04 19:48:36,173 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command cmd.run with jid 20171204194836168716
2017-12-04 19:48:36,189 [salt.minion      ][INFO    ][30114] Starting a new job with PID 30114
2017-12-04 19:48:36,193 [salt.loaded.int.module.cmdmod][INFO    ][30114] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-12-04 19:48:36,309 [salt.minion      ][INFO    ][30114] Returning information for job: 20171204194836168716
2017-12-04 20:23:19,707 [salt.utils.schedule][INFO    ][16936] Running scheduled job: __mine_interval
2017-12-04 21:21:13,507 [salt.minion      ][INFO    ][16936] User sudo_ubuntu Executing command cp.push_dir with jid 20171204212113502680
2017-12-04 21:21:13,529 [salt.minion      ][INFO    ][31257] Starting a new job with PID 31257
