2017-09-14 21:15:21,702 [salt.cli.daemons ][WARNING ][838] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-14 21:16:29,860 [salt.state       ][ERROR   ][1022] No contents found in top file
2017-09-14 21:16:39,332 [py.warnings      ][WARNING ][1129] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2017-09-14 21:16:39,340 [salt.loaded.int.module.cmdmod][ERROR   ][1129] Command 'rabbitmqctl cluster_status' failed with return code: 127
2017-09-14 21:16:39,341 [salt.loaded.int.module.cmdmod][ERROR   ][1129] output: /bin/sh: 1: rabbitmqctl: not found
2017-09-14 21:16:40,509 [salt.loaded.int.module.cmdmod][ERROR   ][1129] Command 'rabbitmqctl cluster_status' failed with return code: 127
2017-09-14 21:16:40,509 [salt.loaded.int.module.cmdmod][ERROR   ][1129] output: /bin/sh: 1: rabbitmqctl: not found
2017-09-14 21:16:52,981 [salt.loaded.int.module.cmdmod][ERROR   ][1129] Command 'while true; do salt-call saltutil.running|grep fun: && continue; salt-call --local service.restart salt-minion; break; done' failed with return code: None
2017-09-14 21:18:32,409 [salt.loaded.int.module.cmdmod][INFO    ][12655] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-09-14 21:18:32,423 [salt.loaded.int.module.cmdmod][INFO    ][12655] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-09-14 21:18:32,441 [salt.loaded.int.module.cmdmod][INFO    ][12655] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-09-14 21:18:32,468 [salt.utils.parsers][WARNING ][838] Minion received a SIGTERM. Exiting.
2017-09-14 21:18:32,892 [salt.cli.daemons ][INFO    ][12711] Setting up the Salt Minion "msg01.baremetal-mcp-ocata-odl-ha.local"
2017-09-14 21:18:33,413 [salt.minion      ][INFO    ][12711] Creating minion process manager
2017-09-14 21:18:33,414 [salt.cli.daemons ][WARNING ][12711] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-14 21:18:33,414 [salt.cli.daemons ][INFO    ][12711] The Salt Minion is starting up
2017-09-14 21:18:33,414 [salt.minion      ][INFO    ][12711] Minion is starting as user 'root'
2017-09-14 21:18:33,415 [salt.utils.event ][INFO    ][12711] Starting pull socket on /var/run/salt/minion/minion_event_838a539487_pull.ipc
2017-09-14 21:18:34,216 [salt.loaded.int.module.cmdmod][INFO    ][12711] Executing command ['date', '+%z'] in directory '/root'
2017-09-14 21:18:34,230 [salt.utils.schedule][INFO    ][12711] Updating job settings for scheduled job: __mine_interval
2017-09-14 21:18:34,233 [salt.minion      ][INFO    ][12711] Added mine.update to scheduler
2017-09-14 21:18:34,324 [salt.minion      ][INFO    ][12711] Minion is ready to receive requests!
2017-09-14 21:18:35,325 [salt.utils.schedule][INFO    ][12711] Running scheduled job: __mine_interval
2017-09-14 21:20:36,932 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command state.apply with jid 20170914212036914919
2017-09-14 21:20:36,954 [salt.minion      ][INFO    ][12979] Starting a new job with PID 12979
2017-09-14 21:20:40,548 [salt.state       ][INFO    ][12979] Loading fresh modules for state activity
2017-09-14 21:20:40,840 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/init.sls'
2017-09-14 21:20:41,095 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/system/init.sls'
2017-09-14 21:20:41,154 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:41,208 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/system/env.sls'
2017-09-14 21:20:41,229 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:41,277 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/system/profile.sls'
2017-09-14 21:20:41,300 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:41,393 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/system/repo.sls'
2017-09-14 21:20:41,452 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:41,548 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/system/package.sls'
2017-09-14 21:20:41,582 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:41,633 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/system/timezone.sls'
2017-09-14 21:20:41,647 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:41,703 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/system/kernel.sls'
2017-09-14 21:20:41,754 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:41,828 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/system/cpu.sls'
2017-09-14 21:20:41,849 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:41,911 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/system/sysfs.sls'
2017-09-14 21:20:41,934 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:41,985 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/system/locale.sls'
2017-09-14 21:20:42,004 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:42,076 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/system/user.sls'
2017-09-14 21:20:42,122 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:42,215 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/system/group.sls'
2017-09-14 21:20:42,255 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:42,314 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/system/limit.sls'
2017-09-14 21:20:42,339 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:42,393 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/system/systemd.sls'
2017-09-14 21:20:42,411 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:42,473 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/network/init.sls'
2017-09-14 21:20:42,496 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:42,541 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/network/hostname.sls'
2017-09-14 21:20:42,574 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:42,635 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/network/host.sls'
2017-09-14 21:20:42,675 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:42,751 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/network/interface.sls'
2017-09-14 21:20:42,825 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:42,872 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/network/proxy.sls'
2017-09-14 21:20:42,914 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:42,981 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/storage/init.sls'
2017-09-14 21:20:43,001 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:43,049 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'ntp/init.sls'
2017-09-14 21:20:43,064 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'ntp/client.sls'
2017-09-14 21:20:43,083 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-09-14 21:20:43,104 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'ntp/server.sls'
2017-09-14 21:20:43,121 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-09-14 21:20:43,136 [salt.state       ][INFO    ][12979] Running state [/etc/environment] at time 21:20:43.135509
2017-09-14 21:20:43,136 [salt.state       ][INFO    ][12979] Executing state file.blockreplace for /etc/environment
2017-09-14 21:20:43,141 [salt.state       ][INFO    ][12979] File changed:
--- 
+++ 
@@ -1,3 +1,4 @@
 PATH="/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games"
 # SALT MANAGED VARIABLES - DO NOT EDIT - START
+# 
 # # SALT MANAGED VARIABLES - END

2017-09-14 21:20:43,142 [salt.state       ][INFO    ][12979] Completed state [/etc/environment] at time 21:20:43.142314 duration_in_ms=6.805
2017-09-14 21:20:43,143 [salt.state       ][INFO    ][12979] Running state [/etc/profile.d] at time 21:20:43.142638
2017-09-14 21:20:43,143 [salt.state       ][INFO    ][12979] Executing state file.directory for /etc/profile.d
2017-09-14 21:20:43,144 [salt.state       ][INFO    ][12979] Directory /etc/profile.d is in the correct state
2017-09-14 21:20:43,144 [salt.state       ][INFO    ][12979] Completed state [/etc/profile.d] at time 21:20:43.144053 duration_in_ms=1.415
2017-09-14 21:20:43,450 [salt.state       ][INFO    ][12979] Running state [linux_repo_prereq_pkgs] at time 21:20:43.449587
2017-09-14 21:20:43,450 [salt.state       ][INFO    ][12979] Executing state pkg.installed for linux_repo_prereq_pkgs
2017-09-14 21:20:43,451 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-14 21:20:44,006 [salt.state       ][INFO    ][12979] All specified packages are already installed
2017-09-14 21:20:44,007 [salt.state       ][INFO    ][12979] Completed state [linux_repo_prereq_pkgs] at time 21:20:44.006599 duration_in_ms=557.012
2017-09-14 21:20:44,007 [salt.state       ][INFO    ][12979] Running state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack_holdback] at time 21:20:44.006985
2017-09-14 21:20:44,007 [salt.state       ][INFO    ][12979] Executing state file.absent for /etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack_holdback
2017-09-14 21:20:44,008 [salt.state       ][INFO    ][12979] File /etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack_holdback is not present
2017-09-14 21:20:44,008 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack_holdback] at time 21:20:44.008187 duration_in_ms=1.202
2017-09-14 21:20:44,008 [salt.state       ][INFO    ][12979] Running state [/etc/apt/preferences.d/mirantis_openstack_holdback] at time 21:20:44.008466
2017-09-14 21:20:44,009 [salt.state       ][INFO    ][12979] Executing state file.managed for /etc/apt/preferences.d/mirantis_openstack_holdback
2017-09-14 21:20:44,028 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/files/preferences_repo'
2017-09-14 21:20:44,046 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:44,080 [salt.state       ][INFO    ][12979] File /etc/apt/preferences.d/mirantis_openstack_holdback is in the correct state
2017-09-14 21:20:44,081 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/preferences.d/mirantis_openstack_holdback] at time 21:20:44.080736 duration_in_ms=72.27
2017-09-14 21:20:44,083 [salt.state       ][INFO    ][12979] Running state [deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata-holdback main] at time 21:20:44.083014
2017-09-14 21:20:44,083 [salt.state       ][INFO    ][12979] Executing state pkgrepo.managed for deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata-holdback main
2017-09-14 21:20:44,199 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-key', 'add', '/var/cache/salt/minion/extrn_files/base/mirror.fuel-infra.org/mcp-repos/ocata/xenial/archive-mcpocata.key'] in directory '/root'
2017-09-14 21:20:44,293 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-14 21:20:46,979 [salt.state       ][INFO    ][12979] Configured package repo 'deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata-holdback main'
2017-09-14 21:20:46,980 [salt.state       ][INFO    ][12979] Completed state [deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata-holdback main] at time 21:20:46.979906 duration_in_ms=2896.891
2017-09-14 21:20:46,980 [salt.state       ][INFO    ][12979] Running state [/etc/apt/apt.conf.d/99proxies-salt-uca] at time 21:20:46.980317
2017-09-14 21:20:46,981 [salt.state       ][INFO    ][12979] Executing state file.absent for /etc/apt/apt.conf.d/99proxies-salt-uca
2017-09-14 21:20:46,981 [salt.state       ][INFO    ][12979] File /etc/apt/apt.conf.d/99proxies-salt-uca is not present
2017-09-14 21:20:46,981 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/apt.conf.d/99proxies-salt-uca] at time 21:20:46.981430 duration_in_ms=1.113
2017-09-14 21:20:46,982 [salt.state       ][INFO    ][12979] Running state [/etc/apt/preferences.d/uca] at time 21:20:46.981732
2017-09-14 21:20:46,982 [salt.state       ][INFO    ][12979] Executing state file.absent for /etc/apt/preferences.d/uca
2017-09-14 21:20:46,982 [salt.state       ][INFO    ][12979] File /etc/apt/preferences.d/uca is not present
2017-09-14 21:20:46,983 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/preferences.d/uca] at time 21:20:46.982717 duration_in_ms=0.985
2017-09-14 21:20:46,984 [salt.state       ][INFO    ][12979] Running state [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/ocata main] at time 21:20:46.983650
2017-09-14 21:20:46,984 [salt.state       ][INFO    ][12979] Executing state pkgrepo.managed for deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/ocata main
2017-09-14 21:20:47,020 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914212047003969
2017-09-14 21:20:47,038 [salt.minion      ][INFO    ][13683] Starting a new job with PID 13683
2017-09-14 21:20:47,048 [salt.minion      ][INFO    ][13683] Returning information for job: 20170914212047003969
2017-09-14 21:20:47,052 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-key', 'export', 'EC4926EA'] in directory '/root'
2017-09-14 21:20:47,103 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-14 21:20:49,784 [salt.state       ][INFO    ][12979] Configured package repo 'deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/ocata main'
2017-09-14 21:20:49,785 [salt.state       ][INFO    ][12979] Completed state [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/ocata main] at time 21:20:49.784845 duration_in_ms=2801.193
2017-09-14 21:20:49,785 [salt.state       ][INFO    ][12979] Running state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack_updates] at time 21:20:49.785392
2017-09-14 21:20:49,786 [salt.state       ][INFO    ][12979] Executing state file.absent for /etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack_updates
2017-09-14 21:20:49,787 [salt.state       ][INFO    ][12979] File /etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack_updates is not present
2017-09-14 21:20:49,787 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack_updates] at time 21:20:49.786960 duration_in_ms=1.569
2017-09-14 21:20:49,787 [salt.state       ][INFO    ][12979] Running state [/etc/apt/preferences.d/mirantis_openstack_updates] at time 21:20:49.787336
2017-09-14 21:20:49,788 [salt.state       ][INFO    ][12979] Executing state file.managed for /etc/apt/preferences.d/mirantis_openstack_updates
2017-09-14 21:20:49,801 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/files/preferences_repo'
2017-09-14 21:20:49,818 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:49,855 [salt.state       ][INFO    ][12979] File /etc/apt/preferences.d/mirantis_openstack_updates is in the correct state
2017-09-14 21:20:49,856 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/preferences.d/mirantis_openstack_updates] at time 21:20:49.855872 duration_in_ms=68.536
2017-09-14 21:20:49,857 [salt.state       ][INFO    ][12979] Running state [deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata-updates main] at time 21:20:49.856735
2017-09-14 21:20:49,857 [salt.state       ][INFO    ][12979] Executing state pkgrepo.managed for deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata-updates main
2017-09-14 21:20:49,936 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-key', 'add', '/var/cache/salt/minion/extrn_files/base/mirror.fuel-infra.org/mcp-repos/ocata/xenial/archive-mcpocata.key'] in directory '/root'
2017-09-14 21:20:50,023 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-14 21:20:52,476 [salt.state       ][INFO    ][12979] Configured package repo 'deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata-updates main'
2017-09-14 21:20:52,477 [salt.state       ][INFO    ][12979] Completed state [deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata-updates main] at time 21:20:52.476922 duration_in_ms=2620.186
2017-09-14 21:20:52,477 [salt.state       ][INFO    ][12979] Running state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack_security] at time 21:20:52.477444
2017-09-14 21:20:52,478 [salt.state       ][INFO    ][12979] Executing state file.absent for /etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack_security
2017-09-14 21:20:52,479 [salt.state       ][INFO    ][12979] File /etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack_security is not present
2017-09-14 21:20:52,479 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack_security] at time 21:20:52.478962 duration_in_ms=1.518
2017-09-14 21:20:52,479 [salt.state       ][INFO    ][12979] Running state [/etc/apt/preferences.d/mirantis_openstack_security] at time 21:20:52.479400
2017-09-14 21:20:52,480 [salt.state       ][INFO    ][12979] Executing state file.managed for /etc/apt/preferences.d/mirantis_openstack_security
2017-09-14 21:20:52,499 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/files/preferences_repo'
2017-09-14 21:20:52,521 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:52,565 [salt.state       ][INFO    ][12979] File /etc/apt/preferences.d/mirantis_openstack_security is in the correct state
2017-09-14 21:20:52,566 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/preferences.d/mirantis_openstack_security] at time 21:20:52.565854 duration_in_ms=86.453
2017-09-14 21:20:52,567 [salt.state       ][INFO    ][12979] Running state [deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata-security main] at time 21:20:52.566948
2017-09-14 21:20:52,567 [salt.state       ][INFO    ][12979] Executing state pkgrepo.managed for deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata-security main
2017-09-14 21:20:52,674 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-key', 'add', '/var/cache/salt/minion/extrn_files/base/mirror.fuel-infra.org/mcp-repos/ocata/xenial/archive-mcpocata.key'] in directory '/root'
2017-09-14 21:20:52,797 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-14 21:20:54,929 [salt.state       ][INFO    ][12979] Configured package repo 'deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata-security main'
2017-09-14 21:20:54,929 [salt.state       ][INFO    ][12979] Completed state [deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata-security main] at time 21:20:54.929333 duration_in_ms=2362.384
2017-09-14 21:20:54,930 [salt.state       ][INFO    ][12979] Running state [/etc/apt/apt.conf.d/99proxies-salt-mk_openstack] at time 21:20:54.929902
2017-09-14 21:20:54,930 [salt.state       ][INFO    ][12979] Executing state file.absent for /etc/apt/apt.conf.d/99proxies-salt-mk_openstack
2017-09-14 21:20:54,931 [salt.state       ][INFO    ][12979] File /etc/apt/apt.conf.d/99proxies-salt-mk_openstack is not present
2017-09-14 21:20:54,932 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mk_openstack] at time 21:20:54.931603 duration_in_ms=1.7
2017-09-14 21:20:54,932 [salt.state       ][INFO    ][12979] Running state [/etc/apt/preferences.d/mk_openstack] at time 21:20:54.932088
2017-09-14 21:20:54,933 [salt.state       ][INFO    ][12979] Executing state file.managed for /etc/apt/preferences.d/mk_openstack
2017-09-14 21:20:54,953 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/files/preferences_repo'
2017-09-14 21:20:54,980 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:55,043 [salt.state       ][INFO    ][12979] File /etc/apt/preferences.d/mk_openstack is in the correct state
2017-09-14 21:20:55,044 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/preferences.d/mk_openstack] at time 21:20:55.043512 duration_in_ms=111.423
2017-09-14 21:20:55,045 [salt.state       ][INFO    ][12979] Running state [deb [arch=amd64] http://apt-mk.mirantis.com/xenial/ nightly ocata] at time 21:20:55.045012
2017-09-14 21:20:55,046 [salt.state       ][INFO    ][12979] Executing state pkgrepo.managed for deb [arch=amd64] http://apt-mk.mirantis.com/xenial/ nightly ocata
2017-09-14 21:20:55,504 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-key', 'add', '/var/cache/salt/minion/extrn_files/base/apt-mk.mirantis.com/public.gpg'] in directory '/root'
2017-09-14 21:20:55,657 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-14 21:20:57,088 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914212057069317
2017-09-14 21:20:57,110 [salt.minion      ][INFO    ][16189] Starting a new job with PID 16189
2017-09-14 21:20:57,662 [salt.minion      ][INFO    ][16189] Returning information for job: 20170914212057069317
2017-09-14 21:20:58,375 [salt.state       ][INFO    ][12979] Configured package repo 'deb [arch=amd64] http://apt-mk.mirantis.com/xenial/ nightly ocata'
2017-09-14 21:20:58,376 [salt.state       ][INFO    ][12979] Completed state [deb [arch=amd64] http://apt-mk.mirantis.com/xenial/ nightly ocata] at time 21:20:58.376117 duration_in_ms=3331.104
2017-09-14 21:20:58,377 [salt.state       ][INFO    ][12979] Running state [/etc/apt/apt.conf.d/99proxies-salt-mcp_extra] at time 21:20:58.376484
2017-09-14 21:20:58,377 [salt.state       ][INFO    ][12979] Executing state file.absent for /etc/apt/apt.conf.d/99proxies-salt-mcp_extra
2017-09-14 21:20:58,377 [salt.state       ][INFO    ][12979] File /etc/apt/apt.conf.d/99proxies-salt-mcp_extra is not present
2017-09-14 21:20:58,378 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mcp_extra] at time 21:20:58.377530 duration_in_ms=1.046
2017-09-14 21:20:58,378 [salt.state       ][INFO    ][12979] Running state [/etc/apt/preferences.d/mcp_extra] at time 21:20:58.377819
2017-09-14 21:20:58,378 [salt.state       ][INFO    ][12979] Executing state file.managed for /etc/apt/preferences.d/mcp_extra
2017-09-14 21:20:58,400 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/files/preferences_repo'
2017-09-14 21:20:58,420 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:20:58,454 [salt.state       ][INFO    ][12979] File /etc/apt/preferences.d/mcp_extra is in the correct state
2017-09-14 21:20:58,454 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/preferences.d/mcp_extra] at time 21:20:58.454390 duration_in_ms=76.569
2017-09-14 21:20:58,457 [salt.state       ][INFO    ][12979] Running state [deb [arch=amd64] http://apt-mk.mirantis.com/xenial/ nightly extra] at time 21:20:58.456588
2017-09-14 21:20:58,457 [salt.state       ][INFO    ][12979] Executing state pkgrepo.managed for deb [arch=amd64] http://apt-mk.mirantis.com/xenial/ nightly extra
2017-09-14 21:20:58,565 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-key', 'add', '/var/cache/salt/minion/extrn_files/base/apt-mk.mirantis.com/public.gpg'] in directory '/root'
2017-09-14 21:20:58,658 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-14 21:21:01,215 [salt.state       ][INFO    ][12979] Configured package repo 'deb [arch=amd64] http://apt-mk.mirantis.com/xenial/ nightly extra'
2017-09-14 21:21:01,216 [salt.state       ][INFO    ][12979] Completed state [deb [arch=amd64] http://apt-mk.mirantis.com/xenial/ nightly extra] at time 21:21:01.215524 duration_in_ms=2758.936
2017-09-14 21:21:01,216 [salt.state       ][INFO    ][12979] Running state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack] at time 21:21:01.215898
2017-09-14 21:21:01,216 [salt.state       ][INFO    ][12979] Executing state file.absent for /etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack
2017-09-14 21:21:01,217 [salt.state       ][INFO    ][12979] File /etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack is not present
2017-09-14 21:21:01,217 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack] at time 21:21:01.216979 duration_in_ms=1.081
2017-09-14 21:21:01,217 [salt.state       ][INFO    ][12979] Running state [/etc/apt/preferences.d/mirantis_openstack] at time 21:21:01.217292
2017-09-14 21:21:01,218 [salt.state       ][INFO    ][12979] Executing state file.managed for /etc/apt/preferences.d/mirantis_openstack
2017-09-14 21:21:01,237 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/files/preferences_repo'
2017-09-14 21:21:01,255 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:21:01,289 [salt.state       ][INFO    ][12979] File /etc/apt/preferences.d/mirantis_openstack is in the correct state
2017-09-14 21:21:01,289 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/preferences.d/mirantis_openstack] at time 21:21:01.289201 duration_in_ms=71.908
2017-09-14 21:21:01,290 [salt.state       ][INFO    ][12979] Running state [deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata main] at time 21:21:01.290096
2017-09-14 21:21:01,290 [salt.state       ][INFO    ][12979] Executing state pkgrepo.managed for deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata main
2017-09-14 21:21:01,404 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-key', 'add', '/var/cache/salt/minion/extrn_files/base/mirror.fuel-infra.org/mcp-repos/ocata/xenial/archive-mcpocata.key'] in directory '/root'
2017-09-14 21:21:01,519 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-14 21:21:07,192 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914212107176763
2017-09-14 21:21:07,230 [salt.minion      ][INFO    ][17479] Starting a new job with PID 17479
2017-09-14 21:21:07,244 [salt.minion      ][INFO    ][17479] Returning information for job: 20170914212107176763
2017-09-14 21:21:08,976 [salt.state       ][INFO    ][12979] Configured package repo 'deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata main'
2017-09-14 21:21:08,976 [salt.state       ][INFO    ][12979] Completed state [deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata main] at time 21:21:08.976127 duration_in_ms=7686.031
2017-09-14 21:21:08,976 [salt.state       ][INFO    ][12979] Running state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack_hotfix] at time 21:21:08.976347
2017-09-14 21:21:08,977 [salt.state       ][INFO    ][12979] Executing state file.absent for /etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack_hotfix
2017-09-14 21:21:08,977 [salt.state       ][INFO    ][12979] File /etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack_hotfix is not present
2017-09-14 21:21:08,977 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack_hotfix] at time 21:21:08.977338 duration_in_ms=0.991
2017-09-14 21:21:08,978 [salt.state       ][INFO    ][12979] Running state [/etc/apt/preferences.d/mirantis_openstack_hotfix] at time 21:21:08.977640
2017-09-14 21:21:08,978 [salt.state       ][INFO    ][12979] Executing state file.managed for /etc/apt/preferences.d/mirantis_openstack_hotfix
2017-09-14 21:21:09,003 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/files/preferences_repo'
2017-09-14 21:21:09,023 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:21:09,090 [salt.state       ][INFO    ][12979] File /etc/apt/preferences.d/mirantis_openstack_hotfix is in the correct state
2017-09-14 21:21:09,091 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/preferences.d/mirantis_openstack_hotfix] at time 21:21:09.090534 duration_in_ms=112.894
2017-09-14 21:21:09,092 [salt.state       ][INFO    ][12979] Running state [deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata-hotfix main] at time 21:21:09.091679
2017-09-14 21:21:09,092 [salt.state       ][INFO    ][12979] Executing state pkgrepo.managed for deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata-hotfix main
2017-09-14 21:21:09,162 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-key', 'add', '/var/cache/salt/minion/extrn_files/base/mirror.fuel-infra.org/mcp-repos/ocata/xenial/archive-mcpocata.key'] in directory '/root'
2017-09-14 21:21:09,241 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-14 21:21:11,878 [salt.state       ][INFO    ][12979] Configured package repo 'deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata-hotfix main'
2017-09-14 21:21:11,879 [salt.state       ][INFO    ][12979] Completed state [deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata-hotfix main] at time 21:21:11.878773 duration_in_ms=2787.093
2017-09-14 21:21:11,879 [salt.state       ][INFO    ][12979] Running state [/etc/apt/apt.conf.d/99proxies-salt-salt] at time 21:21:11.879190
2017-09-14 21:21:11,880 [salt.state       ][INFO    ][12979] Executing state file.absent for /etc/apt/apt.conf.d/99proxies-salt-salt
2017-09-14 21:21:11,880 [salt.state       ][INFO    ][12979] File /etc/apt/apt.conf.d/99proxies-salt-salt is not present
2017-09-14 21:21:11,881 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/apt.conf.d/99proxies-salt-salt] at time 21:21:11.880660 duration_in_ms=1.47
2017-09-14 21:21:11,881 [salt.state       ][INFO    ][12979] Running state [/etc/apt/preferences.d/salt] at time 21:21:11.881102
2017-09-14 21:21:11,882 [salt.state       ][INFO    ][12979] Executing state file.absent for /etc/apt/preferences.d/salt
2017-09-14 21:21:11,882 [salt.state       ][INFO    ][12979] File /etc/apt/preferences.d/salt is not present
2017-09-14 21:21:11,882 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/preferences.d/salt] at time 21:21:11.882423 duration_in_ms=1.32
2017-09-14 21:21:11,884 [salt.state       ][INFO    ][12979] Running state [deb http://repo.saltstack.com/apt/ubuntu/16.04/amd64/2016.3 xenial main] at time 21:21:11.884078
2017-09-14 21:21:11,885 [salt.state       ][INFO    ][12979] Executing state pkgrepo.managed for deb http://repo.saltstack.com/apt/ubuntu/16.04/amd64/2016.3 xenial main
2017-09-14 21:21:12,162 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-key', 'add', '/var/cache/salt/minion/extrn_files/base/repo.saltstack.com/apt/ubuntu/16.04/amd64/2016.3/SALTSTACK-GPG-KEY.pub'] in directory '/root'
2017-09-14 21:21:12,325 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-14 21:21:14,549 [salt.state       ][INFO    ][12979] Configured package repo 'deb http://repo.saltstack.com/apt/ubuntu/16.04/amd64/2016.3 xenial main'
2017-09-14 21:21:14,550 [salt.state       ][INFO    ][12979] Completed state [deb http://repo.saltstack.com/apt/ubuntu/16.04/amd64/2016.3 xenial main] at time 21:21:14.549488 duration_in_ms=2665.41
2017-09-14 21:21:14,550 [salt.state       ][INFO    ][12979] Running state [linux_extra_packages_purged] at time 21:21:14.550103
2017-09-14 21:21:14,551 [salt.state       ][INFO    ][12979] Executing state pkg.purged for linux_extra_packages_purged
2017-09-14 21:21:14,560 [salt.state       ][INFO    ][12979] All specified packages are already absent
2017-09-14 21:21:14,560 [salt.state       ][INFO    ][12979] Completed state [linux_extra_packages_purged] at time 21:21:14.560426 duration_in_ms=10.322
2017-09-14 21:21:14,561 [salt.state       ][INFO    ][12979] Running state [linux_extra_packages_latest] at time 21:21:14.560846
2017-09-14 21:21:14,561 [salt.state       ][INFO    ][12979] Executing state pkg.latest for linux_extra_packages_latest
2017-09-14 21:21:14,566 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-14 21:21:16,892 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-cache', '-q', 'policy', 'mcelog'] in directory '/root'
2017-09-14 21:21:16,975 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['apt-cache', '-q', 'policy', 'python-msgpack'] in directory '/root'
2017-09-14 21:21:17,061 [salt.state       ][INFO    ][12979] All packages are up-to-date (mcelog, python-msgpack).
2017-09-14 21:21:17,062 [salt.state       ][INFO    ][12979] Completed state [linux_extra_packages_latest] at time 21:21:17.062028 duration_in_ms=2501.18
2017-09-14 21:21:17,063 [salt.state       ][INFO    ][12979] Running state [UTC] at time 21:21:17.063381
2017-09-14 21:21:17,064 [salt.state       ][INFO    ][12979] Executing state timezone.system for UTC
2017-09-14 21:21:17,065 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['timedatectl'] in directory '/root'
2017-09-14 21:21:17,114 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['timedatectl'] in directory '/root'
2017-09-14 21:21:17,129 [salt.state       ][INFO    ][12979] Timezone UTC already set, UTC already set to UTC
2017-09-14 21:21:17,129 [salt.state       ][INFO    ][12979] Completed state [UTC] at time 21:21:17.129380 duration_in_ms=65.999
2017-09-14 21:21:17,131 [salt.state       ][INFO    ][12979] Running state [nf_conntrack] at time 21:21:17.130507
2017-09-14 21:21:17,131 [salt.state       ][INFO    ][12979] Executing state kmod.present for nf_conntrack
2017-09-14 21:21:17,132 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'lsmod' in directory '/root'
2017-09-14 21:21:17,141 [salt.state       ][INFO    ][12979] Kernel module nf_conntrack is already present
2017-09-14 21:21:17,141 [salt.state       ][INFO    ][12979] Completed state [nf_conntrack] at time 21:21:17.141192 duration_in_ms=10.685
2017-09-14 21:21:17,142 [salt.state       ][INFO    ][12979] Running state [net.ipv4.tcp_keepalive_probes] at time 21:21:17.142163
2017-09-14 21:21:17,143 [salt.state       ][INFO    ][12979] Executing state sysctl.present for net.ipv4.tcp_keepalive_probes
2017-09-14 21:21:17,151 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,172 [salt.state       ][INFO    ][12979] Sysctl value net.ipv4.tcp_keepalive_probes = 8 is already set
2017-09-14 21:21:17,172 [salt.state       ][INFO    ][12979] Completed state [net.ipv4.tcp_keepalive_probes] at time 21:21:17.172382 duration_in_ms=30.217
2017-09-14 21:21:17,173 [salt.state       ][INFO    ][12979] Running state [fs.file-max] at time 21:21:17.172930
2017-09-14 21:21:17,173 [salt.state       ][INFO    ][12979] Executing state sysctl.present for fs.file-max
2017-09-14 21:21:17,174 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,200 [salt.state       ][INFO    ][12979] Sysctl value fs.file-max = 124165 is already set
2017-09-14 21:21:17,200 [salt.state       ][INFO    ][12979] Completed state [fs.file-max] at time 21:21:17.200322 duration_in_ms=27.391
2017-09-14 21:21:17,201 [salt.state       ][INFO    ][12979] Running state [net.core.somaxconn] at time 21:21:17.200889
2017-09-14 21:21:17,201 [salt.state       ][INFO    ][12979] Executing state sysctl.present for net.core.somaxconn
2017-09-14 21:21:17,202 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,227 [salt.state       ][INFO    ][12979] Sysctl value net.core.somaxconn = 4096 is already set
2017-09-14 21:21:17,227 [salt.state       ][INFO    ][12979] Completed state [net.core.somaxconn] at time 21:21:17.227343 duration_in_ms=26.453
2017-09-14 21:21:17,228 [salt.state       ][INFO    ][12979] Running state [net.ipv4.tcp_max_syn_backlog] at time 21:21:17.227914
2017-09-14 21:21:17,228 [salt.state       ][INFO    ][12979] Executing state sysctl.present for net.ipv4.tcp_max_syn_backlog
2017-09-14 21:21:17,229 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,251 [salt.state       ][INFO    ][12979] Sysctl value net.ipv4.tcp_max_syn_backlog = 8192 is already set
2017-09-14 21:21:17,252 [salt.state       ][INFO    ][12979] Completed state [net.ipv4.tcp_max_syn_backlog] at time 21:21:17.251905 duration_in_ms=23.99
2017-09-14 21:21:17,252 [salt.state       ][INFO    ][12979] Running state [net.ipv4.tcp_tw_reuse] at time 21:21:17.252420
2017-09-14 21:21:17,253 [salt.state       ][INFO    ][12979] Executing state sysctl.present for net.ipv4.tcp_tw_reuse
2017-09-14 21:21:17,254 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,274 [salt.state       ][INFO    ][12979] Sysctl value net.ipv4.tcp_tw_reuse = 1 is already set
2017-09-14 21:21:17,275 [salt.state       ][INFO    ][12979] Completed state [net.ipv4.tcp_tw_reuse] at time 21:21:17.274450 duration_in_ms=22.029
2017-09-14 21:21:17,275 [salt.state       ][INFO    ][12979] Running state [net.ipv4.tcp_congestion_control] at time 21:21:17.274965
2017-09-14 21:21:17,275 [salt.state       ][INFO    ][12979] Executing state sysctl.present for net.ipv4.tcp_congestion_control
2017-09-14 21:21:17,276 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,295 [salt.state       ][INFO    ][12979] Sysctl value net.ipv4.tcp_congestion_control = yeah is already set
2017-09-14 21:21:17,296 [salt.state       ][INFO    ][12979] Completed state [net.ipv4.tcp_congestion_control] at time 21:21:17.295840 duration_in_ms=20.875
2017-09-14 21:21:17,296 [salt.state       ][INFO    ][12979] Running state [net.nf_conntrack_max] at time 21:21:17.296322
2017-09-14 21:21:17,297 [salt.state       ][INFO    ][12979] Executing state sysctl.present for net.nf_conntrack_max
2017-09-14 21:21:17,297 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,316 [salt.state       ][INFO    ][12979] Sysctl value net.nf_conntrack_max = 1048576 is already set
2017-09-14 21:21:17,317 [salt.state       ][INFO    ][12979] Completed state [net.nf_conntrack_max] at time 21:21:17.316804 duration_in_ms=20.481
2017-09-14 21:21:17,317 [salt.state       ][INFO    ][12979] Running state [net.ipv4.tcp_retries2] at time 21:21:17.317252
2017-09-14 21:21:17,318 [salt.state       ][INFO    ][12979] Executing state sysctl.present for net.ipv4.tcp_retries2
2017-09-14 21:21:17,318 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,340 [salt.state       ][INFO    ][12979] Sysctl value net.ipv4.tcp_retries2 = 5 is already set
2017-09-14 21:21:17,341 [salt.state       ][INFO    ][12979] Completed state [net.ipv4.tcp_retries2] at time 21:21:17.340876 duration_in_ms=23.623
2017-09-14 21:21:17,341 [salt.state       ][INFO    ][12979] Running state [net.core.netdev_max_backlog] at time 21:21:17.341303
2017-09-14 21:21:17,342 [salt.state       ][INFO    ][12979] Executing state sysctl.present for net.core.netdev_max_backlog
2017-09-14 21:21:17,342 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,357 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914212117339739
2017-09-14 21:21:17,374 [salt.state       ][INFO    ][12979] Sysctl value net.core.netdev_max_backlog = 261144 is already set
2017-09-14 21:21:17,375 [salt.state       ][INFO    ][12979] Completed state [net.core.netdev_max_backlog] at time 21:21:17.374871 duration_in_ms=33.566
2017-09-14 21:21:17,376 [salt.state       ][INFO    ][12979] Running state [net.ipv4.tcp_slow_start_after_idle] at time 21:21:17.376065
2017-09-14 21:21:17,378 [salt.state       ][INFO    ][12979] Executing state sysctl.present for net.ipv4.tcp_slow_start_after_idle
2017-09-14 21:21:17,380 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,381 [salt.minion      ][INFO    ][19320] Starting a new job with PID 19320
2017-09-14 21:21:17,397 [salt.minion      ][INFO    ][19320] Returning information for job: 20170914212117339739
2017-09-14 21:21:17,404 [salt.state       ][INFO    ][12979] Sysctl value net.ipv4.tcp_slow_start_after_idle = 0 is already set
2017-09-14 21:21:17,404 [salt.state       ][INFO    ][12979] Completed state [net.ipv4.tcp_slow_start_after_idle] at time 21:21:17.404384 duration_in_ms=28.32
2017-09-14 21:21:17,405 [salt.state       ][INFO    ][12979] Running state [vm.swappiness] at time 21:21:17.405032
2017-09-14 21:21:17,406 [salt.state       ][INFO    ][12979] Executing state sysctl.present for vm.swappiness
2017-09-14 21:21:17,407 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,427 [salt.state       ][INFO    ][12979] Sysctl value vm.swappiness = 10 is already set
2017-09-14 21:21:17,456 [salt.state       ][INFO    ][12979] Completed state [vm.swappiness] at time 21:21:17.456305 duration_in_ms=51.271
2017-09-14 21:21:17,457 [salt.state       ][INFO    ][12979] Running state [net.ipv4.tcp_keepalive_intvl] at time 21:21:17.457004
2017-09-14 21:21:17,458 [salt.state       ][INFO    ][12979] Executing state sysctl.present for net.ipv4.tcp_keepalive_intvl
2017-09-14 21:21:17,459 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,484 [salt.state       ][INFO    ][12979] Sysctl value net.ipv4.tcp_keepalive_intvl = 3 is already set
2017-09-14 21:21:17,485 [salt.state       ][INFO    ][12979] Completed state [net.ipv4.tcp_keepalive_intvl] at time 21:21:17.484542 duration_in_ms=27.537
2017-09-14 21:21:17,485 [salt.state       ][INFO    ][12979] Running state [net.ipv4.neigh.default.gc_thresh1] at time 21:21:17.485068
2017-09-14 21:21:17,486 [salt.state       ][INFO    ][12979] Executing state sysctl.present for net.ipv4.neigh.default.gc_thresh1
2017-09-14 21:21:17,486 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,502 [salt.state       ][INFO    ][12979] Sysctl value net.ipv4.neigh.default.gc_thresh1 = 4096 is already set
2017-09-14 21:21:17,502 [salt.state       ][INFO    ][12979] Completed state [net.ipv4.neigh.default.gc_thresh1] at time 21:21:17.502284 duration_in_ms=17.216
2017-09-14 21:21:17,503 [salt.state       ][INFO    ][12979] Running state [net.ipv4.neigh.default.gc_thresh2] at time 21:21:17.502813
2017-09-14 21:21:17,503 [salt.state       ][INFO    ][12979] Executing state sysctl.present for net.ipv4.neigh.default.gc_thresh2
2017-09-14 21:21:17,504 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,525 [salt.state       ][INFO    ][12979] Sysctl value net.ipv4.neigh.default.gc_thresh2 = 8192 is already set
2017-09-14 21:21:17,526 [salt.state       ][INFO    ][12979] Completed state [net.ipv4.neigh.default.gc_thresh2] at time 21:21:17.525862 duration_in_ms=23.048
2017-09-14 21:21:17,526 [salt.state       ][INFO    ][12979] Running state [net.ipv4.neigh.default.gc_thresh3] at time 21:21:17.526361
2017-09-14 21:21:17,527 [salt.state       ][INFO    ][12979] Executing state sysctl.present for net.ipv4.neigh.default.gc_thresh3
2017-09-14 21:21:17,528 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,548 [salt.state       ][INFO    ][12979] Sysctl value net.ipv4.neigh.default.gc_thresh3 = 16384 is already set
2017-09-14 21:21:17,548 [salt.state       ][INFO    ][12979] Completed state [net.ipv4.neigh.default.gc_thresh3] at time 21:21:17.548069 duration_in_ms=21.708
2017-09-14 21:21:17,549 [salt.state       ][INFO    ][12979] Running state [net.ipv4.tcp_fin_timeout] at time 21:21:17.548560
2017-09-14 21:21:17,549 [salt.state       ][INFO    ][12979] Executing state sysctl.present for net.ipv4.tcp_fin_timeout
2017-09-14 21:21:17,550 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,564 [salt.state       ][INFO    ][12979] Sysctl value net.ipv4.tcp_fin_timeout = 30 is already set
2017-09-14 21:21:17,564 [salt.state       ][INFO    ][12979] Completed state [net.ipv4.tcp_fin_timeout] at time 21:21:17.564411 duration_in_ms=15.85
2017-09-14 21:21:17,565 [salt.state       ][INFO    ][12979] Running state [net.ipv4.tcp_keepalive_time] at time 21:21:17.564892
2017-09-14 21:21:17,565 [salt.state       ][INFO    ][12979] Executing state sysctl.present for net.ipv4.tcp_keepalive_time
2017-09-14 21:21:17,566 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,584 [salt.state       ][INFO    ][12979] Sysctl value net.ipv4.tcp_keepalive_time = 30 is already set
2017-09-14 21:21:17,585 [salt.state       ][INFO    ][12979] Completed state [net.ipv4.tcp_keepalive_time] at time 21:21:17.584521 duration_in_ms=19.629
2017-09-14 21:21:17,585 [salt.state       ][INFO    ][12979] Running state [kernel.panic] at time 21:21:17.584892
2017-09-14 21:21:17,585 [salt.state       ][INFO    ][12979] Executing state sysctl.present for kernel.panic
2017-09-14 21:21:17,586 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:21:17,604 [salt.state       ][INFO    ][12979] Sysctl value kernel.panic = 60 is already set
2017-09-14 21:21:17,604 [salt.state       ][INFO    ][12979] Completed state [kernel.panic] at time 21:21:17.604132 duration_in_ms=19.239
2017-09-14 21:21:17,605 [salt.state       ][INFO    ][12979] Running state [linux_sysfs_package] at time 21:21:17.604534
2017-09-14 21:21:17,605 [salt.state       ][INFO    ][12979] Executing state pkg.installed for linux_sysfs_package
2017-09-14 21:21:17,609 [salt.state       ][INFO    ][12979] All specified packages are already installed
2017-09-14 21:21:17,609 [salt.state       ][INFO    ][12979] Completed state [linux_sysfs_package] at time 21:21:17.609048 duration_in_ms=4.514
2017-09-14 21:21:17,610 [salt.state       ][INFO    ][12979] Running state [/etc/sysfs.d] at time 21:21:17.610206
2017-09-14 21:21:17,611 [salt.state       ][INFO    ][12979] Executing state file.directory for /etc/sysfs.d
2017-09-14 21:21:17,611 [salt.state       ][INFO    ][12979] Directory /etc/sysfs.d is in the correct state
2017-09-14 21:21:17,611 [salt.state       ][INFO    ][12979] Completed state [/etc/sysfs.d] at time 21:21:17.611444 duration_in_ms=1.238
2017-09-14 21:21:17,612 [salt.state       ][INFO    ][12979] Running state [ondemand] at time 21:21:17.612103
2017-09-14 21:21:17,612 [salt.state       ][INFO    ][12979] Executing state service.dead for ondemand
2017-09-14 21:21:17,613 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['systemctl', 'status', 'ondemand.service', '-n', '0'] in directory '/root'
2017-09-14 21:21:17,624 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['systemctl', 'is-active', 'ondemand.service'] in directory '/root'
2017-09-14 21:21:17,635 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2017-09-14 21:21:17,649 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'runlevel' in directory '/root'
2017-09-14 21:21:17,659 [salt.state       ][INFO    ][12979] The service ondemand is already dead
2017-09-14 21:21:17,660 [salt.state       ][INFO    ][12979] Completed state [ondemand] at time 21:21:17.659947 duration_in_ms=47.844
2017-09-14 21:21:17,661 [salt.state       ][INFO    ][12979] Running state [cs_CZ.UTF-8] at time 21:21:17.660716
2017-09-14 21:21:17,661 [salt.state       ][INFO    ][12979] Executing state locale.present for cs_CZ.UTF-8
2017-09-14 21:21:17,662 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'locale -a' in directory '/root'
2017-09-14 21:21:17,672 [salt.state       ][INFO    ][12979] Locale cs_CZ.UTF-8 is already present
2017-09-14 21:21:17,672 [salt.state       ][INFO    ][12979] Completed state [cs_CZ.UTF-8] at time 21:21:17.672035 duration_in_ms=11.319
2017-09-14 21:21:17,672 [salt.state       ][INFO    ][12979] Running state [en_US.UTF-8] at time 21:21:17.672418
2017-09-14 21:21:17,673 [salt.state       ][INFO    ][12979] Executing state locale.present for en_US.UTF-8
2017-09-14 21:21:17,673 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'locale -a' in directory '/root'
2017-09-14 21:21:17,682 [salt.state       ][INFO    ][12979] Locale en_US.UTF-8 is already present
2017-09-14 21:21:17,682 [salt.state       ][INFO    ][12979] Completed state [en_US.UTF-8] at time 21:21:17.681931 duration_in_ms=9.513
2017-09-14 21:21:17,683 [salt.state       ][INFO    ][12979] Running state [en_US.UTF-8] at time 21:21:17.683015
2017-09-14 21:21:17,683 [salt.state       ][INFO    ][12979] Executing state locale.system for en_US.UTF-8
2017-09-14 21:21:17,684 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'localectl' in directory '/root'
2017-09-14 21:21:17,722 [salt.state       ][INFO    ][12979] System locale en_US.UTF-8 already set
2017-09-14 21:21:17,722 [salt.state       ][INFO    ][12979] Completed state [en_US.UTF-8] at time 21:21:17.722276 duration_in_ms=39.26
2017-09-14 21:21:17,724 [salt.state       ][INFO    ][12979] Running state [root] at time 21:21:17.723955
2017-09-14 21:21:17,724 [salt.state       ][INFO    ][12979] Executing state user.present for root
2017-09-14 21:21:17,725 [salt.state       ][INFO    ][12979] User root is present and up to date
2017-09-14 21:21:17,726 [salt.state       ][INFO    ][12979] Completed state [root] at time 21:21:17.725692 duration_in_ms=1.737
2017-09-14 21:21:17,727 [salt.state       ][INFO    ][12979] Running state [/root] at time 21:21:17.726841
2017-09-14 21:21:17,727 [salt.state       ][INFO    ][12979] Executing state file.directory for /root
2017-09-14 21:21:17,728 [salt.state       ][INFO    ][12979] Directory /root is in the correct state
2017-09-14 21:21:17,728 [salt.state       ][INFO    ][12979] Completed state [/root] at time 21:21:17.728157 duration_in_ms=1.316
2017-09-14 21:21:17,729 [salt.state       ][INFO    ][12979] Running state [/etc/sudoers.d/90-salt-user-root] at time 21:21:17.728486
2017-09-14 21:21:17,729 [salt.state       ][INFO    ][12979] Executing state file.absent for /etc/sudoers.d/90-salt-user-root
2017-09-14 21:21:17,729 [salt.state       ][INFO    ][12979] File /etc/sudoers.d/90-salt-user-root is not present
2017-09-14 21:21:17,730 [salt.state       ][INFO    ][12979] Completed state [/etc/sudoers.d/90-salt-user-root] at time 21:21:17.729501 duration_in_ms=1.015
2017-09-14 21:21:17,730 [salt.state       ][INFO    ][12979] Running state [ubuntu] at time 21:21:17.729845
2017-09-14 21:21:17,730 [salt.state       ][INFO    ][12979] Executing state user.present for ubuntu
2017-09-14 21:21:17,731 [salt.state       ][INFO    ][12979] User ubuntu is present and up to date
2017-09-14 21:21:17,731 [salt.state       ][INFO    ][12979] Completed state [ubuntu] at time 21:21:17.731323 duration_in_ms=1.478
2017-09-14 21:21:17,732 [salt.state       ][INFO    ][12979] Running state [/home/ubuntu] at time 21:21:17.732301
2017-09-14 21:21:17,733 [salt.state       ][INFO    ][12979] Executing state file.directory for /home/ubuntu
2017-09-14 21:21:17,733 [salt.state       ][INFO    ][12979] Directory /home/ubuntu is in the correct state
2017-09-14 21:21:17,734 [salt.state       ][INFO    ][12979] Completed state [/home/ubuntu] at time 21:21:17.733572 duration_in_ms=1.271
2017-09-14 21:21:17,734 [salt.state       ][INFO    ][12979] Running state [/etc/sudoers.d/90-salt-user-ubuntu] at time 21:21:17.734420
2017-09-14 21:21:17,735 [salt.state       ][INFO    ][12979] Executing state file.managed for /etc/sudoers.d/90-salt-user-ubuntu
2017-09-14 21:21:17,753 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/files/sudoer'
2017-09-14 21:21:17,757 [salt.state       ][INFO    ][12979] File /etc/sudoers.d/90-salt-user-ubuntu is in the correct state
2017-09-14 21:21:17,758 [salt.state       ][INFO    ][12979] Completed state [/etc/sudoers.d/90-salt-user-ubuntu] at time 21:21:17.757865 duration_in_ms=23.444
2017-09-14 21:21:17,758 [salt.state       ][INFO    ][12979] Running state [/etc/security/limits.d/90-salt-default.conf] at time 21:21:17.758193
2017-09-14 21:21:17,759 [salt.state       ][INFO    ][12979] Executing state file.managed for /etc/security/limits.d/90-salt-default.conf
2017-09-14 21:21:17,776 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/files/limits.conf'
2017-09-14 21:21:17,795 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:21:17,827 [salt.state       ][INFO    ][12979] File /etc/security/limits.d/90-salt-default.conf is in the correct state
2017-09-14 21:21:17,827 [salt.state       ][INFO    ][12979] Completed state [/etc/security/limits.d/90-salt-default.conf] at time 21:21:17.827364 duration_in_ms=69.17
2017-09-14 21:21:17,828 [salt.state       ][INFO    ][12979] Running state [/etc/systemd/system.conf.d/90-salt.conf] at time 21:21:17.827665
2017-09-14 21:21:17,828 [salt.state       ][INFO    ][12979] Executing state file.managed for /etc/systemd/system.conf.d/90-salt.conf
2017-09-14 21:21:17,844 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/files/systemd.conf'
2017-09-14 21:21:17,862 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:21:17,891 [salt.state       ][INFO    ][12979] File /etc/systemd/system.conf.d/90-salt.conf is in the correct state
2017-09-14 21:21:17,891 [salt.state       ][INFO    ][12979] Completed state [/etc/systemd/system.conf.d/90-salt.conf] at time 21:21:17.891207 duration_in_ms=63.542
2017-09-14 21:21:17,892 [salt.state       ][INFO    ][12979] Running state [service.systemctl_reload] at time 21:21:17.892322
2017-09-14 21:21:17,893 [salt.state       ][INFO    ][12979] Executing state module.wait for service.systemctl_reload
2017-09-14 21:21:17,893 [salt.state       ][INFO    ][12979] No changes made for service.systemctl_reload
2017-09-14 21:21:17,893 [salt.state       ][INFO    ][12979] Completed state [service.systemctl_reload] at time 21:21:17.893128 duration_in_ms=0.806
2017-09-14 21:21:17,893 [salt.state       ][INFO    ][12979] Running state [/etc/hostname] at time 21:21:17.893392
2017-09-14 21:21:17,894 [salt.state       ][INFO    ][12979] Executing state file.managed for /etc/hostname
2017-09-14 21:21:17,909 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'linux/files/hostname'
2017-09-14 21:21:17,910 [salt.state       ][INFO    ][12979] File /etc/hostname is in the correct state
2017-09-14 21:21:17,910 [salt.state       ][INFO    ][12979] Completed state [/etc/hostname] at time 21:21:17.910238 duration_in_ms=16.847
2017-09-14 21:21:17,911 [salt.state       ][INFO    ][12979] Running state [hostname msg01] at time 21:21:17.911345
2017-09-14 21:21:17,912 [salt.state       ][INFO    ][12979] Executing state cmd.wait for hostname msg01
2017-09-14 21:21:17,912 [salt.state       ][INFO    ][12979] No changes made for hostname msg01
2017-09-14 21:21:17,912 [salt.state       ][INFO    ][12979] Completed state [hostname msg01] at time 21:21:17.912181 duration_in_ms=0.836
2017-09-14 21:21:17,913 [salt.state       ][INFO    ][12979] Running state [mdb02.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.912629
2017-09-14 21:21:17,913 [salt.state       ][INFO    ][12979] Executing state host.present for mdb02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:17,913 [salt.state       ][INFO    ][12979] Host mdb02.baremetal-mcp-ocata-odl-ha.local (10.167.4.77) already present
2017-09-14 21:21:17,914 [salt.state       ][INFO    ][12979] Completed state [mdb02.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.913599 duration_in_ms=0.97
2017-09-14 21:21:17,914 [salt.state       ][INFO    ][12979] Running state [mdb02] at time 21:21:17.913890
2017-09-14 21:21:17,914 [salt.state       ][INFO    ][12979] Executing state host.present for mdb02
2017-09-14 21:21:17,915 [salt.state       ][INFO    ][12979] Host mdb02 (10.167.4.77) already present
2017-09-14 21:21:17,915 [salt.state       ][INFO    ][12979] Completed state [mdb02] at time 21:21:17.914839 duration_in_ms=0.948
2017-09-14 21:21:17,915 [salt.state       ][INFO    ][12979] Running state [mdb03.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.915115
2017-09-14 21:21:17,915 [salt.state       ][INFO    ][12979] Executing state host.present for mdb03.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:17,916 [salt.state       ][INFO    ][12979] Host mdb03.baremetal-mcp-ocata-odl-ha.local (10.167.4.78) already present
2017-09-14 21:21:17,916 [salt.state       ][INFO    ][12979] Completed state [mdb03.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.916045 duration_in_ms=0.93
2017-09-14 21:21:17,916 [salt.state       ][INFO    ][12979] Running state [mdb03] at time 21:21:17.916317
2017-09-14 21:21:17,917 [salt.state       ][INFO    ][12979] Executing state host.present for mdb03
2017-09-14 21:21:17,917 [salt.state       ][INFO    ][12979] Host mdb03 (10.167.4.78) already present
2017-09-14 21:21:17,917 [salt.state       ][INFO    ][12979] Completed state [mdb03] at time 21:21:17.917245 duration_in_ms=0.928
2017-09-14 21:21:17,918 [salt.state       ][INFO    ][12979] Running state [mdb01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.917516
2017-09-14 21:21:17,918 [salt.state       ][INFO    ][12979] Executing state host.present for mdb01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:17,918 [salt.state       ][INFO    ][12979] Host mdb01.baremetal-mcp-ocata-odl-ha.local (10.167.4.76) already present
2017-09-14 21:21:17,918 [salt.state       ][INFO    ][12979] Completed state [mdb01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.918471 duration_in_ms=0.955
2017-09-14 21:21:17,919 [salt.state       ][INFO    ][12979] Running state [mdb01] at time 21:21:17.918765
2017-09-14 21:21:17,919 [salt.state       ][INFO    ][12979] Executing state host.present for mdb01
2017-09-14 21:21:17,919 [salt.state       ][INFO    ][12979] Host mdb01 (10.167.4.76) already present
2017-09-14 21:21:17,920 [salt.state       ][INFO    ][12979] Completed state [mdb01] at time 21:21:17.919695 duration_in_ms=0.929
2017-09-14 21:21:17,920 [salt.state       ][INFO    ][12979] Running state [mdb] at time 21:21:17.919971
2017-09-14 21:21:17,920 [salt.state       ][INFO    ][12979] Executing state host.present for mdb
2017-09-14 21:21:17,921 [salt.state       ][INFO    ][12979] Host mdb (10.167.4.75) already present
2017-09-14 21:21:17,921 [salt.state       ][INFO    ][12979] Completed state [mdb] at time 21:21:17.920912 duration_in_ms=0.941
2017-09-14 21:21:17,921 [salt.state       ][INFO    ][12979] Running state [mdb.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.921185
2017-09-14 21:21:17,921 [salt.state       ][INFO    ][12979] Executing state host.present for mdb.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:17,922 [salt.state       ][INFO    ][12979] Host mdb.baremetal-mcp-ocata-odl-ha.local (10.167.4.75) already present
2017-09-14 21:21:17,922 [salt.state       ][INFO    ][12979] Completed state [mdb.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.922127 duration_in_ms=0.942
2017-09-14 21:21:17,922 [salt.state       ][INFO    ][12979] Running state [cfg01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.922403
2017-09-14 21:21:17,923 [salt.state       ][INFO    ][12979] Executing state host.present for cfg01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:17,923 [salt.state       ][INFO    ][12979] Host cfg01.baremetal-mcp-ocata-odl-ha.local (10.167.4.100) already present
2017-09-14 21:21:17,923 [salt.state       ][INFO    ][12979] Completed state [cfg01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.923349 duration_in_ms=0.946
2017-09-14 21:21:17,924 [salt.state       ][INFO    ][12979] Running state [cfg01] at time 21:21:17.923623
2017-09-14 21:21:17,924 [salt.state       ][INFO    ][12979] Executing state host.present for cfg01
2017-09-14 21:21:17,924 [salt.state       ][INFO    ][12979] Host cfg01 (10.167.4.100) already present
2017-09-14 21:21:17,925 [salt.state       ][INFO    ][12979] Completed state [cfg01] at time 21:21:17.924554 duration_in_ms=0.93
2017-09-14 21:21:17,925 [salt.state       ][INFO    ][12979] Running state [prx01] at time 21:21:17.924826
2017-09-14 21:21:17,925 [salt.state       ][INFO    ][12979] Executing state host.present for prx01
2017-09-14 21:21:17,926 [salt.state       ][INFO    ][12979] Host prx01 (10.167.4.81) already present
2017-09-14 21:21:17,926 [salt.state       ][INFO    ][12979] Completed state [prx01] at time 21:21:17.925770 duration_in_ms=0.944
2017-09-14 21:21:17,926 [salt.state       ][INFO    ][12979] Running state [prx01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.926044
2017-09-14 21:21:17,926 [salt.state       ][INFO    ][12979] Executing state host.present for prx01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:17,927 [salt.state       ][INFO    ][12979] Host prx01.baremetal-mcp-ocata-odl-ha.local (10.167.4.81) already present
2017-09-14 21:21:17,927 [salt.state       ][INFO    ][12979] Completed state [prx01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.926986 duration_in_ms=0.942
2017-09-14 21:21:17,927 [salt.state       ][INFO    ][12979] Running state [kvm01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.927261
2017-09-14 21:21:17,928 [salt.state       ][INFO    ][12979] Executing state host.present for kvm01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:17,928 [salt.state       ][INFO    ][12979] Host kvm01.baremetal-mcp-ocata-odl-ha.local (10.167.4.141) already present
2017-09-14 21:21:17,928 [salt.state       ][INFO    ][12979] Completed state [kvm01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.928202 duration_in_ms=0.941
2017-09-14 21:21:17,929 [salt.state       ][INFO    ][12979] Running state [kvm01] at time 21:21:17.928486
2017-09-14 21:21:17,929 [salt.state       ][INFO    ][12979] Executing state host.present for kvm01
2017-09-14 21:21:17,929 [salt.state       ][INFO    ][12979] Host kvm01 (10.167.4.141) already present
2017-09-14 21:21:17,929 [salt.state       ][INFO    ][12979] Completed state [kvm01] at time 21:21:17.929415 duration_in_ms=0.928
2017-09-14 21:21:17,930 [salt.state       ][INFO    ][12979] Running state [kvm03.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.929698
2017-09-14 21:21:17,930 [salt.state       ][INFO    ][12979] Executing state host.present for kvm03.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:17,930 [salt.state       ][INFO    ][12979] Host kvm03.baremetal-mcp-ocata-odl-ha.local (10.167.4.143) already present
2017-09-14 21:21:17,931 [salt.state       ][INFO    ][12979] Completed state [kvm03.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.930654 duration_in_ms=0.956
2017-09-14 21:21:17,931 [salt.state       ][INFO    ][12979] Running state [kvm03] at time 21:21:17.930924
2017-09-14 21:21:17,931 [salt.state       ][INFO    ][12979] Executing state host.present for kvm03
2017-09-14 21:21:17,932 [salt.state       ][INFO    ][12979] Host kvm03 (10.167.4.143) already present
2017-09-14 21:21:17,932 [salt.state       ][INFO    ][12979] Completed state [kvm03] at time 21:21:17.931824 duration_in_ms=0.901
2017-09-14 21:21:17,932 [salt.state       ][INFO    ][12979] Running state [kvm02.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.932090
2017-09-14 21:21:17,932 [salt.state       ][INFO    ][12979] Executing state host.present for kvm02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:17,933 [salt.state       ][INFO    ][12979] Host kvm02.baremetal-mcp-ocata-odl-ha.local (10.167.4.142) already present
2017-09-14 21:21:17,933 [salt.state       ][INFO    ][12979] Completed state [kvm02.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.933145 duration_in_ms=1.055
2017-09-14 21:21:17,933 [salt.state       ][INFO    ][12979] Running state [kvm02] at time 21:21:17.933413
2017-09-14 21:21:17,934 [salt.state       ][INFO    ][12979] Executing state host.present for kvm02
2017-09-14 21:21:17,934 [salt.state       ][INFO    ][12979] Host kvm02 (10.167.4.142) already present
2017-09-14 21:21:17,934 [salt.state       ][INFO    ][12979] Completed state [kvm02] at time 21:21:17.934359 duration_in_ms=0.945
2017-09-14 21:21:17,935 [salt.state       ][INFO    ][12979] Running state [dbs] at time 21:21:17.934634
2017-09-14 21:21:17,935 [salt.state       ][INFO    ][12979] Executing state host.present for dbs
2017-09-14 21:21:17,935 [salt.state       ][INFO    ][12979] Host dbs (10.167.4.50) already present
2017-09-14 21:21:17,936 [salt.state       ][INFO    ][12979] Completed state [dbs] at time 21:21:17.935543 duration_in_ms=0.909
2017-09-14 21:21:17,936 [salt.state       ][INFO    ][12979] Running state [dbs.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.935807
2017-09-14 21:21:17,936 [salt.state       ][INFO    ][12979] Executing state host.present for dbs.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:17,936 [salt.state       ][INFO    ][12979] Host dbs.baremetal-mcp-ocata-odl-ha.local (10.167.4.50) already present
2017-09-14 21:21:17,937 [salt.state       ][INFO    ][12979] Completed state [dbs.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.936725 duration_in_ms=0.917
2017-09-14 21:21:17,937 [salt.state       ][INFO    ][12979] Running state [prx.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.937024
2017-09-14 21:21:17,937 [salt.state       ][INFO    ][12979] Executing state host.present for prx.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:17,938 [salt.state       ][INFO    ][12979] Host prx.baremetal-mcp-ocata-odl-ha.local (10.167.4.80) already present
2017-09-14 21:21:17,938 [salt.state       ][INFO    ][12979] Completed state [prx.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.937960 duration_in_ms=0.937
2017-09-14 21:21:17,938 [salt.state       ][INFO    ][12979] Running state [prx] at time 21:21:17.938232
2017-09-14 21:21:17,939 [salt.state       ][INFO    ][12979] Executing state host.present for prx
2017-09-14 21:21:17,939 [salt.state       ][INFO    ][12979] Host prx (10.167.4.80) already present
2017-09-14 21:21:17,939 [salt.state       ][INFO    ][12979] Completed state [prx] at time 21:21:17.939185 duration_in_ms=0.953
2017-09-14 21:21:17,939 [salt.state       ][INFO    ][12979] Running state [prx02] at time 21:21:17.939462
2017-09-14 21:21:17,940 [salt.state       ][INFO    ][12979] Executing state host.present for prx02
2017-09-14 21:21:17,940 [salt.state       ][INFO    ][12979] Host prx02 (10.167.4.82) already present
2017-09-14 21:21:17,940 [salt.state       ][INFO    ][12979] Completed state [prx02] at time 21:21:17.940384 duration_in_ms=0.921
2017-09-14 21:21:17,941 [salt.state       ][INFO    ][12979] Running state [prx02.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.940654
2017-09-14 21:21:17,941 [salt.state       ][INFO    ][12979] Executing state host.present for prx02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:17,941 [salt.state       ][INFO    ][12979] Host prx02.baremetal-mcp-ocata-odl-ha.local (10.167.4.82) already present
2017-09-14 21:21:17,942 [salt.state       ][INFO    ][12979] Completed state [prx02.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.941593 duration_in_ms=0.938
2017-09-14 21:21:17,942 [salt.state       ][INFO    ][12979] Running state [msg02.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.941870
2017-09-14 21:21:17,942 [salt.state       ][INFO    ][12979] Executing state host.present for msg02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:17,943 [salt.state       ][INFO    ][12979] Host msg02.baremetal-mcp-ocata-odl-ha.local (10.167.4.42) already present
2017-09-14 21:21:17,943 [salt.state       ][INFO    ][12979] Completed state [msg02.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.942836 duration_in_ms=0.965
2017-09-14 21:21:17,943 [salt.state       ][INFO    ][12979] Running state [msg02] at time 21:21:17.943106
2017-09-14 21:21:17,943 [salt.state       ][INFO    ][12979] Executing state host.present for msg02
2017-09-14 21:21:17,944 [salt.state       ][INFO    ][12979] Host msg02 (10.167.4.42) already present
2017-09-14 21:21:17,944 [salt.state       ][INFO    ][12979] Completed state [msg02] at time 21:21:17.944019 duration_in_ms=0.913
2017-09-14 21:21:17,944 [salt.state       ][INFO    ][12979] Running state [msg03.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.944286
2017-09-14 21:21:17,945 [salt.state       ][INFO    ][12979] Executing state host.present for msg03.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:17,945 [salt.state       ][INFO    ][12979] Host msg03.baremetal-mcp-ocata-odl-ha.local (10.167.4.43) already present
2017-09-14 21:21:17,945 [salt.state       ][INFO    ][12979] Completed state [msg03.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.945260 duration_in_ms=0.974
2017-09-14 21:21:17,946 [salt.state       ][INFO    ][12979] Running state [msg03] at time 21:21:17.945538
2017-09-14 21:21:17,946 [salt.state       ][INFO    ][12979] Executing state host.present for msg03
2017-09-14 21:21:17,946 [salt.state       ][INFO    ][12979] Host msg03 (10.167.4.43) already present
2017-09-14 21:21:17,946 [salt.state       ][INFO    ][12979] Completed state [msg03] at time 21:21:17.946460 duration_in_ms=0.921
2017-09-14 21:21:17,947 [salt.state       ][INFO    ][12979] Running state [msg01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.946753
2017-09-14 21:21:17,947 [salt.state       ][INFO    ][12979] Executing state host.present for msg01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:17,947 [salt.state       ][INFO    ][12979] Host msg01.baremetal-mcp-ocata-odl-ha.local (10.167.4.41) already present
2017-09-14 21:21:17,948 [salt.state       ][INFO    ][12979] Completed state [msg01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:17.947720 duration_in_ms=0.967
2017-09-14 21:21:17,948 [salt.state       ][INFO    ][12979] Running state [msg01] at time 21:21:17.948003
2017-09-14 21:21:17,948 [salt.state       ][INFO    ][12979] Executing state host.present for msg01
2017-09-14 21:21:17,949 [salt.state       ][INFO    ][12979] Host msg01 (10.167.4.41) already present
2017-09-14 21:21:17,949 [salt.state       ][INFO    ][12979] Completed state [msg01] at time 21:21:17.948937 duration_in_ms=0.934
2017-09-14 21:21:17,950 [salt.state       ][INFO    ][12979] Running state [file.replace] at time 21:21:17.949779
2017-09-14 21:21:17,950 [salt.state       ][INFO    ][12979] Executing state module.run for file.replace
2017-09-14 21:21:19,329 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command 'grep -q "msg01 msg01.baremetal-mcp-ocata-odl-ha.local" /etc/hosts' in directory '/root'
2017-09-14 21:21:19,347 [salt.state       ][INFO    ][12979] onlyif execution failed
2017-09-14 21:21:19,348 [salt.state       ][INFO    ][12979] Completed state [file.replace] at time 21:21:19.348050 duration_in_ms=1398.269
2017-09-14 21:21:19,349 [salt.state       ][INFO    ][12979] Running state [msg] at time 21:21:19.349076
2017-09-14 21:21:19,350 [salt.state       ][INFO    ][12979] Executing state host.present for msg
2017-09-14 21:21:19,352 [salt.state       ][INFO    ][12979] Host msg (10.167.4.40) already present
2017-09-14 21:21:19,352 [salt.state       ][INFO    ][12979] Completed state [msg] at time 21:21:19.352297 duration_in_ms=3.221
2017-09-14 21:21:19,353 [salt.state       ][INFO    ][12979] Running state [msg.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.353058
2017-09-14 21:21:19,354 [salt.state       ][INFO    ][12979] Executing state host.present for msg.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:19,355 [salt.state       ][INFO    ][12979] Host msg.baremetal-mcp-ocata-odl-ha.local (10.167.4.40) already present
2017-09-14 21:21:19,356 [salt.state       ][INFO    ][12979] Completed state [msg.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.355877 duration_in_ms=2.819
2017-09-14 21:21:19,357 [salt.state       ][INFO    ][12979] Running state [cfg01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.356643
2017-09-14 21:21:19,357 [salt.state       ][INFO    ][12979] Executing state host.present for cfg01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:19,359 [salt.state       ][INFO    ][12979] Host cfg01.baremetal-mcp-ocata-odl-ha.local (10.167.4.100) already present
2017-09-14 21:21:19,359 [salt.state       ][INFO    ][12979] Completed state [cfg01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.359192 duration_in_ms=2.549
2017-09-14 21:21:19,360 [salt.state       ][INFO    ][12979] Running state [cfg01] at time 21:21:19.359905
2017-09-14 21:21:19,361 [salt.state       ][INFO    ][12979] Executing state host.present for cfg01
2017-09-14 21:21:19,362 [salt.state       ][INFO    ][12979] Host cfg01 (10.167.4.100) already present
2017-09-14 21:21:19,362 [salt.state       ][INFO    ][12979] Completed state [cfg01] at time 21:21:19.362310 duration_in_ms=2.405
2017-09-14 21:21:19,363 [salt.state       ][INFO    ][12979] Running state [cmp002] at time 21:21:19.363079
2017-09-14 21:21:19,364 [salt.state       ][INFO    ][12979] Executing state host.present for cmp002
2017-09-14 21:21:19,365 [salt.state       ][INFO    ][12979] Host cmp002 (10.167.4.102) already present
2017-09-14 21:21:19,366 [salt.state       ][INFO    ][12979] Completed state [cmp002] at time 21:21:19.365438 duration_in_ms=2.358
2017-09-14 21:21:19,366 [salt.state       ][INFO    ][12979] Running state [cmp002.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.366277
2017-09-14 21:21:19,367 [salt.state       ][INFO    ][12979] Executing state host.present for cmp002.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:19,369 [salt.state       ][INFO    ][12979] Host cmp002.baremetal-mcp-ocata-odl-ha.local (10.167.4.102) already present
2017-09-14 21:21:19,370 [salt.state       ][INFO    ][12979] Completed state [cmp002.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.369447 duration_in_ms=3.171
2017-09-14 21:21:19,370 [salt.state       ][INFO    ][12979] Running state [cmp001] at time 21:21:19.370304
2017-09-14 21:21:19,371 [salt.state       ][INFO    ][12979] Executing state host.present for cmp001
2017-09-14 21:21:19,372 [salt.state       ][INFO    ][12979] Host cmp001 (10.167.4.101) already present
2017-09-14 21:21:19,373 [salt.state       ][INFO    ][12979] Completed state [cmp001] at time 21:21:19.372469 duration_in_ms=2.165
2017-09-14 21:21:19,373 [salt.state       ][INFO    ][12979] Running state [cmp001.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.373094
2017-09-14 21:21:19,374 [salt.state       ][INFO    ][12979] Executing state host.present for cmp001.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:19,375 [salt.state       ][INFO    ][12979] Host cmp001.baremetal-mcp-ocata-odl-ha.local (10.167.4.101) already present
2017-09-14 21:21:19,375 [salt.state       ][INFO    ][12979] Completed state [cmp001.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.375242 duration_in_ms=2.148
2017-09-14 21:21:19,376 [salt.state       ][INFO    ][12979] Running state [dbs01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.375885
2017-09-14 21:21:19,376 [salt.state       ][INFO    ][12979] Executing state host.present for dbs01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:19,377 [salt.state       ][INFO    ][12979] Host dbs01.baremetal-mcp-ocata-odl-ha.local (10.167.4.51) already present
2017-09-14 21:21:19,378 [salt.state       ][INFO    ][12979] Completed state [dbs01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.377970 duration_in_ms=2.085
2017-09-14 21:21:19,379 [salt.state       ][INFO    ][12979] Running state [dbs01] at time 21:21:19.378597
2017-09-14 21:21:19,379 [salt.state       ][INFO    ][12979] Executing state host.present for dbs01
2017-09-14 21:21:19,380 [salt.state       ][INFO    ][12979] Host dbs01 (10.167.4.51) already present
2017-09-14 21:21:19,381 [salt.state       ][INFO    ][12979] Completed state [dbs01] at time 21:21:19.380709 duration_in_ms=2.112
2017-09-14 21:21:19,381 [salt.state       ][INFO    ][12979] Running state [dbs02.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.381315
2017-09-14 21:21:19,382 [salt.state       ][INFO    ][12979] Executing state host.present for dbs02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:19,383 [salt.state       ][INFO    ][12979] Host dbs02.baremetal-mcp-ocata-odl-ha.local (10.167.4.52) already present
2017-09-14 21:21:19,383 [salt.state       ][INFO    ][12979] Completed state [dbs02.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.383370 duration_in_ms=2.055
2017-09-14 21:21:19,384 [salt.state       ][INFO    ][12979] Running state [dbs02] at time 21:21:19.383962
2017-09-14 21:21:19,385 [salt.state       ][INFO    ][12979] Executing state host.present for dbs02
2017-09-14 21:21:19,385 [salt.state       ][INFO    ][12979] Host dbs02 (10.167.4.52) already present
2017-09-14 21:21:19,386 [salt.state       ][INFO    ][12979] Completed state [dbs02] at time 21:21:19.385867 duration_in_ms=1.905
2017-09-14 21:21:19,386 [salt.state       ][INFO    ][12979] Running state [dbs03.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.386411
2017-09-14 21:21:19,387 [salt.state       ][INFO    ][12979] Executing state host.present for dbs03.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:19,388 [salt.state       ][INFO    ][12979] Host dbs03.baremetal-mcp-ocata-odl-ha.local (10.167.4.53) already present
2017-09-14 21:21:19,388 [salt.state       ][INFO    ][12979] Completed state [dbs03.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.388266 duration_in_ms=1.854
2017-09-14 21:21:19,389 [salt.state       ][INFO    ][12979] Running state [dbs03] at time 21:21:19.388806
2017-09-14 21:21:19,389 [salt.state       ][INFO    ][12979] Executing state host.present for dbs03
2017-09-14 21:21:19,390 [salt.state       ][INFO    ][12979] Host dbs03 (10.167.4.53) already present
2017-09-14 21:21:19,391 [salt.state       ][INFO    ][12979] Completed state [dbs03] at time 21:21:19.390701 duration_in_ms=1.895
2017-09-14 21:21:19,391 [salt.state       ][INFO    ][12979] Running state [odl01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.391258
2017-09-14 21:21:19,392 [salt.state       ][INFO    ][12979] Executing state host.present for odl01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:19,393 [salt.state       ][INFO    ][12979] Host odl01.baremetal-mcp-ocata-odl-ha.local (10.167.4.111) already present
2017-09-14 21:21:19,394 [salt.state       ][INFO    ][12979] Completed state [odl01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.393478 duration_in_ms=2.22
2017-09-14 21:21:19,394 [salt.state       ][INFO    ][12979] Running state [odl01] at time 21:21:19.394036
2017-09-14 21:21:19,395 [salt.state       ][INFO    ][12979] Executing state host.present for odl01
2017-09-14 21:21:19,395 [salt.state       ][INFO    ][12979] Host odl01 (10.167.4.111) already present
2017-09-14 21:21:19,396 [salt.state       ][INFO    ][12979] Completed state [odl01] at time 21:21:19.395826 duration_in_ms=1.79
2017-09-14 21:21:19,396 [salt.state       ][INFO    ][12979] Running state [mas01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.396339
2017-09-14 21:21:19,397 [salt.state       ][INFO    ][12979] Executing state host.present for mas01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:19,398 [salt.state       ][INFO    ][12979] Host mas01.baremetal-mcp-ocata-odl-ha.local (10.167.4.3) already present
2017-09-14 21:21:19,398 [salt.state       ][INFO    ][12979] Completed state [mas01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.398082 duration_in_ms=1.743
2017-09-14 21:21:19,399 [salt.state       ][INFO    ][12979] Running state [mas01] at time 21:21:19.398619
2017-09-14 21:21:19,399 [salt.state       ][INFO    ][12979] Executing state host.present for mas01
2017-09-14 21:21:19,400 [salt.state       ][INFO    ][12979] Host mas01 (10.167.4.3) already present
2017-09-14 21:21:19,400 [salt.state       ][INFO    ][12979] Completed state [mas01] at time 21:21:19.400347 duration_in_ms=1.729
2017-09-14 21:21:19,401 [salt.state       ][INFO    ][12979] Running state [ctl02.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.400849
2017-09-14 21:21:19,401 [salt.state       ][INFO    ][12979] Executing state host.present for ctl02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:19,402 [salt.state       ][INFO    ][12979] Host ctl02.baremetal-mcp-ocata-odl-ha.local (10.167.4.12) already present
2017-09-14 21:21:19,403 [salt.state       ][INFO    ][12979] Completed state [ctl02.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.402617 duration_in_ms=1.768
2017-09-14 21:21:19,403 [salt.state       ][INFO    ][12979] Running state [ctl02] at time 21:21:19.403124
2017-09-14 21:21:19,404 [salt.state       ][INFO    ][12979] Executing state host.present for ctl02
2017-09-14 21:21:19,404 [salt.state       ][INFO    ][12979] Host ctl02 (10.167.4.12) already present
2017-09-14 21:21:19,405 [salt.state       ][INFO    ][12979] Completed state [ctl02] at time 21:21:19.404871 duration_in_ms=1.746
2017-09-14 21:21:19,405 [salt.state       ][INFO    ][12979] Running state [ctl03.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.405369
2017-09-14 21:21:19,406 [salt.state       ][INFO    ][12979] Executing state host.present for ctl03.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:19,407 [salt.state       ][INFO    ][12979] Host ctl03.baremetal-mcp-ocata-odl-ha.local (10.167.4.13) already present
2017-09-14 21:21:19,407 [salt.state       ][INFO    ][12979] Completed state [ctl03.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.407086 duration_in_ms=1.717
2017-09-14 21:21:19,408 [salt.state       ][INFO    ][12979] Running state [ctl03] at time 21:21:19.407565
2017-09-14 21:21:19,408 [salt.state       ][INFO    ][12979] Executing state host.present for ctl03
2017-09-14 21:21:19,409 [salt.state       ][INFO    ][12979] Host ctl03 (10.167.4.13) already present
2017-09-14 21:21:19,409 [salt.state       ][INFO    ][12979] Completed state [ctl03] at time 21:21:19.409199 duration_in_ms=1.635
2017-09-14 21:21:19,410 [salt.state       ][INFO    ][12979] Running state [ctl01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.409690
2017-09-14 21:21:19,410 [salt.state       ][INFO    ][12979] Executing state host.present for ctl01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:19,411 [salt.state       ][INFO    ][12979] Host ctl01.baremetal-mcp-ocata-odl-ha.local (10.167.4.11) already present
2017-09-14 21:21:19,411 [salt.state       ][INFO    ][12979] Completed state [ctl01.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.411368 duration_in_ms=1.677
2017-09-14 21:21:19,412 [salt.state       ][INFO    ][12979] Running state [ctl01] at time 21:21:19.411850
2017-09-14 21:21:19,412 [salt.state       ][INFO    ][12979] Executing state host.present for ctl01
2017-09-14 21:21:19,413 [salt.state       ][INFO    ][12979] Host ctl01 (10.167.4.11) already present
2017-09-14 21:21:19,414 [salt.state       ][INFO    ][12979] Completed state [ctl01] at time 21:21:19.413509 duration_in_ms=1.659
2017-09-14 21:21:19,414 [salt.state       ][INFO    ][12979] Running state [ctl] at time 21:21:19.414000
2017-09-14 21:21:19,414 [salt.state       ][INFO    ][12979] Executing state host.present for ctl
2017-09-14 21:21:19,415 [salt.state       ][INFO    ][12979] Host ctl (10.167.4.10) already present
2017-09-14 21:21:19,416 [salt.state       ][INFO    ][12979] Completed state [ctl] at time 21:21:19.415692 duration_in_ms=1.692
2017-09-14 21:21:19,416 [salt.state       ][INFO    ][12979] Running state [ctl.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.416161
2017-09-14 21:21:19,417 [salt.state       ][INFO    ][12979] Executing state host.present for ctl.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:21:19,417 [salt.state       ][INFO    ][12979] Host ctl.baremetal-mcp-ocata-odl-ha.local (10.167.4.10) already present
2017-09-14 21:21:19,418 [salt.state       ][INFO    ][12979] Completed state [ctl.baremetal-mcp-ocata-odl-ha.local] at time 21:21:19.417813 duration_in_ms=1.653
2017-09-14 21:21:19,418 [salt.state       ][INFO    ][12979] Running state [ens2] at time 21:21:19.418265
2017-09-14 21:21:19,419 [salt.state       ][INFO    ][12979] Executing state network.managed for ens2
2017-09-14 21:21:19,882 [salt.state       ][INFO    ][12979] Interface ens2 is up to date.
2017-09-14 21:21:19,883 [salt.state       ][INFO    ][12979] Completed state [ens2] at time 21:21:19.882794 duration_in_ms=464.529
2017-09-14 21:21:19,883 [salt.state       ][INFO    ][12979] Running state [ens3] at time 21:21:19.883155
2017-09-14 21:21:19,884 [salt.state       ][INFO    ][12979] Executing state network.managed for ens3
2017-09-14 21:21:20,280 [salt.state       ][INFO    ][12979] Interface ens3 is up to date.
2017-09-14 21:21:20,281 [salt.state       ][INFO    ][12979] Completed state [ens3] at time 21:21:20.280655 duration_in_ms=397.499
2017-09-14 21:21:20,281 [salt.state       ][INFO    ][12979] Running state [/etc/profile.d/proxy.sh] at time 21:21:20.281061
2017-09-14 21:21:20,281 [salt.state       ][INFO    ][12979] Executing state file.absent for /etc/profile.d/proxy.sh
2017-09-14 21:21:20,282 [salt.state       ][INFO    ][12979] File /etc/profile.d/proxy.sh is not present
2017-09-14 21:21:20,282 [salt.state       ][INFO    ][12979] Completed state [/etc/profile.d/proxy.sh] at time 21:21:20.282146 duration_in_ms=1.084
2017-09-14 21:21:20,282 [salt.state       ][INFO    ][12979] Running state [/etc/apt/apt.conf.d/95proxies] at time 21:21:20.282454
2017-09-14 21:21:20,283 [salt.state       ][INFO    ][12979] Executing state file.absent for /etc/apt/apt.conf.d/95proxies
2017-09-14 21:21:20,283 [salt.state       ][INFO    ][12979] File /etc/apt/apt.conf.d/95proxies is not present
2017-09-14 21:21:20,283 [salt.state       ][INFO    ][12979] Completed state [/etc/apt/apt.conf.d/95proxies] at time 21:21:20.283406 duration_in_ms=0.952
2017-09-14 21:21:20,284 [salt.state       ][INFO    ][12979] Running state [ntp] at time 21:21:20.283716
2017-09-14 21:21:20,284 [salt.state       ][INFO    ][12979] Executing state pkg.installed for ntp
2017-09-14 21:21:20,288 [salt.state       ][INFO    ][12979] Package ntp is already installed
2017-09-14 21:21:20,288 [salt.state       ][INFO    ][12979] Completed state [ntp] at time 21:21:20.287821 duration_in_ms=4.105
2017-09-14 21:21:20,289 [salt.state       ][INFO    ][12979] Running state [/etc/ntp.conf] at time 21:21:20.288860
2017-09-14 21:21:20,289 [salt.state       ][INFO    ][12979] Executing state file.managed for /etc/ntp.conf
2017-09-14 21:21:20,309 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'ntp/files/ntp.conf'
2017-09-14 21:21:20,343 [salt.fileclient  ][INFO    ][12979] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-09-14 21:21:20,350 [salt.state       ][INFO    ][12979] File /etc/ntp.conf is in the correct state
2017-09-14 21:21:20,350 [salt.state       ][INFO    ][12979] Completed state [/etc/ntp.conf] at time 21:21:20.350283 duration_in_ms=61.421
2017-09-14 21:21:20,351 [salt.state       ][INFO    ][12979] Running state [ntp] at time 21:21:20.351314
2017-09-14 21:21:20,352 [salt.state       ][INFO    ][12979] Executing state service.running for ntp
2017-09-14 21:21:20,352 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['systemctl', 'status', 'ntp.service', '-n', '0'] in directory '/root'
2017-09-14 21:21:20,364 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['systemctl', 'is-active', 'ntp.service'] in directory '/root'
2017-09-14 21:21:20,379 [salt.loaded.int.module.cmdmod][INFO    ][12979] Executing command ['systemctl', 'is-enabled', 'ntp.service'] in directory '/root'
2017-09-14 21:21:20,393 [salt.state       ][INFO    ][12979] The service ntp is already running
2017-09-14 21:21:20,394 [salt.state       ][INFO    ][12979] Completed state [ntp] at time 21:21:20.393875 duration_in_ms=42.558
2017-09-14 21:21:20,398 [salt.minion      ][INFO    ][12979] Returning information for job: 20170914212036914919
2017-09-14 21:22:49,120 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command ssh.set_auth_key with jid 20170914212249106239
2017-09-14 21:22:49,174 [salt.minion      ][INFO    ][19510] Starting a new job with PID 19510
2017-09-14 21:22:49,189 [salt.minion      ][INFO    ][19510] Returning information for job: 20170914212249106239
2017-09-14 21:33:32,045 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command test.ping with jid 20170914213332042470
2017-09-14 21:33:32,104 [salt.minion      ][INFO    ][20081] Starting a new job with PID 20081
2017-09-14 21:33:32,179 [salt.minion      ][INFO    ][20081] Returning information for job: 20170914213332042470
2017-09-14 21:34:54,990 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command state.sls with jid 20170914213454983967
2017-09-14 21:34:55,013 [salt.minion      ][INFO    ][20101] Starting a new job with PID 20101
2017-09-14 21:34:56,401 [salt.state       ][INFO    ][20101] Loading fresh modules for state activity
2017-09-14 21:34:56,443 [salt.fileclient  ][INFO    ][20101] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-09-14 21:34:56,466 [salt.fileclient  ][INFO    ][20101] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-09-14 21:34:56,503 [salt.fileclient  ][INFO    ][20101] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-14 21:34:56,832 [salt.state       ][INFO    ][20101] Running state [lsof] at time 21:34:56.831724
2017-09-14 21:34:56,832 [salt.state       ][INFO    ][20101] Executing state pkg.installed for lsof
2017-09-14 21:34:56,833 [salt.loaded.int.module.cmdmod][INFO    ][20101] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-14 21:34:57,393 [salt.state       ][INFO    ][20101] Package lsof is already installed
2017-09-14 21:34:57,394 [salt.state       ][INFO    ][20101] Completed state [lsof] at time 21:34:57.393749 duration_in_ms=562.026
2017-09-14 21:34:57,394 [salt.state       ][INFO    ][20101] Running state [keepalived] at time 21:34:57.394166
2017-09-14 21:34:57,395 [salt.state       ][INFO    ][20101] Executing state pkg.installed for keepalived
2017-09-14 21:34:57,415 [salt.loaded.int.module.cmdmod][INFO    ][20101] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-14 21:35:00,302 [salt.loaded.int.module.cmdmod][INFO    ][20101] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'keepalived'] in directory '/root'
2017-09-14 21:35:05,030 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914213505021568
2017-09-14 21:35:05,051 [salt.minion      ][INFO    ][20730] Starting a new job with PID 20730
2017-09-14 21:35:05,073 [salt.minion      ][INFO    ][20730] Returning information for job: 20170914213505021568
2017-09-14 21:35:11,654 [salt.loaded.int.module.cmdmod][INFO    ][20101] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-14 21:35:11,689 [salt.state       ][INFO    ][20101] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4'
'keepalived' changed from 'absent' to '1:1.2.19-1ubuntu0.2'
'ipvsadm' changed from 'absent' to '1:1.28-3'
'libsensors4' changed from 'absent' to '1:3.4.0-2'

2017-09-14 21:35:11,700 [salt.state       ][INFO    ][20101] Loading fresh modules for state activity
2017-09-14 21:35:11,716 [salt.state       ][INFO    ][20101] Completed state [keepalived] at time 21:35:11.715492 duration_in_ms=14321.325
2017-09-14 21:35:11,718 [salt.state       ][INFO    ][20101] Running state [/etc/keepalived/keepalived.conf] at time 21:35:11.718208
2017-09-14 21:35:11,719 [salt.state       ][INFO    ][20101] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-09-14 21:35:11,749 [salt.fileclient  ][INFO    ][20101] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-09-14 21:35:11,807 [salt.fileclient  ][INFO    ][20101] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-14 21:35:11,823 [salt.state       ][INFO    ][20101] File changed:
New file
2017-09-14 21:35:11,824 [salt.state       ][INFO    ][20101] Completed state [/etc/keepalived/keepalived.conf] at time 21:35:11.823482 duration_in_ms=105.273
2017-09-14 21:35:11,953 [salt.state       ][INFO    ][20101] Running state [keepalived] at time 21:35:11.953031
2017-09-14 21:35:11,954 [salt.state       ][INFO    ][20101] Executing state service.running for keepalived
2017-09-14 21:35:11,956 [salt.loaded.int.module.cmdmod][INFO    ][20101] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-09-14 21:35:11,970 [salt.loaded.int.module.cmdmod][INFO    ][20101] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-09-14 21:35:11,979 [salt.loaded.int.module.cmdmod][INFO    ][20101] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-14 21:35:11,987 [salt.loaded.int.module.cmdmod][INFO    ][20101] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-14 21:35:11,995 [salt.loaded.int.module.cmdmod][INFO    ][20101] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-09-14 21:35:12,078 [salt.loaded.int.module.cmdmod][INFO    ][20101] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-09-14 21:35:12,092 [salt.loaded.int.module.cmdmod][INFO    ][20101] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-14 21:35:12,106 [salt.loaded.int.module.cmdmod][INFO    ][20101] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-14 21:35:12,118 [salt.state       ][INFO    ][20101] {'keepalived': True}
2017-09-14 21:35:12,118 [salt.state       ][INFO    ][20101] Completed state [keepalived] at time 21:35:12.118330 duration_in_ms=165.298
2017-09-14 21:35:12,120 [salt.minion      ][INFO    ][20101] Returning information for job: 20170914213454983967
2017-09-14 21:37:50,879 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command pillar.get with jid 20170914213750873401
2017-09-14 21:37:50,911 [salt.minion      ][INFO    ][21157] Starting a new job with PID 21157
2017-09-14 21:37:50,918 [salt.minion      ][INFO    ][21157] Returning information for job: 20170914213750873401
2017-09-14 21:37:51,892 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command state.sls with jid 20170914213751885807
2017-09-14 21:37:51,916 [salt.minion      ][INFO    ][21162] Starting a new job with PID 21162
2017-09-14 21:37:53,417 [salt.state       ][INFO    ][21162] Loading fresh modules for state activity
2017-09-14 21:37:53,467 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/init.sls'
2017-09-14 21:37:53,499 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/init.sls'
2017-09-14 21:37:53,519 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/service.sls'
2017-09-14 21:37:53,562 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/map.jinja'
2017-09-14 21:37:53,583 [py.warnings      ][WARNING ][21162] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2017-09-14 21:37:53,585 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command 'cat /var/lib/rabbitmq/.erlang.cookie' in directory '/root'
2017-09-14 21:37:53,595 [salt.loaded.int.module.cmdmod][ERROR   ][21162] Command 'cat /var/lib/rabbitmq/.erlang.cookie' failed with return code: 1
2017-09-14 21:37:53,596 [salt.loaded.int.module.cmdmod][ERROR   ][21162] output: cat: /var/lib/rabbitmq/.erlang.cookie: No such file or directory
2017-09-14 21:37:53,641 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/ssl.sls'
2017-09-14 21:37:53,675 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/map.jinja'
2017-09-14 21:37:53,708 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/plugin.sls'
2017-09-14 21:37:53,731 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/map.jinja'
2017-09-14 21:37:53,767 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/vhost.sls'
2017-09-14 21:37:53,797 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/map.jinja'
2017-09-14 21:37:53,840 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/user.sls'
2017-09-14 21:37:53,864 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/map.jinja'
2017-09-14 21:37:53,896 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/cluster.sls'
2017-09-14 21:37:53,920 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/map.jinja'
2017-09-14 21:37:53,951 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'linux/network/host.sls'
2017-09-14 21:37:53,996 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:37:54,349 [salt.state       ][INFO    ][21162] Running state [rabbitmq-server] at time 21:37:54.348723
2017-09-14 21:37:54,349 [salt.state       ][INFO    ][21162] Executing state pkg.installed for rabbitmq-server
2017-09-14 21:37:54,350 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-14 21:37:54,900 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-14 21:37:57,525 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'rabbitmq-server'] in directory '/root'
2017-09-14 21:38:01,979 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914213801972377
2017-09-14 21:38:02,002 [salt.minion      ][INFO    ][21726] Starting a new job with PID 21726
2017-09-14 21:38:02,018 [salt.minion      ][INFO    ][21726] Returning information for job: 20170914213801972377
2017-09-14 21:38:12,184 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914213812177135
2017-09-14 21:38:12,208 [salt.minion      ][INFO    ][21731] Starting a new job with PID 21731
2017-09-14 21:38:12,224 [salt.minion      ][INFO    ][21731] Returning information for job: 20170914213812177135
2017-09-14 21:38:22,390 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914213822383776
2017-09-14 21:38:22,414 [salt.minion      ][INFO    ][21741] Starting a new job with PID 21741
2017-09-14 21:38:22,432 [salt.minion      ][INFO    ][21741] Returning information for job: 20170914213822383776
2017-09-14 21:38:32,603 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914213832594657
2017-09-14 21:38:32,628 [salt.minion      ][INFO    ][21746] Starting a new job with PID 21746
2017-09-14 21:38:32,645 [salt.minion      ][INFO    ][21746] Returning information for job: 20170914213832594657
2017-09-14 21:38:42,813 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914213842805207
2017-09-14 21:38:42,838 [salt.minion      ][INFO    ][21759] Starting a new job with PID 21759
2017-09-14 21:38:42,849 [salt.minion      ][INFO    ][21759] Returning information for job: 20170914213842805207
2017-09-14 21:38:53,017 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914213853009181
2017-09-14 21:38:53,041 [salt.minion      ][INFO    ][21852] Starting a new job with PID 21852
2017-09-14 21:38:53,057 [salt.minion      ][INFO    ][21852] Returning information for job: 20170914213853009181
2017-09-14 21:39:03,229 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914213903220094
2017-09-14 21:39:03,252 [salt.minion      ][INFO    ][21982] Starting a new job with PID 21982
2017-09-14 21:39:03,269 [salt.minion      ][INFO    ][21982] Returning information for job: 20170914213903220094
2017-09-14 21:39:13,275 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914213913269592
2017-09-14 21:39:13,300 [salt.minion      ][INFO    ][22102] Starting a new job with PID 22102
2017-09-14 21:39:13,318 [salt.minion      ][INFO    ][22102] Returning information for job: 20170914213913269592
2017-09-14 21:39:23,489 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914213923483305
2017-09-14 21:39:23,510 [salt.minion      ][INFO    ][22277] Starting a new job with PID 22277
2017-09-14 21:39:23,526 [salt.minion      ][INFO    ][22277] Returning information for job: 20170914213923483305
2017-09-14 21:39:33,699 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914213933694285
2017-09-14 21:39:33,712 [salt.minion      ][INFO    ][22311] Starting a new job with PID 22311
2017-09-14 21:39:33,720 [salt.minion      ][INFO    ][22311] Returning information for job: 20170914213933694285
2017-09-14 21:39:43,898 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914213943892319
2017-09-14 21:39:43,912 [salt.minion      ][INFO    ][22316] Starting a new job with PID 22316
2017-09-14 21:39:43,921 [salt.minion      ][INFO    ][22316] Returning information for job: 20170914213943892319
2017-09-14 21:39:51,750 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-14 21:39:51,787 [salt.state       ][INFO    ][21162] Made the following changes:
'libltdl7' changed from 'absent' to '2.4.6-0.1'
'erlang-asn1' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-inets' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-snmp' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-percept' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'socat' changed from 'absent' to '1.7.3.1-1'
'erlang-abi-17.0' changed from 'absent' to '1'
'erlang-edoc' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-ic' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-os-mon' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'libodbc1' changed from 'absent' to '2.3.1-4.1'
'erlang-syntax-tools' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-sd-notify' changed from 'absent' to '1.0-1~u16.04+mcp1'
'erlang-ssl' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-ssh' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'libsctp1' changed from 'absent' to '1.0.16+dfsg-3'
'erlang-runtime-tools' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-eunit' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-tools' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-odbc' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-parsetools' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-public-key' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-diameter' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-corba' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-xmerl' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-nox' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-eldap' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-webtool' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-mnesia' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'rabbitmq-server' changed from 'absent' to '3.6.5-1~u16.04+mcp2'
'erlang-base-hipe' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-crypto' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-erl-docgen' changed from 'absent' to '1:18.3.4+dfsg-1~u16.04+mcp1'
'erlang-abi-15.b' changed from 'absent' to '1'

2017-09-14 21:39:51,803 [salt.state       ][INFO    ][21162] Loading fresh modules for state activity
2017-09-14 21:39:51,823 [salt.state       ][INFO    ][21162] Completed state [rabbitmq-server] at time 21:39:51.823338 duration_in_ms=117474.614
2017-09-14 21:39:51,837 [salt.state       ][INFO    ][21162] Running state [gettext-base] at time 21:39:51.836622
2017-09-14 21:39:51,838 [salt.state       ][INFO    ][21162] Executing state pkg.installed for gettext-base
2017-09-14 21:39:52,135 [salt.state       ][INFO    ][21162] Package gettext-base is already installed
2017-09-14 21:39:52,135 [salt.state       ][INFO    ][21162] Completed state [gettext-base] at time 21:39:52.135187 duration_in_ms=298.564
2017-09-14 21:39:52,137 [salt.state       ][INFO    ][21162] Running state [/etc/rabbitmq/rabbitmq.config] at time 21:39:52.137025
2017-09-14 21:39:52,138 [salt.state       ][INFO    ][21162] Executing state file.managed for /etc/rabbitmq/rabbitmq.config
2017-09-14 21:39:52,163 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/files/rabbitmq.config'
2017-09-14 21:39:52,250 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/map.jinja'
2017-09-14 21:39:52,264 [salt.state       ][INFO    ][21162] File changed:
New file
2017-09-14 21:39:52,264 [salt.state       ][INFO    ][21162] Completed state [/etc/rabbitmq/rabbitmq.config] at time 21:39:52.264024 duration_in_ms=126.999
2017-09-14 21:39:52,265 [salt.state       ][INFO    ][21162] Running state [/etc/default/rabbitmq-server] at time 21:39:52.264669
2017-09-14 21:39:52,265 [salt.state       ][INFO    ][21162] Executing state file.managed for /etc/default/rabbitmq-server
2017-09-14 21:39:52,282 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/files/default'
2017-09-14 21:39:52,298 [salt.fileclient  ][INFO    ][21162] Fetching file from saltenv 'base', ** done ** 'rabbitmq/map.jinja'
2017-09-14 21:39:52,310 [salt.state       ][INFO    ][21162] File changed:
--- 
+++ 
@@ -1,3 +1,6 @@
+
+# Managed by Salt
+
 # This file is sourced by /etc/init.d/rabbitmq-server. Its primary
 # reason for existing is to allow adjustment of system limits for the
 # rabbitmq-server process.
@@ -6,12 +9,5 @@
 # to handle many simultaneous connections. Refer to the system
 # documentation for ulimit (in man bash) for more information.
 #
-# Default value is 1024, but it's absolutely unreasonable default in a
-# modern world, as rabbitmq consumes file descriptors for everything:
-# for client connections, for per-queue files, etc. Its documentation
-# recommends to allow for at least 65536 open file descriptors in
-# production. Values were chosen in
-# https://bugs.launchpad.net/fuel/+bug/1279594
-ulimit -H -n 105472 || true
-ulimit -S -n 102400 || true
-ulimit -H -n 105472 || true
+
+ulimit -n 8192
2017-09-14 21:39:52,311 [salt.state       ][INFO    ][21162] Completed state [/etc/default/rabbitmq-server] at time 21:39:52.311370 duration_in_ms=46.7
2017-09-14 21:39:52,313 [salt.state       ][INFO    ][21162] Running state [sleep 30] at time 21:39:52.312790
2017-09-14 21:39:52,313 [salt.state       ][INFO    ][21162] Executing state cmd.run for sleep 30
2017-09-14 21:39:52,314 [py.warnings      ][WARNING ][21162] /usr/lib/python2.7/dist-packages/salt/states/cmd.py:807: DeprecationWarning: The legacy user/group arguments are deprecated. Replace them with runas. These arguments will be removed in Salt Oxygen.

2017-09-14 21:39:52,314 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command 'sleep 30' as user 'root' in directory '/root'
2017-09-14 21:39:54,103 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914213954097972
2017-09-14 21:39:54,124 [salt.minion      ][INFO    ][22401] Starting a new job with PID 22401
2017-09-14 21:39:54,144 [salt.minion      ][INFO    ][22401] Returning information for job: 20170914213954097972
2017-09-14 21:40:04,326 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914214004321424
2017-09-14 21:40:04,347 [salt.minion      ][INFO    ][22406] Starting a new job with PID 22406
2017-09-14 21:40:04,366 [salt.minion      ][INFO    ][22406] Returning information for job: 20170914214004321424
2017-09-14 21:40:14,548 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914214014543516
2017-09-14 21:40:14,571 [salt.minion      ][INFO    ][22416] Starting a new job with PID 22416
2017-09-14 21:40:14,590 [salt.minion      ][INFO    ][22416] Returning information for job: 20170914214014543516
2017-09-14 21:40:22,366 [salt.state       ][INFO    ][21162] {'pid': 22397, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-09-14 21:40:22,367 [salt.state       ][INFO    ][21162] Completed state [sleep 30] at time 21:40:22.366813 duration_in_ms=30054.021
2017-09-14 21:40:22,369 [salt.state       ][INFO    ][21162] Running state [service rabbitmq-server stop] at time 21:40:22.368837
2017-09-14 21:40:22,370 [salt.state       ][INFO    ][21162] Executing state cmd.run for service rabbitmq-server stop
2017-09-14 21:40:22,371 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command 'service rabbitmq-server stop' in directory '/root'
2017-09-14 21:40:22,598 [salt.state       ][INFO    ][21162] {'pid': 22419, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-09-14 21:40:22,599 [salt.state       ][INFO    ][21162] Completed state [service rabbitmq-server stop] at time 21:40:22.599327 duration_in_ms=230.49
2017-09-14 21:40:22,600 [salt.state       ][INFO    ][21162] Running state [/var/lib/rabbitmq] at time 21:40:22.600062
2017-09-14 21:40:22,601 [salt.state       ][INFO    ][21162] Executing state file.directory for /var/lib/rabbitmq
2017-09-14 21:40:22,602 [salt.state       ][INFO    ][21162] Directory /var/lib/rabbitmq is in the correct state
2017-09-14 21:40:22,603 [salt.state       ][INFO    ][21162] Completed state [/var/lib/rabbitmq] at time 21:40:22.602534 duration_in_ms=2.473
2017-09-14 21:40:22,605 [salt.state       ][INFO    ][21162] Running state [/var/lib/rabbitmq/.erlang.cookie] at time 21:40:22.604865
2017-09-14 21:40:22,606 [salt.state       ][INFO    ][21162] Executing state file.managed for /var/lib/rabbitmq/.erlang.cookie
2017-09-14 21:40:22,614 [salt.state       ][INFO    ][21162] File changed:
--- 
+++ 
@@ -1 +1 @@
-GEFBJFYLWTMOATSGCXWY+opnfv_secret

2017-09-14 21:40:22,615 [salt.state       ][INFO    ][21162] Completed state [/var/lib/rabbitmq/.erlang.cookie] at time 21:40:22.614543 duration_in_ms=9.678
2017-09-14 21:40:22,616 [salt.state       ][INFO    ][21162] Running state [sleep 30] at time 21:40:22.615691
2017-09-14 21:40:22,616 [salt.state       ][INFO    ][21162] Executing state cmd.run for sleep 30
2017-09-14 21:40:22,617 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command 'sleep 30' as user 'root' in directory '/root'
2017-09-14 21:40:24,773 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914214024769384
2017-09-14 21:40:24,793 [salt.minion      ][INFO    ][22450] Starting a new job with PID 22450
2017-09-14 21:40:24,811 [salt.minion      ][INFO    ][22450] Returning information for job: 20170914214024769384
2017-09-14 21:40:34,995 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914214034992129
2017-09-14 21:40:35,019 [salt.minion      ][INFO    ][22455] Starting a new job with PID 22455
2017-09-14 21:40:35,038 [salt.minion      ][INFO    ][22455] Returning information for job: 20170914214034992129
2017-09-14 21:40:45,014 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914214045010726
2017-09-14 21:40:45,039 [salt.minion      ][INFO    ][22547] Starting a new job with PID 22547
2017-09-14 21:40:45,055 [salt.minion      ][INFO    ][22547] Returning information for job: 20170914214045010726
2017-09-14 21:40:52,672 [salt.state       ][INFO    ][21162] {'pid': 22446, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-09-14 21:40:52,673 [salt.state       ][INFO    ][21162] Completed state [sleep 30] at time 21:40:52.672718 duration_in_ms=30057.026
2017-09-14 21:40:52,677 [salt.state       ][INFO    ][21162] Running state [rabbitmq-server] at time 21:40:52.676545
2017-09-14 21:40:52,677 [salt.state       ][INFO    ][21162] Executing state service.running for rabbitmq-server
2017-09-14 21:40:52,678 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['systemctl', 'status', 'rabbitmq-server.service', '-n', '0'] in directory '/root'
2017-09-14 21:40:52,701 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['systemctl', 'is-active', 'rabbitmq-server.service'] in directory '/root'
2017-09-14 21:40:52,719 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['systemctl', 'is-enabled', 'rabbitmq-server.service'] in directory '/root'
2017-09-14 21:40:52,741 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['systemctl', 'is-enabled', 'rabbitmq-server.service'] in directory '/root'
2017-09-14 21:40:52,758 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'rabbitmq-server.service'] in directory '/root'
2017-09-14 21:40:55,038 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914214055033111
2017-09-14 21:40:55,058 [salt.minion      ][INFO    ][22764] Starting a new job with PID 22764
2017-09-14 21:40:55,075 [salt.minion      ][INFO    ][22764] Returning information for job: 20170914214055033111
2017-09-14 21:40:56,600 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['systemctl', 'is-active', 'rabbitmq-server.service'] in directory '/root'
2017-09-14 21:40:56,616 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['systemctl', 'is-enabled', 'rabbitmq-server.service'] in directory '/root'
2017-09-14 21:40:56,632 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['systemctl', 'is-enabled', 'rabbitmq-server.service'] in directory '/root'
2017-09-14 21:40:56,650 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['systemctl', 'is-enabled', 'rabbitmq-server.service'] in directory '/root'
2017-09-14 21:40:56,667 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'rabbitmq-server.service'] in directory '/root'
2017-09-14 21:40:57,028 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['systemctl', 'is-enabled', 'rabbitmq-server.service'] in directory '/root'
2017-09-14 21:40:57,047 [salt.state       ][INFO    ][21162] {'rabbitmq-server': True}
2017-09-14 21:40:57,048 [salt.state       ][INFO    ][21162] Completed state [rabbitmq-server] at time 21:40:57.047683 duration_in_ms=4371.137
2017-09-14 21:40:57,051 [salt.state       ][INFO    ][21162] Running state [amqp_client] at time 21:40:57.051346
2017-09-14 21:40:57,052 [salt.state       ][INFO    ][21162] Executing state rabbitmq_plugin.enabled for amqp_client
2017-09-14 21:40:57,053 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['/usr/sbin/rabbitmq-plugins', 'list', '-m', '-e'] as user 'root' in directory '/root'
2017-09-14 21:40:57,943 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['/usr/sbin/rabbitmq-plugins', 'enable', 'amqp_client'] as user 'root' in directory '/root'
2017-09-14 21:40:59,032 [salt.state       ][INFO    ][21162] {'new': 'amqp_client', 'old': ''}
2017-09-14 21:40:59,033 [salt.state       ][INFO    ][21162] Completed state [amqp_client] at time 21:40:59.032639 duration_in_ms=1981.292
2017-09-14 21:40:59,034 [salt.state       ][INFO    ][21162] Running state [rabbitmq_management] at time 21:40:59.033721
2017-09-14 21:40:59,034 [salt.state       ][INFO    ][21162] Executing state rabbitmq_plugin.enabled for rabbitmq_management
2017-09-14 21:40:59,035 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['/usr/sbin/rabbitmq-plugins', 'list', '-m', '-e'] as user 'root' in directory '/root'
2017-09-14 21:40:59,619 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['/usr/sbin/rabbitmq-plugins', 'enable', 'rabbitmq_management'] as user 'root' in directory '/root'
2017-09-14 21:41:02,198 [salt.state       ][INFO    ][21162] {'new': 'rabbitmq_management', 'old': ''}
2017-09-14 21:41:02,198 [salt.state       ][INFO    ][21162] Completed state [rabbitmq_management] at time 21:41:02.198077 duration_in_ms=3164.354
2017-09-14 21:41:02,201 [salt.state       ][INFO    ][21162] Running state [/openstack] at time 21:41:02.200584
2017-09-14 21:41:02,201 [salt.state       ][INFO    ][21162] Executing state rabbitmq_vhost.present for /openstack
2017-09-14 21:41:02,202 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command 'rabbitmqctl list_vhosts -q' as user 'root' in directory '/root'
2017-09-14 21:41:03,263 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'add_vhost', '/openstack'] as user 'root' in directory '/root'
2017-09-14 21:41:04,530 [salt.state       ][INFO    ][21162] {'new': '/openstack', 'old': ''}
2017-09-14 21:41:04,530 [salt.state       ][INFO    ][21162] Completed state [/openstack] at time 21:41:04.530289 duration_in_ms=2329.704
2017-09-14 21:41:04,534 [salt.state       ][INFO    ][21162] Running state [openstack] at time 21:41:04.534181
2017-09-14 21:41:04,535 [salt.state       ][INFO    ][21162] Executing state rabbitmq_user.present for openstack
2017-09-14 21:41:04,537 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'list_users'] as user 'root' in directory '/root'
2017-09-14 21:41:05,064 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914214105051947
2017-09-14 21:41:05,081 [salt.minion      ][INFO    ][23880] Starting a new job with PID 23880
2017-09-14 21:41:05,091 [salt.minion      ][INFO    ][23880] Returning information for job: 20170914214105051947
2017-09-14 21:41:06,369 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'list_user_permissions', 'openstack'] as user 'root' in directory '/root'
2017-09-14 21:41:07,356 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'set_permissions', '-p', '/openstack', 'openstack', '.*', '.*', '.*'] as user 'root' in directory '/root'
2017-09-14 21:41:08,345 [salt.state       ][INFO    ][21162] Made the following changes:
'perms' changed from 'absent' to '{'/openstack': ['.*', '.*', '.*']}'
'user' changed from 'absent' to 'openstack'

2017-09-14 21:41:08,346 [salt.state       ][INFO    ][21162] Completed state [openstack] at time 21:41:08.345492 duration_in_ms=3811.31
2017-09-14 21:41:08,349 [salt.state       ][INFO    ][21162] Running state [Ceilometer] at time 21:41:08.348585
2017-09-14 21:41:08,349 [salt.state       ][INFO    ][21162] Executing state rabbitmq_policy.present for Ceilometer
2017-09-14 21:41:08,351 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'list_policies', '-p', '/openstack'] as user 'root' in directory '/root'
2017-09-14 21:41:09,324 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'set_policy', '-p', '/openstack', '--priority', 10, 'Ceilometer', '^metering.sample$', '{"ha-mode": "all", "message-ttl": 10800000}'] as user 'root' in directory '/root'
2017-09-14 21:41:10,442 [salt.state       ][INFO    ][21162] {'new': 'Ceilometer', 'old': {}}
2017-09-14 21:41:10,443 [salt.state       ][INFO    ][21162] Completed state [Ceilometer] at time 21:41:10.442472 duration_in_ms=2093.885
2017-09-14 21:41:10,444 [salt.state       ][INFO    ][21162] Running state [HA] at time 21:41:10.444116
2017-09-14 21:41:10,445 [salt.state       ][INFO    ][21162] Executing state rabbitmq_policy.present for HA
2017-09-14 21:41:10,446 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'list_policies', '-p', '/openstack'] as user 'root' in directory '/root'
2017-09-14 21:41:11,453 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'set_policy', '-p', '/openstack', 'HA', '^(?!amq\\.).*', '{"ha-mode": "all", "message-ttl": 120000}'] as user 'root' in directory '/root'
2017-09-14 21:41:12,333 [salt.state       ][INFO    ][21162] {'new': 'HA', 'old': {}}
2017-09-14 21:41:12,333 [salt.state       ][INFO    ][21162] Completed state [HA] at time 21:41:12.333350 duration_in_ms=1889.233
2017-09-14 21:41:12,334 [salt.state       ][INFO    ][21162] Running state [guest] at time 21:41:12.333881
2017-09-14 21:41:12,334 [salt.state       ][INFO    ][21162] Executing state rabbitmq_user.present for guest
2017-09-14 21:41:12,336 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'list_users'] as user 'root' in directory '/root'
2017-09-14 21:41:13,182 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'status'] as user 'root' in directory '/root'
2017-09-14 21:41:15,295 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914214115281257
2017-09-14 21:41:15,313 [salt.minion      ][INFO    ][25570] Starting a new job with PID 25570
2017-09-14 21:41:15,331 [salt.minion      ][INFO    ][25570] Returning information for job: 20170914214115281257
2017-09-14 21:41:15,778 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'list_user_permissions', 'guest'] as user 'root' in directory '/root'
2017-09-14 21:41:16,779 [salt.state       ][INFO    ][21162] Made the following changes:
'password' changed from 'absent' to 'Set password.'

2017-09-14 21:41:16,780 [salt.state       ][INFO    ][21162] Completed state [guest] at time 21:41:16.779735 duration_in_ms=4445.853
2017-09-14 21:41:16,781 [salt.state       ][INFO    ][21162] Running state [HA] at time 21:41:16.781110
2017-09-14 21:41:16,782 [salt.state       ][INFO    ][21162] Executing state rabbitmq_policy.present for HA
2017-09-14 21:41:16,783 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'list_policies', '-p', '/'] as user 'root' in directory '/root'
2017-09-14 21:41:17,579 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'set_policy', '-p', '/', 'HA', '^(?!amq\\.).*', '{"ha-mode": "all", "message-ttl": 120000}'] as user 'root' in directory '/root'
2017-09-14 21:41:18,567 [salt.state       ][INFO    ][21162] {'new': 'HA', 'old': {}}
2017-09-14 21:41:18,568 [salt.state       ][INFO    ][21162] Completed state [HA] at time 21:41:18.568007 duration_in_ms=1786.896
2017-09-14 21:41:18,569 [salt.state       ][INFO    ][21162] Running state [admin] at time 21:41:18.569384
2017-09-14 21:41:18,570 [salt.state       ][INFO    ][21162] Executing state rabbitmq_user.present for admin
2017-09-14 21:41:18,571 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'list_users'] as user 'root' in directory '/root'
2017-09-14 21:41:20,274 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'list_users'] as user 'root' in directory '/root'
2017-09-14 21:41:21,022 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'set_user_tags', 'admin', 'administrator'] as user 'root' in directory '/root'
2017-09-14 21:41:21,771 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'list_user_permissions', 'admin'] as user 'root' in directory '/root'
2017-09-14 21:41:22,573 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'set_permissions', '-p', '/openstack', 'admin', '.*', '.*', '.*'] as user 'root' in directory '/root'
2017-09-14 21:41:23,484 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command ['rabbitmqctl', 'set_permissions', '-p', '/', 'admin', '.*', '.*', '.*'] as user 'root' in directory '/root'
2017-09-14 21:41:24,338 [salt.state       ][INFO    ][21162] Made the following changes:
'perms' changed from 'absent' to '{'/openstack': ['.*', '.*', '.*'], '/': ['.*', '.*', '.*']}'
'user' changed from 'absent' to 'admin'
'tags' changed from '['']' to '['administrator']'

2017-09-14 21:41:24,339 [salt.state       ][INFO    ][21162] Completed state [admin] at time 21:41:24.338881 duration_in_ms=5769.495
2017-09-14 21:41:24,340 [salt.state       ][INFO    ][21162] Running state [mdb02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.340265
2017-09-14 21:41:24,341 [salt.state       ][INFO    ][21162] Executing state host.present for mdb02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,342 [salt.state       ][INFO    ][21162] Host mdb02.baremetal-mcp-ocata-odl-ha.local (10.167.4.77) already present
2017-09-14 21:41:24,342 [salt.state       ][INFO    ][21162] Completed state [mdb02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.342165 duration_in_ms=1.898
2017-09-14 21:41:24,343 [salt.state       ][INFO    ][21162] Running state [mdb02] at time 21:41:24.342562
2017-09-14 21:41:24,343 [salt.state       ][INFO    ][21162] Executing state host.present for mdb02
2017-09-14 21:41:24,344 [salt.state       ][INFO    ][21162] Host mdb02 (10.167.4.77) already present
2017-09-14 21:41:24,344 [salt.state       ][INFO    ][21162] Completed state [mdb02] at time 21:41:24.344097 duration_in_ms=1.534
2017-09-14 21:41:24,345 [salt.state       ][INFO    ][21162] Running state [mdb03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.344481
2017-09-14 21:41:24,345 [salt.state       ][INFO    ][21162] Executing state host.present for mdb03.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,346 [salt.state       ][INFO    ][21162] Host mdb03.baremetal-mcp-ocata-odl-ha.local (10.167.4.78) already present
2017-09-14 21:41:24,346 [salt.state       ][INFO    ][21162] Completed state [mdb03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.345911 duration_in_ms=1.43
2017-09-14 21:41:24,346 [salt.state       ][INFO    ][21162] Running state [mdb03] at time 21:41:24.346288
2017-09-14 21:41:24,349 [salt.state       ][INFO    ][21162] Executing state host.present for mdb03
2017-09-14 21:41:24,350 [salt.state       ][INFO    ][21162] Host mdb03 (10.167.4.78) already present
2017-09-14 21:41:24,351 [salt.state       ][INFO    ][21162] Completed state [mdb03] at time 21:41:24.350609 duration_in_ms=4.321
2017-09-14 21:41:24,351 [salt.state       ][INFO    ][21162] Running state [mdb01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.351302
2017-09-14 21:41:24,352 [salt.state       ][INFO    ][21162] Executing state host.present for mdb01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,353 [salt.state       ][INFO    ][21162] Host mdb01.baremetal-mcp-ocata-odl-ha.local (10.167.4.76) already present
2017-09-14 21:41:24,354 [salt.state       ][INFO    ][21162] Completed state [mdb01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.353621 duration_in_ms=2.319
2017-09-14 21:41:24,354 [salt.state       ][INFO    ][21162] Running state [mdb01] at time 21:41:24.354259
2017-09-14 21:41:24,355 [salt.state       ][INFO    ][21162] Executing state host.present for mdb01
2017-09-14 21:41:24,356 [salt.state       ][INFO    ][21162] Host mdb01 (10.167.4.76) already present
2017-09-14 21:41:24,357 [salt.state       ][INFO    ][21162] Completed state [mdb01] at time 21:41:24.356578 duration_in_ms=2.319
2017-09-14 21:41:24,357 [salt.state       ][INFO    ][21162] Running state [mdb] at time 21:41:24.357216
2017-09-14 21:41:24,358 [salt.state       ][INFO    ][21162] Executing state host.present for mdb
2017-09-14 21:41:24,359 [salt.state       ][INFO    ][21162] Host mdb (10.167.4.75) already present
2017-09-14 21:41:24,360 [salt.state       ][INFO    ][21162] Completed state [mdb] at time 21:41:24.359489 duration_in_ms=2.273
2017-09-14 21:41:24,360 [salt.state       ][INFO    ][21162] Running state [mdb.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.360184
2017-09-14 21:41:24,361 [salt.state       ][INFO    ][21162] Executing state host.present for mdb.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,362 [salt.state       ][INFO    ][21162] Host mdb.baremetal-mcp-ocata-odl-ha.local (10.167.4.75) already present
2017-09-14 21:41:24,363 [salt.state       ][INFO    ][21162] Completed state [mdb.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.362434 duration_in_ms=2.25
2017-09-14 21:41:24,363 [salt.state       ][INFO    ][21162] Running state [cfg01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.363129
2017-09-14 21:41:24,364 [salt.state       ][INFO    ][21162] Executing state host.present for cfg01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,365 [salt.state       ][INFO    ][21162] Host cfg01.baremetal-mcp-ocata-odl-ha.local (10.167.4.100) already present
2017-09-14 21:41:24,365 [salt.state       ][INFO    ][21162] Completed state [cfg01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.365300 duration_in_ms=2.171
2017-09-14 21:41:24,366 [salt.state       ][INFO    ][21162] Running state [cfg01] at time 21:41:24.365914
2017-09-14 21:41:24,367 [salt.state       ][INFO    ][21162] Executing state host.present for cfg01
2017-09-14 21:41:24,368 [salt.state       ][INFO    ][21162] Host cfg01 (10.167.4.100) already present
2017-09-14 21:41:24,368 [salt.state       ][INFO    ][21162] Completed state [cfg01] at time 21:41:24.368072 duration_in_ms=2.158
2017-09-14 21:41:24,369 [salt.state       ][INFO    ][21162] Running state [prx01] at time 21:41:24.368688
2017-09-14 21:41:24,369 [salt.state       ][INFO    ][21162] Executing state host.present for prx01
2017-09-14 21:41:24,371 [salt.state       ][INFO    ][21162] Host prx01 (10.167.4.81) already present
2017-09-14 21:41:24,372 [salt.state       ][INFO    ][21162] Completed state [prx01] at time 21:41:24.371705 duration_in_ms=3.017
2017-09-14 21:41:24,372 [salt.state       ][INFO    ][21162] Running state [prx01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.372331
2017-09-14 21:41:24,373 [salt.state       ][INFO    ][21162] Executing state host.present for prx01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,374 [salt.state       ][INFO    ][21162] Host prx01.baremetal-mcp-ocata-odl-ha.local (10.167.4.81) already present
2017-09-14 21:41:24,375 [salt.state       ][INFO    ][21162] Completed state [prx01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.374477 duration_in_ms=2.145
2017-09-14 21:41:24,375 [salt.state       ][INFO    ][21162] Running state [kvm01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.375122
2017-09-14 21:41:24,376 [salt.state       ][INFO    ][21162] Executing state host.present for kvm01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,377 [salt.state       ][INFO    ][21162] Host kvm01.baremetal-mcp-ocata-odl-ha.local (10.167.4.141) already present
2017-09-14 21:41:24,377 [salt.state       ][INFO    ][21162] Completed state [kvm01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.377218 duration_in_ms=2.095
2017-09-14 21:41:24,378 [salt.state       ][INFO    ][21162] Running state [kvm01] at time 21:41:24.377802
2017-09-14 21:41:24,378 [salt.state       ][INFO    ][21162] Executing state host.present for kvm01
2017-09-14 21:41:24,379 [salt.state       ][INFO    ][21162] Host kvm01 (10.167.4.141) already present
2017-09-14 21:41:24,380 [salt.state       ][INFO    ][21162] Completed state [kvm01] at time 21:41:24.379883 duration_in_ms=2.08
2017-09-14 21:41:24,381 [salt.state       ][INFO    ][21162] Running state [kvm03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.380480
2017-09-14 21:41:24,381 [salt.state       ][INFO    ][21162] Executing state host.present for kvm03.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,382 [salt.state       ][INFO    ][21162] Host kvm03.baremetal-mcp-ocata-odl-ha.local (10.167.4.143) already present
2017-09-14 21:41:24,383 [salt.state       ][INFO    ][21162] Completed state [kvm03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.382547 duration_in_ms=2.067
2017-09-14 21:41:24,383 [salt.state       ][INFO    ][21162] Running state [kvm03] at time 21:41:24.383174
2017-09-14 21:41:24,384 [salt.state       ][INFO    ][21162] Executing state host.present for kvm03
2017-09-14 21:41:24,385 [salt.state       ][INFO    ][21162] Host kvm03 (10.167.4.143) already present
2017-09-14 21:41:24,385 [salt.state       ][INFO    ][21162] Completed state [kvm03] at time 21:41:24.385232 duration_in_ms=2.058
2017-09-14 21:41:24,386 [salt.state       ][INFO    ][21162] Running state [kvm02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.385830
2017-09-14 21:41:24,386 [salt.state       ][INFO    ][21162] Executing state host.present for kvm02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,387 [salt.state       ][INFO    ][21162] Host kvm02.baremetal-mcp-ocata-odl-ha.local (10.167.4.142) already present
2017-09-14 21:41:24,388 [salt.state       ][INFO    ][21162] Completed state [kvm02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.387953 duration_in_ms=2.122
2017-09-14 21:41:24,389 [salt.state       ][INFO    ][21162] Running state [kvm02] at time 21:41:24.388535
2017-09-14 21:41:24,389 [salt.state       ][INFO    ][21162] Executing state host.present for kvm02
2017-09-14 21:41:24,390 [salt.state       ][INFO    ][21162] Host kvm02 (10.167.4.142) already present
2017-09-14 21:41:24,391 [salt.state       ][INFO    ][21162] Completed state [kvm02] at time 21:41:24.390502 duration_in_ms=1.967
2017-09-14 21:41:24,391 [salt.state       ][INFO    ][21162] Running state [dbs] at time 21:41:24.391104
2017-09-14 21:41:24,392 [salt.state       ][INFO    ][21162] Executing state host.present for dbs
2017-09-14 21:41:24,393 [salt.state       ][INFO    ][21162] Host dbs (10.167.4.50) already present
2017-09-14 21:41:24,393 [salt.state       ][INFO    ][21162] Completed state [dbs] at time 21:41:24.393091 duration_in_ms=1.987
2017-09-14 21:41:24,394 [salt.state       ][INFO    ][21162] Running state [dbs.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.393677
2017-09-14 21:41:24,394 [salt.state       ][INFO    ][21162] Executing state host.present for dbs.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,395 [salt.state       ][INFO    ][21162] Host dbs.baremetal-mcp-ocata-odl-ha.local (10.167.4.50) already present
2017-09-14 21:41:24,396 [salt.state       ][INFO    ][21162] Completed state [dbs.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.395720 duration_in_ms=2.043
2017-09-14 21:41:24,396 [salt.state       ][INFO    ][21162] Running state [prx.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.396302
2017-09-14 21:41:24,397 [salt.state       ][INFO    ][21162] Executing state host.present for prx.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,398 [salt.state       ][INFO    ][21162] Host prx.baremetal-mcp-ocata-odl-ha.local (10.167.4.80) already present
2017-09-14 21:41:24,398 [salt.state       ][INFO    ][21162] Completed state [prx.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.398281 duration_in_ms=1.979
2017-09-14 21:41:24,399 [salt.state       ][INFO    ][21162] Running state [prx] at time 21:41:24.398882
2017-09-14 21:41:24,399 [salt.state       ][INFO    ][21162] Executing state host.present for prx
2017-09-14 21:41:24,400 [salt.state       ][INFO    ][21162] Host prx (10.167.4.80) already present
2017-09-14 21:41:24,401 [salt.state       ][INFO    ][21162] Completed state [prx] at time 21:41:24.400848 duration_in_ms=1.965
2017-09-14 21:41:24,401 [salt.state       ][INFO    ][21162] Running state [prx02] at time 21:41:24.401397
2017-09-14 21:41:24,402 [salt.state       ][INFO    ][21162] Executing state host.present for prx02
2017-09-14 21:41:24,403 [salt.state       ][INFO    ][21162] Host prx02 (10.167.4.82) already present
2017-09-14 21:41:24,403 [salt.state       ][INFO    ][21162] Completed state [prx02] at time 21:41:24.403374 duration_in_ms=1.976
2017-09-14 21:41:24,404 [salt.state       ][INFO    ][21162] Running state [prx02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.403993
2017-09-14 21:41:24,405 [salt.state       ][INFO    ][21162] Executing state host.present for prx02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,406 [salt.state       ][INFO    ][21162] Host prx02.baremetal-mcp-ocata-odl-ha.local (10.167.4.82) already present
2017-09-14 21:41:24,406 [salt.state       ][INFO    ][21162] Completed state [prx02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.406013 duration_in_ms=2.021
2017-09-14 21:41:24,407 [salt.state       ][INFO    ][21162] Running state [msg02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.406634
2017-09-14 21:41:24,407 [salt.state       ][INFO    ][21162] Executing state host.present for msg02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,408 [salt.state       ][INFO    ][21162] Host msg02.baremetal-mcp-ocata-odl-ha.local (10.167.4.42) already present
2017-09-14 21:41:24,409 [salt.state       ][INFO    ][21162] Completed state [msg02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.408696 duration_in_ms=2.062
2017-09-14 21:41:24,409 [salt.state       ][INFO    ][21162] Running state [msg02] at time 21:41:24.409289
2017-09-14 21:41:24,410 [salt.state       ][INFO    ][21162] Executing state host.present for msg02
2017-09-14 21:41:24,411 [salt.state       ][INFO    ][21162] Host msg02 (10.167.4.42) already present
2017-09-14 21:41:24,411 [salt.state       ][INFO    ][21162] Completed state [msg02] at time 21:41:24.411338 duration_in_ms=2.049
2017-09-14 21:41:24,412 [salt.state       ][INFO    ][21162] Running state [msg03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.411967
2017-09-14 21:41:24,413 [salt.state       ][INFO    ][21162] Executing state host.present for msg03.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,413 [salt.state       ][INFO    ][21162] Host msg03.baremetal-mcp-ocata-odl-ha.local (10.167.4.43) already present
2017-09-14 21:41:24,414 [salt.state       ][INFO    ][21162] Completed state [msg03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.413935 duration_in_ms=1.968
2017-09-14 21:41:24,415 [salt.state       ][INFO    ][21162] Running state [msg03] at time 21:41:24.414508
2017-09-14 21:41:24,415 [salt.state       ][INFO    ][21162] Executing state host.present for msg03
2017-09-14 21:41:24,416 [salt.state       ][INFO    ][21162] Host msg03 (10.167.4.43) already present
2017-09-14 21:41:24,417 [salt.state       ][INFO    ][21162] Completed state [msg03] at time 21:41:24.416498 duration_in_ms=1.991
2017-09-14 21:41:24,417 [salt.state       ][INFO    ][21162] Running state [msg01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.417067
2017-09-14 21:41:24,418 [salt.state       ][INFO    ][21162] Executing state host.present for msg01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,419 [salt.state       ][INFO    ][21162] Host msg01.baremetal-mcp-ocata-odl-ha.local (10.167.4.41) already present
2017-09-14 21:41:24,419 [salt.state       ][INFO    ][21162] Completed state [msg01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.419051 duration_in_ms=1.984
2017-09-14 21:41:24,420 [salt.state       ][INFO    ][21162] Running state [msg01] at time 21:41:24.419644
2017-09-14 21:41:24,420 [salt.state       ][INFO    ][21162] Executing state host.present for msg01
2017-09-14 21:41:24,421 [salt.state       ][INFO    ][21162] Host msg01 (10.167.4.41) already present
2017-09-14 21:41:24,422 [salt.state       ][INFO    ][21162] Completed state [msg01] at time 21:41:24.421603 duration_in_ms=1.958
2017-09-14 21:41:24,424 [salt.state       ][INFO    ][21162] Running state [file.replace] at time 21:41:24.423481
2017-09-14 21:41:24,424 [salt.state       ][INFO    ][21162] Executing state module.run for file.replace
2017-09-14 21:41:24,534 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command 'grep -q "msg01 msg01.baremetal-mcp-ocata-odl-ha.local" /etc/hosts' in directory '/root'
2017-09-14 21:41:24,549 [salt.state       ][INFO    ][21162] onlyif execution failed
2017-09-14 21:41:24,550 [salt.state       ][INFO    ][21162] Completed state [file.replace] at time 21:41:24.549690 duration_in_ms=126.208
2017-09-14 21:41:24,550 [salt.state       ][INFO    ][21162] Running state [msg] at time 21:41:24.550220
2017-09-14 21:41:24,551 [salt.state       ][INFO    ][21162] Executing state host.present for msg
2017-09-14 21:41:24,552 [salt.state       ][INFO    ][21162] Host msg (10.167.4.40) already present
2017-09-14 21:41:24,552 [salt.state       ][INFO    ][21162] Completed state [msg] at time 21:41:24.551909 duration_in_ms=1.689
2017-09-14 21:41:24,552 [salt.state       ][INFO    ][21162] Running state [msg.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.552307
2017-09-14 21:41:24,553 [salt.state       ][INFO    ][21162] Executing state host.present for msg.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,553 [salt.state       ][INFO    ][21162] Host msg.baremetal-mcp-ocata-odl-ha.local (10.167.4.40) already present
2017-09-14 21:41:24,554 [salt.state       ][INFO    ][21162] Completed state [msg.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.553642 duration_in_ms=1.334
2017-09-14 21:41:24,554 [salt.state       ][INFO    ][21162] Running state [cfg01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.554036
2017-09-14 21:41:24,554 [salt.state       ][INFO    ][21162] Executing state host.present for cfg01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,555 [salt.state       ][INFO    ][21162] Host cfg01.baremetal-mcp-ocata-odl-ha.local (10.167.4.100) already present
2017-09-14 21:41:24,555 [salt.state       ][INFO    ][21162] Completed state [cfg01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.555369 duration_in_ms=1.332
2017-09-14 21:41:24,556 [salt.state       ][INFO    ][21162] Running state [cfg01] at time 21:41:24.555772
2017-09-14 21:41:24,556 [salt.state       ][INFO    ][21162] Executing state host.present for cfg01
2017-09-14 21:41:24,557 [salt.state       ][INFO    ][21162] Host cfg01 (10.167.4.100) already present
2017-09-14 21:41:24,557 [salt.state       ][INFO    ][21162] Completed state [cfg01] at time 21:41:24.557099 duration_in_ms=1.327
2017-09-14 21:41:24,558 [salt.state       ][INFO    ][21162] Running state [cmp002] at time 21:41:24.557486
2017-09-14 21:41:24,558 [salt.state       ][INFO    ][21162] Executing state host.present for cmp002
2017-09-14 21:41:24,558 [salt.state       ][INFO    ][21162] Host cmp002 (10.167.4.102) already present
2017-09-14 21:41:24,559 [salt.state       ][INFO    ][21162] Completed state [cmp002] at time 21:41:24.558823 duration_in_ms=1.337
2017-09-14 21:41:24,559 [salt.state       ][INFO    ][21162] Running state [cmp002.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.559209
2017-09-14 21:41:24,560 [salt.state       ][INFO    ][21162] Executing state host.present for cmp002.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,560 [salt.state       ][INFO    ][21162] Host cmp002.baremetal-mcp-ocata-odl-ha.local (10.167.4.102) already present
2017-09-14 21:41:24,561 [salt.state       ][INFO    ][21162] Completed state [cmp002.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.560536 duration_in_ms=1.327
2017-09-14 21:41:24,561 [salt.state       ][INFO    ][21162] Running state [cmp001] at time 21:41:24.560923
2017-09-14 21:41:24,561 [salt.state       ][INFO    ][21162] Executing state host.present for cmp001
2017-09-14 21:41:24,562 [salt.state       ][INFO    ][21162] Host cmp001 (10.167.4.101) already present
2017-09-14 21:41:24,562 [salt.state       ][INFO    ][21162] Completed state [cmp001] at time 21:41:24.562218 duration_in_ms=1.295
2017-09-14 21:41:24,563 [salt.state       ][INFO    ][21162] Running state [cmp001.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.562616
2017-09-14 21:41:24,563 [salt.state       ][INFO    ][21162] Executing state host.present for cmp001.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,564 [salt.state       ][INFO    ][21162] Host cmp001.baremetal-mcp-ocata-odl-ha.local (10.167.4.101) already present
2017-09-14 21:41:24,564 [salt.state       ][INFO    ][21162] Completed state [cmp001.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.563936 duration_in_ms=1.32
2017-09-14 21:41:24,564 [salt.state       ][INFO    ][21162] Running state [dbs01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.564316
2017-09-14 21:41:24,565 [salt.state       ][INFO    ][21162] Executing state host.present for dbs01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,565 [salt.state       ][INFO    ][21162] Host dbs01.baremetal-mcp-ocata-odl-ha.local (10.167.4.51) already present
2017-09-14 21:41:24,566 [salt.state       ][INFO    ][21162] Completed state [dbs01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.565609 duration_in_ms=1.293
2017-09-14 21:41:24,566 [salt.state       ][INFO    ][21162] Running state [dbs01] at time 21:41:24.565986
2017-09-14 21:41:24,566 [salt.state       ][INFO    ][21162] Executing state host.present for dbs01
2017-09-14 21:41:24,567 [salt.state       ][INFO    ][21162] Host dbs01 (10.167.4.51) already present
2017-09-14 21:41:24,567 [salt.state       ][INFO    ][21162] Completed state [dbs01] at time 21:41:24.567290 duration_in_ms=1.304
2017-09-14 21:41:24,568 [salt.state       ][INFO    ][21162] Running state [dbs02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.567701
2017-09-14 21:41:24,568 [salt.state       ][INFO    ][21162] Executing state host.present for dbs02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,569 [salt.state       ][INFO    ][21162] Host dbs02.baremetal-mcp-ocata-odl-ha.local (10.167.4.52) already present
2017-09-14 21:41:24,569 [salt.state       ][INFO    ][21162] Completed state [dbs02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.568946 duration_in_ms=1.245
2017-09-14 21:41:24,569 [salt.state       ][INFO    ][21162] Running state [dbs02] at time 21:41:24.569314
2017-09-14 21:41:24,570 [salt.state       ][INFO    ][21162] Executing state host.present for dbs02
2017-09-14 21:41:24,570 [salt.state       ][INFO    ][21162] Host dbs02 (10.167.4.52) already present
2017-09-14 21:41:24,571 [salt.state       ][INFO    ][21162] Completed state [dbs02] at time 21:41:24.570929 duration_in_ms=1.614
2017-09-14 21:41:24,571 [salt.state       ][INFO    ][21162] Running state [dbs03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.571349
2017-09-14 21:41:24,572 [salt.state       ][INFO    ][21162] Executing state host.present for dbs03.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,573 [salt.state       ][INFO    ][21162] Host dbs03.baremetal-mcp-ocata-odl-ha.local (10.167.4.53) already present
2017-09-14 21:41:24,573 [salt.state       ][INFO    ][21162] Completed state [dbs03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.572840 duration_in_ms=1.491
2017-09-14 21:41:24,573 [salt.state       ][INFO    ][21162] Running state [dbs03] at time 21:41:24.573194
2017-09-14 21:41:24,574 [salt.state       ][INFO    ][21162] Executing state host.present for dbs03
2017-09-14 21:41:24,574 [salt.state       ][INFO    ][21162] Host dbs03 (10.167.4.53) already present
2017-09-14 21:41:24,575 [salt.state       ][INFO    ][21162] Completed state [dbs03] at time 21:41:24.574598 duration_in_ms=1.403
2017-09-14 21:41:24,575 [salt.state       ][INFO    ][21162] Running state [odl01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.574976
2017-09-14 21:41:24,575 [salt.state       ][INFO    ][21162] Executing state host.present for odl01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,576 [salt.state       ][INFO    ][21162] Host odl01.baremetal-mcp-ocata-odl-ha.local (10.167.4.111) already present
2017-09-14 21:41:24,576 [salt.state       ][INFO    ][21162] Completed state [odl01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.576371 duration_in_ms=1.395
2017-09-14 21:41:24,577 [salt.state       ][INFO    ][21162] Running state [odl01] at time 21:41:24.576711
2017-09-14 21:41:24,577 [salt.state       ][INFO    ][21162] Executing state host.present for odl01
2017-09-14 21:41:24,578 [salt.state       ][INFO    ][21162] Host odl01 (10.167.4.111) already present
2017-09-14 21:41:24,578 [salt.state       ][INFO    ][21162] Completed state [odl01] at time 21:41:24.578002 duration_in_ms=1.291
2017-09-14 21:41:24,578 [salt.state       ][INFO    ][21162] Running state [mas01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.578341
2017-09-14 21:41:24,579 [salt.state       ][INFO    ][21162] Executing state host.present for mas01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,579 [salt.state       ][INFO    ][21162] Host mas01.baremetal-mcp-ocata-odl-ha.local (10.167.4.3) already present
2017-09-14 21:41:24,580 [salt.state       ][INFO    ][21162] Completed state [mas01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.579689 duration_in_ms=1.348
2017-09-14 21:41:24,580 [salt.state       ][INFO    ][21162] Running state [mas01] at time 21:41:24.580028
2017-09-14 21:41:24,580 [salt.state       ][INFO    ][21162] Executing state host.present for mas01
2017-09-14 21:41:24,581 [salt.state       ][INFO    ][21162] Host mas01 (10.167.4.3) already present
2017-09-14 21:41:24,581 [salt.state       ][INFO    ][21162] Completed state [mas01] at time 21:41:24.581306 duration_in_ms=1.278
2017-09-14 21:41:24,582 [salt.state       ][INFO    ][21162] Running state [ctl02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.581646
2017-09-14 21:41:24,582 [salt.state       ][INFO    ][21162] Executing state host.present for ctl02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,583 [salt.state       ][INFO    ][21162] Host ctl02.baremetal-mcp-ocata-odl-ha.local (10.167.4.12) already present
2017-09-14 21:41:24,583 [salt.state       ][INFO    ][21162] Completed state [ctl02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.582984 duration_in_ms=1.338
2017-09-14 21:41:24,583 [salt.state       ][INFO    ][21162] Running state [ctl02] at time 21:41:24.583321
2017-09-14 21:41:24,584 [salt.state       ][INFO    ][21162] Executing state host.present for ctl02
2017-09-14 21:41:24,584 [salt.state       ][INFO    ][21162] Host ctl02 (10.167.4.12) already present
2017-09-14 21:41:24,585 [salt.state       ][INFO    ][21162] Completed state [ctl02] at time 21:41:24.584651 duration_in_ms=1.329
2017-09-14 21:41:24,585 [salt.state       ][INFO    ][21162] Running state [ctl03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.584989
2017-09-14 21:41:24,585 [salt.state       ][INFO    ][21162] Executing state host.present for ctl03.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,586 [salt.state       ][INFO    ][21162] Host ctl03.baremetal-mcp-ocata-odl-ha.local (10.167.4.13) already present
2017-09-14 21:41:24,586 [salt.state       ][INFO    ][21162] Completed state [ctl03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.586297 duration_in_ms=1.308
2017-09-14 21:41:24,587 [salt.state       ][INFO    ][21162] Running state [ctl03] at time 21:41:24.586655
2017-09-14 21:41:24,587 [salt.state       ][INFO    ][21162] Executing state host.present for ctl03
2017-09-14 21:41:24,588 [salt.state       ][INFO    ][21162] Host ctl03 (10.167.4.13) already present
2017-09-14 21:41:24,588 [salt.state       ][INFO    ][21162] Completed state [ctl03] at time 21:41:24.587978 duration_in_ms=1.322
2017-09-14 21:41:24,588 [salt.state       ][INFO    ][21162] Running state [ctl01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.588294
2017-09-14 21:41:24,589 [salt.state       ][INFO    ][21162] Executing state host.present for ctl01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,589 [salt.state       ][INFO    ][21162] Host ctl01.baremetal-mcp-ocata-odl-ha.local (10.167.4.11) already present
2017-09-14 21:41:24,590 [salt.state       ][INFO    ][21162] Completed state [ctl01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.589524 duration_in_ms=1.229
2017-09-14 21:41:24,590 [salt.state       ][INFO    ][21162] Running state [ctl01] at time 21:41:24.589842
2017-09-14 21:41:24,590 [salt.state       ][INFO    ][21162] Executing state host.present for ctl01
2017-09-14 21:41:24,591 [salt.state       ][INFO    ][21162] Host ctl01 (10.167.4.11) already present
2017-09-14 21:41:24,591 [salt.state       ][INFO    ][21162] Completed state [ctl01] at time 21:41:24.591087 duration_in_ms=1.245
2017-09-14 21:41:24,591 [salt.state       ][INFO    ][21162] Running state [ctl] at time 21:41:24.591403
2017-09-14 21:41:24,592 [salt.state       ][INFO    ][21162] Executing state host.present for ctl
2017-09-14 21:41:24,592 [salt.state       ][INFO    ][21162] Host ctl (10.167.4.10) already present
2017-09-14 21:41:24,593 [salt.state       ][INFO    ][21162] Completed state [ctl] at time 21:41:24.592649 duration_in_ms=1.245
2017-09-14 21:41:24,593 [salt.state       ][INFO    ][21162] Running state [ctl.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.592980
2017-09-14 21:41:24,593 [salt.state       ][INFO    ][21162] Executing state host.present for ctl.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:24,594 [salt.state       ][INFO    ][21162] Host ctl.baremetal-mcp-ocata-odl-ha.local (10.167.4.10) already present
2017-09-14 21:41:24,594 [salt.state       ][INFO    ][21162] Completed state [ctl.baremetal-mcp-ocata-odl-ha.local] at time 21:41:24.594234 duration_in_ms=1.253
2017-09-14 21:41:24,595 [salt.state       ][INFO    ][21162] Running state [rabbitmqctl set_cluster_name openstack && echo "openstack" > /var/lib/rabbitmq/.cluster_name
] at time 21:41:24.595237
2017-09-14 21:41:24,596 [salt.state       ][INFO    ][21162] Executing state cmd.run for rabbitmqctl set_cluster_name openstack && echo "openstack" > /var/lib/rabbitmq/.cluster_name

2017-09-14 21:41:24,596 [salt.loaded.int.module.cmdmod][INFO    ][21162] Executing command 'rabbitmqctl set_cluster_name openstack && echo "openstack" > /var/lib/rabbitmq/.cluster_name
' in directory '/root'
2017-09-14 21:41:25,321 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914214125307416
2017-09-14 21:41:25,346 [salt.minion      ][INFO    ][27274] Starting a new job with PID 27274
2017-09-14 21:41:25,364 [salt.minion      ][INFO    ][27274] Returning information for job: 20170914214125307416
2017-09-14 21:41:25,670 [salt.state       ][INFO    ][21162] {'pid': 27130, 'retcode': 0, 'stderr': '', 'stdout': 'Setting cluster name to openstack ...'}
2017-09-14 21:41:25,671 [salt.state       ][INFO    ][21162] Completed state [rabbitmqctl set_cluster_name openstack && echo "openstack" > /var/lib/rabbitmq/.cluster_name
] at time 21:41:25.670830 duration_in_ms=1075.59
2017-09-14 21:41:25,675 [salt.minion      ][INFO    ][21162] Returning information for job: 20170914213751885807
2017-09-14 21:41:26,696 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command state.sls with jid 20170914214126687569
2017-09-14 21:41:26,720 [salt.minion      ][INFO    ][27285] Starting a new job with PID 27285
2017-09-14 21:41:28,151 [salt.state       ][INFO    ][27285] Loading fresh modules for state activity
2017-09-14 21:41:28,190 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/init.sls'
2017-09-14 21:41:28,221 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/init.sls'
2017-09-14 21:41:28,238 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/service.sls'
2017-09-14 21:41:28,273 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/map.jinja'
2017-09-14 21:41:28,298 [py.warnings      ][WARNING ][27285] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2017-09-14 21:41:28,300 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command 'cat /var/lib/rabbitmq/.erlang.cookie' in directory '/root'
2017-09-14 21:41:28,338 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/ssl.sls'
2017-09-14 21:41:28,370 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/map.jinja'
2017-09-14 21:41:28,403 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/plugin.sls'
2017-09-14 21:41:28,424 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/map.jinja'
2017-09-14 21:41:28,458 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/vhost.sls'
2017-09-14 21:41:28,506 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/map.jinja'
2017-09-14 21:41:28,560 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/user.sls'
2017-09-14 21:41:28,592 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/map.jinja'
2017-09-14 21:41:28,637 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/cluster.sls'
2017-09-14 21:41:28,667 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/map.jinja'
2017-09-14 21:41:28,702 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'linux/network/host.sls'
2017-09-14 21:41:28,755 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-14 21:41:29,122 [salt.state       ][INFO    ][27285] Running state [rabbitmq-server] at time 21:41:29.122180
2017-09-14 21:41:29,122 [salt.state       ][INFO    ][27285] Executing state pkg.installed for rabbitmq-server
2017-09-14 21:41:29,123 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-14 21:41:29,572 [salt.state       ][INFO    ][27285] Package rabbitmq-server is already installed
2017-09-14 21:41:29,572 [salt.state       ][INFO    ][27285] Completed state [rabbitmq-server] at time 21:41:29.572090 duration_in_ms=449.91
2017-09-14 21:41:29,572 [salt.state       ][INFO    ][27285] Running state [gettext-base] at time 21:41:29.572299
2017-09-14 21:41:29,573 [salt.state       ][INFO    ][27285] Executing state pkg.installed for gettext-base
2017-09-14 21:41:29,575 [salt.state       ][INFO    ][27285] Package gettext-base is already installed
2017-09-14 21:41:29,575 [salt.state       ][INFO    ][27285] Completed state [gettext-base] at time 21:41:29.575356 duration_in_ms=3.057
2017-09-14 21:41:29,577 [salt.state       ][INFO    ][27285] Running state [/etc/rabbitmq/rabbitmq.config] at time 21:41:29.577112
2017-09-14 21:41:29,577 [salt.state       ][INFO    ][27285] Executing state file.managed for /etc/rabbitmq/rabbitmq.config
2017-09-14 21:41:29,600 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/files/rabbitmq.config'
2017-09-14 21:41:29,630 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/map.jinja'
2017-09-14 21:41:29,641 [salt.state       ][INFO    ][27285] File /etc/rabbitmq/rabbitmq.config is in the correct state
2017-09-14 21:41:29,641 [salt.state       ][INFO    ][27285] Completed state [/etc/rabbitmq/rabbitmq.config] at time 21:41:29.641313 duration_in_ms=64.201
2017-09-14 21:41:29,642 [salt.state       ][INFO    ][27285] Running state [/etc/default/rabbitmq-server] at time 21:41:29.641728
2017-09-14 21:41:29,642 [salt.state       ][INFO    ][27285] Executing state file.managed for /etc/default/rabbitmq-server
2017-09-14 21:41:29,659 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/files/default'
2017-09-14 21:41:29,676 [salt.fileclient  ][INFO    ][27285] Fetching file from saltenv 'base', ** done ** 'rabbitmq/map.jinja'
2017-09-14 21:41:29,689 [salt.state       ][INFO    ][27285] File /etc/default/rabbitmq-server is in the correct state
2017-09-14 21:41:29,689 [salt.state       ][INFO    ][27285] Completed state [/etc/default/rabbitmq-server] at time 21:41:29.688906 duration_in_ms=47.178
2017-09-14 21:41:29,690 [salt.state       ][INFO    ][27285] Running state [rabbitmq-server] at time 21:41:29.689768
2017-09-14 21:41:29,690 [salt.state       ][INFO    ][27285] Executing state service.running for rabbitmq-server
2017-09-14 21:41:29,690 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['systemctl', 'status', 'rabbitmq-server.service', '-n', '0'] in directory '/root'
2017-09-14 21:41:29,721 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['systemctl', 'is-active', 'rabbitmq-server.service'] in directory '/root'
2017-09-14 21:41:29,736 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['systemctl', 'is-enabled', 'rabbitmq-server.service'] in directory '/root'
2017-09-14 21:41:29,748 [salt.state       ][INFO    ][27285] The service rabbitmq-server is already running
2017-09-14 21:41:29,749 [salt.state       ][INFO    ][27285] Completed state [rabbitmq-server] at time 21:41:29.748702 duration_in_ms=58.933
2017-09-14 21:41:29,750 [salt.state       ][INFO    ][27285] Running state [amqp_client] at time 21:41:29.750418
2017-09-14 21:41:29,751 [salt.state       ][INFO    ][27285] Executing state rabbitmq_plugin.enabled for amqp_client
2017-09-14 21:41:29,751 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['/usr/sbin/rabbitmq-plugins', 'list', '-m', '-e'] as user 'root' in directory '/root'
2017-09-14 21:41:30,577 [salt.state       ][INFO    ][27285] Plugin 'amqp_client' is already enabled.
2017-09-14 21:41:30,578 [salt.state       ][INFO    ][27285] Completed state [amqp_client] at time 21:41:30.577696 duration_in_ms=827.277
2017-09-14 21:41:30,578 [salt.state       ][INFO    ][27285] Running state [rabbitmq_management] at time 21:41:30.578430
2017-09-14 21:41:30,579 [salt.state       ][INFO    ][27285] Executing state rabbitmq_plugin.enabled for rabbitmq_management
2017-09-14 21:41:30,579 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['/usr/sbin/rabbitmq-plugins', 'list', '-m', '-e'] as user 'root' in directory '/root'
2017-09-14 21:41:31,370 [salt.state       ][INFO    ][27285] Plugin 'rabbitmq_management' is already enabled.
2017-09-14 21:41:31,370 [salt.state       ][INFO    ][27285] Completed state [rabbitmq_management] at time 21:41:31.370187 duration_in_ms=791.755
2017-09-14 21:41:31,373 [salt.state       ][INFO    ][27285] Running state [/openstack] at time 21:41:31.372771
2017-09-14 21:41:31,373 [salt.state       ][INFO    ][27285] Executing state rabbitmq_vhost.present for /openstack
2017-09-14 21:41:31,374 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command 'rabbitmqctl list_vhosts -q' as user 'root' in directory '/root'
2017-09-14 21:41:32,296 [salt.state       ][INFO    ][27285] Virtual Host '/openstack' already exists.
2017-09-14 21:41:32,296 [salt.state       ][INFO    ][27285] Completed state [/openstack] at time 21:41:32.296181 duration_in_ms=923.409
2017-09-14 21:41:32,298 [salt.state       ][INFO    ][27285] Running state [openstack] at time 21:41:32.298245
2017-09-14 21:41:32,299 [salt.state       ][INFO    ][27285] Executing state rabbitmq_user.present for openstack
2017-09-14 21:41:32,299 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['rabbitmqctl', 'list_users'] as user 'root' in directory '/root'
2017-09-14 21:41:33,122 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['rabbitmqctl', 'status'] as user 'root' in directory '/root'
2017-09-14 21:41:36,020 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['rabbitmqctl', 'list_user_permissions', 'openstack'] as user 'root' in directory '/root'
2017-09-14 21:41:36,783 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914214136767766
2017-09-14 21:41:36,804 [salt.minion      ][INFO    ][28511] Starting a new job with PID 28511
2017-09-14 21:41:36,823 [salt.minion      ][INFO    ][28511] Returning information for job: 20170914214136767766
2017-09-14 21:41:36,959 [salt.state       ][INFO    ][27285] Made the following changes:
'password' changed from 'absent' to 'Set password.'

2017-09-14 21:41:36,960 [salt.state       ][INFO    ][27285] Completed state [openstack] at time 21:41:36.959551 duration_in_ms=4661.304
2017-09-14 21:41:36,963 [salt.state       ][INFO    ][27285] Running state [Ceilometer] at time 21:41:36.962448
2017-09-14 21:41:36,963 [salt.state       ][INFO    ][27285] Executing state rabbitmq_policy.present for Ceilometer
2017-09-14 21:41:36,964 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['rabbitmqctl', 'list_policies', '-p', '/openstack'] as user 'root' in directory '/root'
2017-09-14 21:41:37,960 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['rabbitmqctl', 'set_policy', '-p', '/openstack', '--priority', 10, 'Ceilometer', '^metering.sample$', '{"ha-mode": "all", "message-ttl": 10800000}'] as user 'root' in directory '/root'
2017-09-14 21:41:39,054 [salt.state       ][INFO    ][27285] {'new': ['Definition'], 'old': {'apply_to': 'all', 'priority': '10', 'definition': '{"ha-mode":"all","message-ttl":10800000}', 'pattern': '^metering.sample$'}}
2017-09-14 21:41:39,055 [salt.state       ][INFO    ][27285] Completed state [Ceilometer] at time 21:41:39.054751 duration_in_ms=2092.302
2017-09-14 21:41:39,056 [salt.state       ][INFO    ][27285] Running state [HA] at time 21:41:39.056145
2017-09-14 21:41:39,057 [salt.state       ][INFO    ][27285] Executing state rabbitmq_policy.present for HA
2017-09-14 21:41:39,058 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['rabbitmqctl', 'list_policies', '-p', '/openstack'] as user 'root' in directory '/root'
2017-09-14 21:41:40,062 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['rabbitmqctl', 'set_policy', '-p', '/openstack', 'HA', '^(?!amq\\.).*', '{"ha-mode": "all", "message-ttl": 120000}'] as user 'root' in directory '/root'
2017-09-14 21:41:41,097 [salt.state       ][INFO    ][27285] {'new': ['Pattern', 'Definition'], 'old': {'apply_to': 'all', 'priority': '0', 'definition': '{"ha-mode":"all","message-ttl":120000}', 'pattern': '^(?!amq\\\\.).*'}}
2017-09-14 21:41:41,097 [salt.state       ][INFO    ][27285] Completed state [HA] at time 21:41:41.097330 duration_in_ms=2041.183
2017-09-14 21:41:41,098 [salt.state       ][INFO    ][27285] Running state [guest] at time 21:41:41.097832
2017-09-14 21:41:41,098 [salt.state       ][INFO    ][27285] Executing state rabbitmq_user.present for guest
2017-09-14 21:41:41,099 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['rabbitmqctl', 'list_users'] as user 'root' in directory '/root'
2017-09-14 21:41:42,050 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['rabbitmqctl', 'status'] as user 'root' in directory '/root'
2017-09-14 21:41:44,762 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['rabbitmqctl', 'list_user_permissions', 'guest'] as user 'root' in directory '/root'
2017-09-14 21:41:45,561 [salt.state       ][INFO    ][27285] Made the following changes:
'password' changed from 'absent' to 'Set password.'

2017-09-14 21:41:45,562 [salt.state       ][INFO    ][27285] Completed state [guest] at time 21:41:45.561652 duration_in_ms=4463.821
2017-09-14 21:41:45,562 [salt.state       ][INFO    ][27285] Running state [HA] at time 21:41:45.562252
2017-09-14 21:41:45,562 [salt.state       ][INFO    ][27285] Executing state rabbitmq_policy.present for HA
2017-09-14 21:41:45,563 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['rabbitmqctl', 'list_policies', '-p', '/'] as user 'root' in directory '/root'
2017-09-14 21:41:46,553 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['rabbitmqctl', 'set_policy', '-p', '/', 'HA', '^(?!amq\\.).*', '{"ha-mode": "all", "message-ttl": 120000}'] as user 'root' in directory '/root'
2017-09-14 21:41:46,994 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914214146981091
2017-09-14 21:41:47,015 [salt.minion      ][INFO    ][30171] Starting a new job with PID 30171
2017-09-14 21:41:47,035 [salt.minion      ][INFO    ][30171] Returning information for job: 20170914214146981091
2017-09-14 21:41:47,637 [salt.state       ][INFO    ][27285] {'new': ['Pattern', 'Definition'], 'old': {'apply_to': 'all', 'priority': '0', 'definition': '{"ha-mode":"all","message-ttl":120000}', 'pattern': '^(?!amq\\\\.).*'}}
2017-09-14 21:41:47,638 [salt.state       ][INFO    ][27285] Completed state [HA] at time 21:41:47.637486 duration_in_ms=2075.232
2017-09-14 21:41:47,639 [salt.state       ][INFO    ][27285] Running state [admin] at time 21:41:47.638790
2017-09-14 21:41:47,639 [salt.state       ][INFO    ][27285] Executing state rabbitmq_user.present for admin
2017-09-14 21:41:47,640 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['rabbitmqctl', 'list_users'] as user 'root' in directory '/root'
2017-09-14 21:41:48,618 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['rabbitmqctl', 'status'] as user 'root' in directory '/root'
2017-09-14 21:41:51,642 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['rabbitmqctl', 'list_users'] as user 'root' in directory '/root'
2017-09-14 21:41:52,369 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command ['rabbitmqctl', 'list_user_permissions', 'admin'] as user 'root' in directory '/root'
2017-09-14 21:41:53,304 [salt.state       ][INFO    ][27285] Made the following changes:
'password' changed from 'absent' to 'Set password.'

2017-09-14 21:41:53,305 [salt.state       ][INFO    ][27285] Completed state [admin] at time 21:41:53.304789 duration_in_ms=5665.999
2017-09-14 21:41:53,306 [salt.state       ][INFO    ][27285] Running state [mdb02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.305660
2017-09-14 21:41:53,306 [salt.state       ][INFO    ][27285] Executing state host.present for mdb02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,307 [salt.state       ][INFO    ][27285] Host mdb02.baremetal-mcp-ocata-odl-ha.local (10.167.4.77) already present
2017-09-14 21:41:53,307 [salt.state       ][INFO    ][27285] Completed state [mdb02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.306945 duration_in_ms=1.285
2017-09-14 21:41:53,307 [salt.state       ][INFO    ][27285] Running state [mdb02] at time 21:41:53.307215
2017-09-14 21:41:53,307 [salt.state       ][INFO    ][27285] Executing state host.present for mdb02
2017-09-14 21:41:53,308 [salt.state       ][INFO    ][27285] Host mdb02 (10.167.4.77) already present
2017-09-14 21:41:53,308 [salt.state       ][INFO    ][27285] Completed state [mdb02] at time 21:41:53.308155 duration_in_ms=0.94
2017-09-14 21:41:53,308 [salt.state       ][INFO    ][27285] Running state [mdb03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.308398
2017-09-14 21:41:53,309 [salt.state       ][INFO    ][27285] Executing state host.present for mdb03.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,309 [salt.state       ][INFO    ][27285] Host mdb03.baremetal-mcp-ocata-odl-ha.local (10.167.4.78) already present
2017-09-14 21:41:53,309 [salt.state       ][INFO    ][27285] Completed state [mdb03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.309274 duration_in_ms=0.876
2017-09-14 21:41:53,310 [salt.state       ][INFO    ][27285] Running state [mdb03] at time 21:41:53.309500
2017-09-14 21:41:53,310 [salt.state       ][INFO    ][27285] Executing state host.present for mdb03
2017-09-14 21:41:53,310 [salt.state       ][INFO    ][27285] Host mdb03 (10.167.4.78) already present
2017-09-14 21:41:53,310 [salt.state       ][INFO    ][27285] Completed state [mdb03] at time 21:41:53.310382 duration_in_ms=0.881
2017-09-14 21:41:53,311 [salt.state       ][INFO    ][27285] Running state [mdb01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.310638
2017-09-14 21:41:53,311 [salt.state       ][INFO    ][27285] Executing state host.present for mdb01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,311 [salt.state       ][INFO    ][27285] Host mdb01.baremetal-mcp-ocata-odl-ha.local (10.167.4.76) already present
2017-09-14 21:41:53,312 [salt.state       ][INFO    ][27285] Completed state [mdb01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.311551 duration_in_ms=0.913
2017-09-14 21:41:53,312 [salt.state       ][INFO    ][27285] Running state [mdb01] at time 21:41:53.311778
2017-09-14 21:41:53,312 [salt.state       ][INFO    ][27285] Executing state host.present for mdb01
2017-09-14 21:41:53,312 [salt.state       ][INFO    ][27285] Host mdb01 (10.167.4.76) already present
2017-09-14 21:41:53,313 [salt.state       ][INFO    ][27285] Completed state [mdb01] at time 21:41:53.312624 duration_in_ms=0.846
2017-09-14 21:41:53,313 [salt.state       ][INFO    ][27285] Running state [mdb] at time 21:41:53.312847
2017-09-14 21:41:53,313 [salt.state       ][INFO    ][27285] Executing state host.present for mdb
2017-09-14 21:41:53,314 [salt.state       ][INFO    ][27285] Host mdb (10.167.4.75) already present
2017-09-14 21:41:53,314 [salt.state       ][INFO    ][27285] Completed state [mdb] at time 21:41:53.314012 duration_in_ms=1.165
2017-09-14 21:41:53,314 [salt.state       ][INFO    ][27285] Running state [mdb.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.314328
2017-09-14 21:41:53,315 [salt.state       ][INFO    ][27285] Executing state host.present for mdb.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,315 [salt.state       ][INFO    ][27285] Host mdb.baremetal-mcp-ocata-odl-ha.local (10.167.4.75) already present
2017-09-14 21:41:53,315 [salt.state       ][INFO    ][27285] Completed state [mdb.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.315419 duration_in_ms=1.091
2017-09-14 21:41:53,316 [salt.state       ][INFO    ][27285] Running state [cfg01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.315659
2017-09-14 21:41:53,316 [salt.state       ][INFO    ][27285] Executing state host.present for cfg01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,316 [salt.state       ][INFO    ][27285] Host cfg01.baremetal-mcp-ocata-odl-ha.local (10.167.4.100) already present
2017-09-14 21:41:53,317 [salt.state       ][INFO    ][27285] Completed state [cfg01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.316565 duration_in_ms=0.906
2017-09-14 21:41:53,317 [salt.state       ][INFO    ][27285] Running state [cfg01] at time 21:41:53.316801
2017-09-14 21:41:53,317 [salt.state       ][INFO    ][27285] Executing state host.present for cfg01
2017-09-14 21:41:53,318 [salt.state       ][INFO    ][27285] Host cfg01 (10.167.4.100) already present
2017-09-14 21:41:53,318 [salt.state       ][INFO    ][27285] Completed state [cfg01] at time 21:41:53.317692 duration_in_ms=0.891
2017-09-14 21:41:53,318 [salt.state       ][INFO    ][27285] Running state [prx01] at time 21:41:53.317928
2017-09-14 21:41:53,318 [salt.state       ][INFO    ][27285] Executing state host.present for prx01
2017-09-14 21:41:53,319 [salt.state       ][INFO    ][27285] Host prx01 (10.167.4.81) already present
2017-09-14 21:41:53,319 [salt.state       ][INFO    ][27285] Completed state [prx01] at time 21:41:53.318847 duration_in_ms=0.919
2017-09-14 21:41:53,319 [salt.state       ][INFO    ][27285] Running state [prx01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.319097
2017-09-14 21:41:53,319 [salt.state       ][INFO    ][27285] Executing state host.present for prx01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,320 [salt.state       ][INFO    ][27285] Host prx01.baremetal-mcp-ocata-odl-ha.local (10.167.4.81) already present
2017-09-14 21:41:53,320 [salt.state       ][INFO    ][27285] Completed state [prx01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.319980 duration_in_ms=0.883
2017-09-14 21:41:53,320 [salt.state       ][INFO    ][27285] Running state [kvm01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.320214
2017-09-14 21:41:53,320 [salt.state       ][INFO    ][27285] Executing state host.present for kvm01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,321 [salt.state       ][INFO    ][27285] Host kvm01.baremetal-mcp-ocata-odl-ha.local (10.167.4.141) already present
2017-09-14 21:41:53,321 [salt.state       ][INFO    ][27285] Completed state [kvm01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.321111 duration_in_ms=0.896
2017-09-14 21:41:53,321 [salt.state       ][INFO    ][27285] Running state [kvm01] at time 21:41:53.321338
2017-09-14 21:41:53,322 [salt.state       ][INFO    ][27285] Executing state host.present for kvm01
2017-09-14 21:41:53,322 [salt.state       ][INFO    ][27285] Host kvm01 (10.167.4.141) already present
2017-09-14 21:41:53,322 [salt.state       ][INFO    ][27285] Completed state [kvm01] at time 21:41:53.322192 duration_in_ms=0.853
2017-09-14 21:41:53,322 [salt.state       ][INFO    ][27285] Running state [kvm03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.322415
2017-09-14 21:41:53,323 [salt.state       ][INFO    ][27285] Executing state host.present for kvm03.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,323 [salt.state       ][INFO    ][27285] Host kvm03.baremetal-mcp-ocata-odl-ha.local (10.167.4.143) already present
2017-09-14 21:41:53,323 [salt.state       ][INFO    ][27285] Completed state [kvm03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.323324 duration_in_ms=0.909
2017-09-14 21:41:53,324 [salt.state       ][INFO    ][27285] Running state [kvm03] at time 21:41:53.323544
2017-09-14 21:41:53,324 [salt.state       ][INFO    ][27285] Executing state host.present for kvm03
2017-09-14 21:41:53,325 [salt.state       ][INFO    ][27285] Host kvm03 (10.167.4.143) already present
2017-09-14 21:41:53,325 [salt.state       ][INFO    ][27285] Completed state [kvm03] at time 21:41:53.324747 duration_in_ms=1.203
2017-09-14 21:41:53,325 [salt.state       ][INFO    ][27285] Running state [kvm02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.324965
2017-09-14 21:41:53,325 [salt.state       ][INFO    ][27285] Executing state host.present for kvm02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,326 [salt.state       ][INFO    ][27285] Host kvm02.baremetal-mcp-ocata-odl-ha.local (10.167.4.142) already present
2017-09-14 21:41:53,326 [salt.state       ][INFO    ][27285] Completed state [kvm02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.325798 duration_in_ms=0.833
2017-09-14 21:41:53,326 [salt.state       ][INFO    ][27285] Running state [kvm02] at time 21:41:53.326033
2017-09-14 21:41:53,326 [salt.state       ][INFO    ][27285] Executing state host.present for kvm02
2017-09-14 21:41:53,327 [salt.state       ][INFO    ][27285] Host kvm02 (10.167.4.142) already present
2017-09-14 21:41:53,327 [salt.state       ][INFO    ][27285] Completed state [kvm02] at time 21:41:53.326900 duration_in_ms=0.866
2017-09-14 21:41:53,327 [salt.state       ][INFO    ][27285] Running state [dbs] at time 21:41:53.327144
2017-09-14 21:41:53,327 [salt.state       ][INFO    ][27285] Executing state host.present for dbs
2017-09-14 21:41:53,328 [salt.state       ][INFO    ][27285] Host dbs (10.167.4.50) already present
2017-09-14 21:41:53,328 [salt.state       ][INFO    ][27285] Completed state [dbs] at time 21:41:53.327983 duration_in_ms=0.839
2017-09-14 21:41:53,328 [salt.state       ][INFO    ][27285] Running state [dbs.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.328203
2017-09-14 21:41:53,328 [salt.state       ][INFO    ][27285] Executing state host.present for dbs.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,329 [salt.state       ][INFO    ][27285] Host dbs.baremetal-mcp-ocata-odl-ha.local (10.167.4.50) already present
2017-09-14 21:41:53,329 [salt.state       ][INFO    ][27285] Completed state [dbs.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.329038 duration_in_ms=0.835
2017-09-14 21:41:53,329 [salt.state       ][INFO    ][27285] Running state [prx.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.329259
2017-09-14 21:41:53,329 [salt.state       ][INFO    ][27285] Executing state host.present for prx.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,330 [salt.state       ][INFO    ][27285] Host prx.baremetal-mcp-ocata-odl-ha.local (10.167.4.80) already present
2017-09-14 21:41:53,330 [salt.state       ][INFO    ][27285] Completed state [prx.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.330086 duration_in_ms=0.827
2017-09-14 21:41:53,330 [salt.state       ][INFO    ][27285] Running state [prx] at time 21:41:53.330308
2017-09-14 21:41:53,331 [salt.state       ][INFO    ][27285] Executing state host.present for prx
2017-09-14 21:41:53,331 [salt.state       ][INFO    ][27285] Host prx (10.167.4.80) already present
2017-09-14 21:41:53,331 [salt.state       ][INFO    ][27285] Completed state [prx] at time 21:41:53.331174 duration_in_ms=0.865
2017-09-14 21:41:53,331 [salt.state       ][INFO    ][27285] Running state [prx02] at time 21:41:53.331394
2017-09-14 21:41:53,332 [salt.state       ][INFO    ][27285] Executing state host.present for prx02
2017-09-14 21:41:53,332 [salt.state       ][INFO    ][27285] Host prx02 (10.167.4.82) already present
2017-09-14 21:41:53,332 [salt.state       ][INFO    ][27285] Completed state [prx02] at time 21:41:53.332221 duration_in_ms=0.826
2017-09-14 21:41:53,332 [salt.state       ][INFO    ][27285] Running state [prx02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.332443
2017-09-14 21:41:53,333 [salt.state       ][INFO    ][27285] Executing state host.present for prx02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,333 [salt.state       ][INFO    ][27285] Host prx02.baremetal-mcp-ocata-odl-ha.local (10.167.4.82) already present
2017-09-14 21:41:53,333 [salt.state       ][INFO    ][27285] Completed state [prx02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.333270 duration_in_ms=0.827
2017-09-14 21:41:53,334 [salt.state       ][INFO    ][27285] Running state [msg02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.333497
2017-09-14 21:41:53,334 [salt.state       ][INFO    ][27285] Executing state host.present for msg02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,334 [salt.state       ][INFO    ][27285] Host msg02.baremetal-mcp-ocata-odl-ha.local (10.167.4.42) already present
2017-09-14 21:41:53,334 [salt.state       ][INFO    ][27285] Completed state [msg02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.334349 duration_in_ms=0.852
2017-09-14 21:41:53,335 [salt.state       ][INFO    ][27285] Running state [msg02] at time 21:41:53.334593
2017-09-14 21:41:53,335 [salt.state       ][INFO    ][27285] Executing state host.present for msg02
2017-09-14 21:41:53,335 [salt.state       ][INFO    ][27285] Host msg02 (10.167.4.42) already present
2017-09-14 21:41:53,335 [salt.state       ][INFO    ][27285] Completed state [msg02] at time 21:41:53.335444 duration_in_ms=0.851
2017-09-14 21:41:53,336 [salt.state       ][INFO    ][27285] Running state [msg03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.335657
2017-09-14 21:41:53,336 [salt.state       ][INFO    ][27285] Executing state host.present for msg03.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,336 [salt.state       ][INFO    ][27285] Host msg03.baremetal-mcp-ocata-odl-ha.local (10.167.4.43) already present
2017-09-14 21:41:53,337 [salt.state       ][INFO    ][27285] Completed state [msg03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.336472 duration_in_ms=0.815
2017-09-14 21:41:53,337 [salt.state       ][INFO    ][27285] Running state [msg03] at time 21:41:53.336687
2017-09-14 21:41:53,337 [salt.state       ][INFO    ][27285] Executing state host.present for msg03
2017-09-14 21:41:53,337 [salt.state       ][INFO    ][27285] Host msg03 (10.167.4.43) already present
2017-09-14 21:41:53,338 [salt.state       ][INFO    ][27285] Completed state [msg03] at time 21:41:53.337497 duration_in_ms=0.81
2017-09-14 21:41:53,338 [salt.state       ][INFO    ][27285] Running state [msg01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.337704
2017-09-14 21:41:53,338 [salt.state       ][INFO    ][27285] Executing state host.present for msg01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,338 [salt.state       ][INFO    ][27285] Host msg01.baremetal-mcp-ocata-odl-ha.local (10.167.4.41) already present
2017-09-14 21:41:53,339 [salt.state       ][INFO    ][27285] Completed state [msg01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.338507 duration_in_ms=0.803
2017-09-14 21:41:53,339 [salt.state       ][INFO    ][27285] Running state [msg01] at time 21:41:53.338748
2017-09-14 21:41:53,339 [salt.state       ][INFO    ][27285] Executing state host.present for msg01
2017-09-14 21:41:53,339 [salt.state       ][INFO    ][27285] Host msg01 (10.167.4.41) already present
2017-09-14 21:41:53,340 [salt.state       ][INFO    ][27285] Completed state [msg01] at time 21:41:53.339566 duration_in_ms=0.818
2017-09-14 21:41:53,341 [salt.state       ][INFO    ][27285] Running state [file.replace] at time 21:41:53.340705
2017-09-14 21:41:53,341 [salt.state       ][INFO    ][27285] Executing state module.run for file.replace
2017-09-14 21:41:53,469 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command 'grep -q "msg01 msg01.baremetal-mcp-ocata-odl-ha.local" /etc/hosts' in directory '/root'
2017-09-14 21:41:53,481 [salt.state       ][INFO    ][27285] onlyif execution failed
2017-09-14 21:41:53,481 [salt.state       ][INFO    ][27285] Completed state [file.replace] at time 21:41:53.480883 duration_in_ms=140.176
2017-09-14 21:41:53,481 [salt.state       ][INFO    ][27285] Running state [msg] at time 21:41:53.481136
2017-09-14 21:41:53,481 [salt.state       ][INFO    ][27285] Executing state host.present for msg
2017-09-14 21:41:53,482 [salt.state       ][INFO    ][27285] Host msg (10.167.4.40) already present
2017-09-14 21:41:53,482 [salt.state       ][INFO    ][27285] Completed state [msg] at time 21:41:53.481987 duration_in_ms=0.851
2017-09-14 21:41:53,482 [salt.state       ][INFO    ][27285] Running state [msg.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.482154
2017-09-14 21:41:53,482 [salt.state       ][INFO    ][27285] Executing state host.present for msg.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,483 [salt.state       ][INFO    ][27285] Host msg.baremetal-mcp-ocata-odl-ha.local (10.167.4.40) already present
2017-09-14 21:41:53,483 [salt.state       ][INFO    ][27285] Completed state [msg.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.482788 duration_in_ms=0.634
2017-09-14 21:41:53,483 [salt.state       ][INFO    ][27285] Running state [file.replace] at time 21:41:53.483470
2017-09-14 21:41:53,484 [salt.state       ][INFO    ][27285] Executing state module.run for file.replace
2017-09-14 21:41:53,486 [salt.loaded.int.module.cmdmod][INFO    ][27285] Executing command 'grep -q "msg msg.baremetal-mcp-ocata-odl-ha.local" /etc/hosts' in directory '/root'
2017-09-14 21:41:53,498 [salt.state       ][INFO    ][27285] {'ret': '--- \n+++ \n@@ -18,7 +18,7 @@\n 10.167.4.42\t\tmsg02.baremetal-mcp-ocata-odl-ha.local msg02\n 10.167.4.43\t\tmsg03.baremetal-mcp-ocata-odl-ha.local msg03\n 10.167.4.41\t\tmsg01.baremetal-mcp-ocata-odl-ha.local msg01\n-10.167.4.40\t\tmsg msg.baremetal-mcp-ocata-odl-ha.local\n+10.167.4.40\t\tmsg.baremetal-mcp-ocata-odl-ha.local msg\n 10.167.4.102\t\tcmp002 cmp002.baremetal-mcp-ocata-odl-ha.local\n 10.167.4.101\t\tcmp001 cmp001.baremetal-mcp-ocata-odl-ha.local\n 10.167.4.51\t\tdbs01.baremetal-mcp-ocata-odl-ha.local dbs01\n'}
2017-09-14 21:41:53,498 [salt.state       ][INFO    ][27285] Completed state [file.replace] at time 21:41:53.497889 duration_in_ms=14.419
2017-09-14 21:41:53,498 [salt.state       ][INFO    ][27285] Running state [cfg01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.498094
2017-09-14 21:41:53,498 [salt.state       ][INFO    ][27285] Executing state host.present for cfg01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,499 [salt.state       ][INFO    ][27285] Host cfg01.baremetal-mcp-ocata-odl-ha.local (10.167.4.100) already present
2017-09-14 21:41:53,499 [salt.state       ][INFO    ][27285] Completed state [cfg01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.498800 duration_in_ms=0.707
2017-09-14 21:41:53,499 [salt.state       ][INFO    ][27285] Running state [cfg01] at time 21:41:53.498992
2017-09-14 21:41:53,499 [salt.state       ][INFO    ][27285] Executing state host.present for cfg01
2017-09-14 21:41:53,499 [salt.state       ][INFO    ][27285] Host cfg01 (10.167.4.100) already present
2017-09-14 21:41:53,500 [salt.state       ][INFO    ][27285] Completed state [cfg01] at time 21:41:53.499606 duration_in_ms=0.613
2017-09-14 21:41:53,500 [salt.state       ][INFO    ][27285] Running state [cmp002] at time 21:41:53.499764
2017-09-14 21:41:53,500 [salt.state       ][INFO    ][27285] Executing state host.present for cmp002
2017-09-14 21:41:53,500 [salt.state       ][INFO    ][27285] Host cmp002 (10.167.4.102) already present
2017-09-14 21:41:53,500 [salt.state       ][INFO    ][27285] Completed state [cmp002] at time 21:41:53.500348 duration_in_ms=0.585
2017-09-14 21:41:53,501 [salt.state       ][INFO    ][27285] Running state [cmp002.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.500504
2017-09-14 21:41:53,501 [salt.state       ][INFO    ][27285] Executing state host.present for cmp002.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,501 [salt.state       ][INFO    ][27285] Host cmp002.baremetal-mcp-ocata-odl-ha.local (10.167.4.102) already present
2017-09-14 21:41:53,501 [salt.state       ][INFO    ][27285] Completed state [cmp002.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.501089 duration_in_ms=0.585
2017-09-14 21:41:53,501 [salt.state       ][INFO    ][27285] Running state [cmp001] at time 21:41:53.501241
2017-09-14 21:41:53,501 [salt.state       ][INFO    ][27285] Executing state host.present for cmp001
2017-09-14 21:41:53,502 [salt.state       ][INFO    ][27285] Host cmp001 (10.167.4.101) already present
2017-09-14 21:41:53,502 [salt.state       ][INFO    ][27285] Completed state [cmp001] at time 21:41:53.501830 duration_in_ms=0.589
2017-09-14 21:41:53,502 [salt.state       ][INFO    ][27285] Running state [cmp001.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.501984
2017-09-14 21:41:53,502 [salt.state       ][INFO    ][27285] Executing state host.present for cmp001.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,502 [salt.state       ][INFO    ][27285] Host cmp001.baremetal-mcp-ocata-odl-ha.local (10.167.4.101) already present
2017-09-14 21:41:53,503 [salt.state       ][INFO    ][27285] Completed state [cmp001.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.502569 duration_in_ms=0.584
2017-09-14 21:41:53,503 [salt.state       ][INFO    ][27285] Running state [dbs01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.502764
2017-09-14 21:41:53,503 [salt.state       ][INFO    ][27285] Executing state host.present for dbs01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,503 [salt.state       ][INFO    ][27285] Host dbs01.baremetal-mcp-ocata-odl-ha.local (10.167.4.51) already present
2017-09-14 21:41:53,503 [salt.state       ][INFO    ][27285] Completed state [dbs01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.503373 duration_in_ms=0.608
2017-09-14 21:41:53,504 [salt.state       ][INFO    ][27285] Running state [dbs01] at time 21:41:53.503537
2017-09-14 21:41:53,504 [salt.state       ][INFO    ][27285] Executing state host.present for dbs01
2017-09-14 21:41:53,504 [salt.state       ][INFO    ][27285] Host dbs01 (10.167.4.51) already present
2017-09-14 21:41:53,504 [salt.state       ][INFO    ][27285] Completed state [dbs01] at time 21:41:53.504128 duration_in_ms=0.591
2017-09-14 21:41:53,504 [salt.state       ][INFO    ][27285] Running state [dbs02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.504286
2017-09-14 21:41:53,504 [salt.state       ][INFO    ][27285] Executing state host.present for dbs02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,505 [salt.state       ][INFO    ][27285] Host dbs02.baremetal-mcp-ocata-odl-ha.local (10.167.4.52) already present
2017-09-14 21:41:53,505 [salt.state       ][INFO    ][27285] Completed state [dbs02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.504878 duration_in_ms=0.592
2017-09-14 21:41:53,505 [salt.state       ][INFO    ][27285] Running state [dbs02] at time 21:41:53.505031
2017-09-14 21:41:53,505 [salt.state       ][INFO    ][27285] Executing state host.present for dbs02
2017-09-14 21:41:53,506 [salt.state       ][INFO    ][27285] Host dbs02 (10.167.4.52) already present
2017-09-14 21:41:53,506 [salt.state       ][INFO    ][27285] Completed state [dbs02] at time 21:41:53.505623 duration_in_ms=0.591
2017-09-14 21:41:53,506 [salt.state       ][INFO    ][27285] Running state [dbs03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.505774
2017-09-14 21:41:53,506 [salt.state       ][INFO    ][27285] Executing state host.present for dbs03.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,506 [salt.state       ][INFO    ][27285] Host dbs03.baremetal-mcp-ocata-odl-ha.local (10.167.4.53) already present
2017-09-14 21:41:53,506 [salt.state       ][INFO    ][27285] Completed state [dbs03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.506357 duration_in_ms=0.583
2017-09-14 21:41:53,507 [salt.state       ][INFO    ][27285] Running state [dbs03] at time 21:41:53.506508
2017-09-14 21:41:53,507 [salt.state       ][INFO    ][27285] Executing state host.present for dbs03
2017-09-14 21:41:53,507 [salt.state       ][INFO    ][27285] Host dbs03 (10.167.4.53) already present
2017-09-14 21:41:53,507 [salt.state       ][INFO    ][27285] Completed state [dbs03] at time 21:41:53.507352 duration_in_ms=0.843
2017-09-14 21:41:53,508 [salt.state       ][INFO    ][27285] Running state [odl01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.507510
2017-09-14 21:41:53,508 [salt.state       ][INFO    ][27285] Executing state host.present for odl01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,508 [salt.state       ][INFO    ][27285] Host odl01.baremetal-mcp-ocata-odl-ha.local (10.167.4.111) already present
2017-09-14 21:41:53,508 [salt.state       ][INFO    ][27285] Completed state [odl01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.508092 duration_in_ms=0.581
2017-09-14 21:41:53,508 [salt.state       ][INFO    ][27285] Running state [odl01] at time 21:41:53.508245
2017-09-14 21:41:53,508 [salt.state       ][INFO    ][27285] Executing state host.present for odl01
2017-09-14 21:41:53,509 [salt.state       ][INFO    ][27285] Host odl01 (10.167.4.111) already present
2017-09-14 21:41:53,509 [salt.state       ][INFO    ][27285] Completed state [odl01] at time 21:41:53.508821 duration_in_ms=0.576
2017-09-14 21:41:53,509 [salt.state       ][INFO    ][27285] Running state [mas01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.508982
2017-09-14 21:41:53,509 [salt.state       ][INFO    ][27285] Executing state host.present for mas01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,509 [salt.state       ][INFO    ][27285] Host mas01.baremetal-mcp-ocata-odl-ha.local (10.167.4.3) already present
2017-09-14 21:41:53,510 [salt.state       ][INFO    ][27285] Completed state [mas01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.509564 duration_in_ms=0.581
2017-09-14 21:41:53,510 [salt.state       ][INFO    ][27285] Running state [mas01] at time 21:41:53.509714
2017-09-14 21:41:53,510 [salt.state       ][INFO    ][27285] Executing state host.present for mas01
2017-09-14 21:41:53,510 [salt.state       ][INFO    ][27285] Host mas01 (10.167.4.3) already present
2017-09-14 21:41:53,510 [salt.state       ][INFO    ][27285] Completed state [mas01] at time 21:41:53.510308 duration_in_ms=0.594
2017-09-14 21:41:53,510 [salt.state       ][INFO    ][27285] Running state [ctl02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.510462
2017-09-14 21:41:53,511 [salt.state       ][INFO    ][27285] Executing state host.present for ctl02.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,511 [salt.state       ][INFO    ][27285] Host ctl02.baremetal-mcp-ocata-odl-ha.local (10.167.4.12) already present
2017-09-14 21:41:53,511 [salt.state       ][INFO    ][27285] Completed state [ctl02.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.511073 duration_in_ms=0.611
2017-09-14 21:41:53,511 [salt.state       ][INFO    ][27285] Running state [ctl02] at time 21:41:53.511225
2017-09-14 21:41:53,511 [salt.state       ][INFO    ][27285] Executing state host.present for ctl02
2017-09-14 21:41:53,512 [salt.state       ][INFO    ][27285] Host ctl02 (10.167.4.12) already present
2017-09-14 21:41:53,512 [salt.state       ][INFO    ][27285] Completed state [ctl02] at time 21:41:53.511800 duration_in_ms=0.574
2017-09-14 21:41:53,512 [salt.state       ][INFO    ][27285] Running state [ctl03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.511952
2017-09-14 21:41:53,512 [salt.state       ][INFO    ][27285] Executing state host.present for ctl03.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,512 [salt.state       ][INFO    ][27285] Host ctl03.baremetal-mcp-ocata-odl-ha.local (10.167.4.13) already present
2017-09-14 21:41:53,513 [salt.state       ][INFO    ][27285] Completed state [ctl03.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.512524 duration_in_ms=0.572
2017-09-14 21:41:53,513 [salt.state       ][INFO    ][27285] Running state [ctl03] at time 21:41:53.512676
2017-09-14 21:41:53,513 [salt.state       ][INFO    ][27285] Executing state host.present for ctl03
2017-09-14 21:41:53,513 [salt.state       ][INFO    ][27285] Host ctl03 (10.167.4.13) already present
2017-09-14 21:41:53,513 [salt.state       ][INFO    ][27285] Completed state [ctl03] at time 21:41:53.513247 duration_in_ms=0.571
2017-09-14 21:41:53,513 [salt.state       ][INFO    ][27285] Running state [ctl01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.513403
2017-09-14 21:41:53,514 [salt.state       ][INFO    ][27285] Executing state host.present for ctl01.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,514 [salt.state       ][INFO    ][27285] Host ctl01.baremetal-mcp-ocata-odl-ha.local (10.167.4.11) already present
2017-09-14 21:41:53,514 [salt.state       ][INFO    ][27285] Completed state [ctl01.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.513971 duration_in_ms=0.568
2017-09-14 21:41:53,514 [salt.state       ][INFO    ][27285] Running state [ctl01] at time 21:41:53.514119
2017-09-14 21:41:53,514 [salt.state       ][INFO    ][27285] Executing state host.present for ctl01
2017-09-14 21:41:53,515 [salt.state       ][INFO    ][27285] Host ctl01 (10.167.4.11) already present
2017-09-14 21:41:53,515 [salt.state       ][INFO    ][27285] Completed state [ctl01] at time 21:41:53.514703 duration_in_ms=0.583
2017-09-14 21:41:53,515 [salt.state       ][INFO    ][27285] Running state [ctl] at time 21:41:53.514853
2017-09-14 21:41:53,515 [salt.state       ][INFO    ][27285] Executing state host.present for ctl
2017-09-14 21:41:53,515 [salt.state       ][INFO    ][27285] Host ctl (10.167.4.10) already present
2017-09-14 21:41:53,515 [salt.state       ][INFO    ][27285] Completed state [ctl] at time 21:41:53.515442 duration_in_ms=0.589
2017-09-14 21:41:53,516 [salt.state       ][INFO    ][27285] Running state [ctl.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.515595
2017-09-14 21:41:53,516 [salt.state       ][INFO    ][27285] Executing state host.present for ctl.baremetal-mcp-ocata-odl-ha.local
2017-09-14 21:41:53,516 [salt.state       ][INFO    ][27285] Host ctl.baremetal-mcp-ocata-odl-ha.local (10.167.4.10) already present
2017-09-14 21:41:53,516 [salt.state       ][INFO    ][27285] Completed state [ctl.baremetal-mcp-ocata-odl-ha.local] at time 21:41:53.516171 duration_in_ms=0.576
2017-09-14 21:41:53,517 [salt.state       ][INFO    ][27285] Running state [rabbitmqctl set_cluster_name openstack && echo "openstack" > /var/lib/rabbitmq/.cluster_name
] at time 21:41:53.516594
2017-09-14 21:41:53,517 [salt.state       ][INFO    ][27285] Executing state cmd.run for rabbitmqctl set_cluster_name openstack && echo "openstack" > /var/lib/rabbitmq/.cluster_name

2017-09-14 21:41:53,517 [salt.state       ][INFO    ][27285] /var/lib/rabbitmq/.cluster_name exists
2017-09-14 21:41:53,517 [salt.state       ][INFO    ][27285] Completed state [rabbitmqctl set_cluster_name openstack && echo "openstack" > /var/lib/rabbitmq/.cluster_name
] at time 21:41:53.517131 duration_in_ms=0.537
2017-09-14 21:41:53,518 [salt.minion      ][INFO    ][27285] Returning information for job: 20170914214126687569
2017-09-14 21:44:10,088 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command cmd.run with jid 20170914214410078688
2017-09-14 21:44:10,113 [salt.minion      ][INFO    ][31155] Starting a new job with PID 31155
2017-09-14 21:44:10,121 [salt.loaded.int.module.cmdmod][INFO    ][31155] Executing command 'rabbitmqctl cluster_status' in directory '/root'
2017-09-14 21:44:11,164 [salt.minion      ][INFO    ][31155] Returning information for job: 20170914214410078688
2017-09-14 21:51:42,817 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command state.sls with jid 20170914215142810808
2017-09-14 21:51:42,841 [salt.minion      ][INFO    ][31775] Starting a new job with PID 31775
2017-09-14 21:51:44,499 [salt.state       ][INFO    ][31775] Loading fresh modules for state activity
2017-09-14 21:51:44,540 [salt.fileclient  ][INFO    ][31775] Fetching file from saltenv 'base', ** done ** 'haproxy/init.sls'
2017-09-14 21:51:44,562 [salt.fileclient  ][INFO    ][31775] Fetching file from saltenv 'base', ** done ** 'haproxy/proxy.sls'
2017-09-14 21:51:44,594 [salt.fileclient  ][INFO    ][31775] Fetching file from saltenv 'base', ** done ** 'haproxy/map.jinja'
2017-09-14 21:51:44,913 [salt.state       ][INFO    ][31775] Running state [haproxy] at time 21:51:44.912919
2017-09-14 21:51:44,944 [salt.state       ][INFO    ][31775] Executing state pkg.installed for haproxy
2017-09-14 21:51:44,945 [salt.loaded.int.module.cmdmod][INFO    ][31775] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-14 21:51:45,613 [salt.loaded.int.module.cmdmod][INFO    ][31775] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-14 21:51:48,744 [salt.loaded.int.module.cmdmod][INFO    ][31775] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'haproxy'] in directory '/root'
2017-09-14 21:51:52,907 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914215152899410
2017-09-14 21:51:52,930 [salt.minion      ][INFO    ][32352] Starting a new job with PID 32352
2017-09-14 21:51:52,944 [salt.minion      ][INFO    ][32352] Returning information for job: 20170914215152899410
2017-09-14 21:52:02,965 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914215202957820
2017-09-14 21:52:02,993 [salt.minion      ][INFO    ][32496] Starting a new job with PID 32496
2017-09-14 21:52:03,008 [salt.minion      ][INFO    ][32496] Returning information for job: 20170914215202957820
2017-09-14 21:52:03,618 [salt.loaded.int.module.cmdmod][INFO    ][31775] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-14 21:52:03,648 [salt.state       ][INFO    ][31775] Made the following changes:
'haproxy' changed from 'absent' to '1.6.3-1~u16.04+mcp1'
'liblua5.3-0' changed from 'absent' to '5.3.1-1ubuntu2'

2017-09-14 21:52:03,660 [salt.state       ][INFO    ][31775] Loading fresh modules for state activity
2017-09-14 21:52:03,674 [salt.state       ][INFO    ][31775] Completed state [haproxy] at time 21:52:03.673565 duration_in_ms=18760.647
2017-09-14 21:52:03,676 [salt.state       ][INFO    ][31775] Running state [/etc/default/haproxy] at time 21:52:03.676194
2017-09-14 21:52:03,676 [salt.state       ][INFO    ][31775] Executing state file.managed for /etc/default/haproxy
2017-09-14 21:52:03,701 [salt.fileclient  ][INFO    ][31775] Fetching file from saltenv 'base', ** done ** 'haproxy/files/haproxy.default'
2017-09-14 21:52:03,704 [salt.state       ][INFO    ][31775] File changed:
--- 
+++ 
@@ -1,10 +1,5 @@
-# Defaults file for HAProxy
-#
-# This is sourced by both, the initscript and the systemd unit file, so do not
-# treat it as a shell script fragment.
 
-# Change the config file location if needed
-#CONFIG="/etc/haproxy/haproxy.cfg"
-
-# Add extra flags here, see haproxy(1) for a few options
+# Set ENABLED to 1 if you want the init script to start haproxy.
+ENABLED=1
+# Add extra flags here.
 #EXTRAOPTS="-de -m 16"

2017-09-14 21:52:03,704 [salt.state       ][INFO    ][31775] Completed state [/etc/default/haproxy] at time 21:52:03.703793 duration_in_ms=27.598
2017-09-14 21:52:03,704 [salt.state       ][INFO    ][31775] Running state [/etc/haproxy/haproxy.cfg] at time 21:52:03.704125
2017-09-14 21:52:03,704 [salt.state       ][INFO    ][31775] Executing state file.managed for /etc/haproxy/haproxy.cfg
2017-09-14 21:52:03,721 [salt.fileclient  ][INFO    ][31775] Fetching file from saltenv 'base', ** done ** 'haproxy/files/haproxy.cfg'
2017-09-14 21:52:03,822 [salt.fileclient  ][INFO    ][31775] Fetching file from saltenv 'base', ** done ** 'haproxy/map.jinja'
2017-09-14 21:52:03,838 [salt.state       ][INFO    ][31775] File changed:
--- 
+++ 
@@ -1,35 +1,49 @@
 global
-	log /dev/log	local0
-	log /dev/log	local1 notice
-	chroot /var/lib/haproxy
-	stats socket /run/haproxy/admin.sock mode 660 level admin
-	stats timeout 30s
-	user haproxy
-	group haproxy
-	daemon
-
-	# Default SSL material locations
-	ca-base /etc/ssl/certs
-	crt-base /etc/ssl/private
-
-	# Default ciphers to use on SSL-enabled listening sockets.
-	# For more information, see ciphers(1SSL). This list is from:
-	#  https://hynek.me/articles/hardening-your-web-servers-ssl-ciphers/
-	ssl-default-bind-ciphers ECDH+AESGCM:DH+AESGCM:ECDH+AES256:DH+AES256:ECDH+AES128:DH+AES:ECDH+3DES:DH+3DES:RSA+AESGCM:RSA+AES:RSA+3DES:!aNULL:!MD5:!DSS
-	ssl-default-bind-options no-sslv3
+  log /dev/log  local0
+  log /dev/log  local1 notice
+  chroot /var/lib/haproxy
+  stats  socket /run/haproxy/admin.sock mode 660 level admin
+  stats timeout 30s
+  user  haproxy
+  group haproxy
+  daemon
+  pidfile  /var/run/haproxy.pid
+  spread-checks 4
+  tune.maxrewrite 1024
+  tune.bufsize 32768
+  maxconn  16000
+  # SSL options
+  ca-base /etc/haproxy/ssl
+  crt-base /etc/haproxy/ssl
+  tune.ssl.default-dh-param 2048
+  ssl-default-bind-ciphers ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-CHACHA20-POLY1305:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-RSA-AES256-GCM-SHA384:DHE-RSA-AES128-GCM-SHA256:DHE-RSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-RSA-AES128-SHA256:ECDHE-ECDSA-AES128-SHA:ECDHE-RSA-AES256-SHA384:ECDHE-RSA-AES128-SHA:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES256-SHA:ECDHE-RSA-AES256-SHA:DHE-RSA-AES128-SHA256:DHE-RSA-AES128-SHA:DHE-RSA-AES256-SHA256:DHE-RSA-AES256-SHA:ECDHE-ECDSA-DES-CBC3-SHA:ECDHE-RSA-DES-CBC3-SHA:EDH-RSA-DES-CBC3-SHA:AES128-GCM-SHA256:AES256-GCM-SHA384:AES128-SHA256:AES256-SHA256:AES128-SHA:AES256-SHA:DES-CBC3-SHA:!DSS
+  ssl-default-bind-options no-sslv3 no-tls-tickets
+  ssl-default-server-ciphers ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-CHACHA20-POLY1305:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-RSA-AES256-GCM-SHA384:DHE-RSA-AES128-GCM-SHA256:DHE-RSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-RSA-AES128-SHA256:ECDHE-ECDSA-AES128-SHA:ECDHE-RSA-AES256-SHA384:ECDHE-RSA-AES128-SHA:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES256-SHA:ECDHE-RSA-AES256-SHA:DHE-RSA-AES128-SHA256:DHE-RSA-AES128-SHA:DHE-RSA-AES256-SHA256:DHE-RSA-AES256-SHA:ECDHE-ECDSA-DES-CBC3-SHA:ECDHE-RSA-DES-CBC3-SHA:EDH-RSA-DES-CBC3-SHA:AES128-GCM-SHA256:AES256-GCM-SHA384:AES128-SHA256:AES256-SHA256:AES128-SHA:AES256-SHA:DES-CBC3-SHA:!DSS
+  ssl-default-server-options no-sslv3 no-tls-tickets
 
 defaults
-	log	global
-	mode	http
-	option	httplog
-	option	dontlognull
-        timeout connect 5000
-        timeout client  50000
-        timeout server  50000
-	errorfile 400 /etc/haproxy/errors/400.http
-	errorfile 403 /etc/haproxy/errors/403.http
-	errorfile 408 /etc/haproxy/errors/408.http
-	errorfile 500 /etc/haproxy/errors/500.http
-	errorfile 502 /etc/haproxy/errors/502.http
-	errorfile 503 /etc/haproxy/errors/503.http
-	errorfile 504 /etc/haproxy/errors/504.http
+  log  global
+  mode http
+
+  maxconn 8000
+  option  redispatch
+  retries  3
+  stats  enable
+
+  timeout http-request 10s
+  timeout queue 1m
+  timeout connect 10s
+  timeout client 1m
+  timeout server 1m
+  timeout check 10s
+
+listen rabbitmq_cluster
+  bind 10.167.4.40:5672 
+  balance roundrobin
+  mode tcp
+  option tcpka
+  timeout client 300s
+  timeout server 300s
+  server msg01 10.167.4.41:5672 check inter 5000 rise 2 fall 3
+  server msg02 10.167.4.42:5672 backup check inter 5000 rise 2 fall 3
+  server msg03 10.167.4.43:5672 backup check inter 5000 rise 2 fall 3

2017-09-14 21:52:03,840 [salt.state       ][INFO    ][31775] Completed state [/etc/haproxy/haproxy.cfg] at time 21:52:03.839736 duration_in_ms=135.603
2017-09-14 21:52:03,840 [salt.state       ][INFO    ][31775] Running state [/etc/haproxy/ssl] at time 21:52:03.840214
2017-09-14 21:52:03,841 [salt.state       ][INFO    ][31775] Executing state file.directory for /etc/haproxy/ssl
2017-09-14 21:52:03,842 [salt.state       ][INFO    ][31775] {'/etc/haproxy/ssl': 'New Dir'}
2017-09-14 21:52:03,842 [salt.state       ][INFO    ][31775] Completed state [/etc/haproxy/ssl] at time 21:52:03.842401 duration_in_ms=2.187
2017-09-14 21:52:03,844 [salt.state       ][INFO    ][31775] Running state [haproxy_status_packages] at time 21:52:03.843767
2017-09-14 21:52:03,844 [salt.state       ][INFO    ][31775] Executing state pkg.installed for haproxy_status_packages
2017-09-14 21:52:04,056 [salt.state       ][INFO    ][31775] All specified packages are already installed
2017-09-14 21:52:04,057 [salt.state       ][INFO    ][31775] Completed state [haproxy_status_packages] at time 21:52:04.056626 duration_in_ms=212.86
2017-09-14 21:52:04,057 [salt.state       ][INFO    ][31775] Running state [/usr/bin/haproxy-status.sh] at time 21:52:04.057043
2017-09-14 21:52:04,057 [salt.state       ][INFO    ][31775] Executing state file.managed for /usr/bin/haproxy-status.sh
2017-09-14 21:52:04,078 [salt.fileclient  ][INFO    ][31775] Fetching file from saltenv 'base', ** done ** 'haproxy/files/haproxy-status.sh'
2017-09-14 21:52:04,095 [salt.fileclient  ][INFO    ][31775] Fetching file from saltenv 'base', ** done ** 'haproxy/map.jinja'
2017-09-14 21:52:04,100 [salt.state       ][INFO    ][31775] File changed:
New file
2017-09-14 21:52:04,100 [salt.state       ][INFO    ][31775] Completed state [/usr/bin/haproxy-status.sh] at time 21:52:04.100107 duration_in_ms=43.063
2017-09-14 21:52:04,101 [salt.state       ][INFO    ][31775] Running state [net.ipv4.ip_nonlocal_bind] at time 21:52:04.100550
2017-09-14 21:52:04,101 [salt.state       ][INFO    ][31775] Executing state sysctl.present for net.ipv4.ip_nonlocal_bind
2017-09-14 21:52:04,101 [salt.loaded.int.module.cmdmod][INFO    ][31775] Executing command 'sysctl -a' in directory '/root'
2017-09-14 21:52:04,119 [salt.loaded.int.module.cmdmod][INFO    ][31775] Executing command 'sysctl -w net.ipv4.ip_nonlocal_bind="1"' in directory '/root'
2017-09-14 21:52:04,127 [salt.state       ][INFO    ][31775] {'net.ipv4.ip_nonlocal_bind': 1}
2017-09-14 21:52:04,127 [salt.state       ][INFO    ][31775] Completed state [net.ipv4.ip_nonlocal_bind] at time 21:52:04.126817 duration_in_ms=26.267
2017-09-14 21:52:04,128 [salt.state       ][INFO    ][31775] Running state [haproxy] at time 21:52:04.127917
2017-09-14 21:52:04,128 [salt.state       ][INFO    ][31775] Executing state service.running for haproxy
2017-09-14 21:52:04,129 [salt.loaded.int.module.cmdmod][INFO    ][31775] Executing command ['systemctl', 'status', 'haproxy.service', '-n', '0'] in directory '/root'
2017-09-14 21:52:04,142 [salt.loaded.int.module.cmdmod][INFO    ][31775] Executing command ['systemctl', 'is-active', 'haproxy.service'] in directory '/root'
2017-09-14 21:52:04,153 [salt.loaded.int.module.cmdmod][INFO    ][31775] Executing command ['systemctl', 'is-enabled', 'haproxy.service'] in directory '/root'
2017-09-14 21:52:04,163 [salt.state       ][INFO    ][31775] The service haproxy is already running
2017-09-14 21:52:04,163 [salt.state       ][INFO    ][31775] Completed state [haproxy] at time 21:52:04.163258 duration_in_ms=35.341
2017-09-14 21:52:04,163 [salt.state       ][INFO    ][31775] Running state [haproxy] at time 21:52:04.163455
2017-09-14 21:52:04,164 [salt.state       ][INFO    ][31775] Executing state service.mod_watch for haproxy
2017-09-14 21:52:04,164 [salt.loaded.int.module.cmdmod][INFO    ][31775] Executing command ['systemctl', 'is-active', 'haproxy.service'] in directory '/root'
2017-09-14 21:52:04,178 [salt.loaded.int.module.cmdmod][INFO    ][31775] Executing command ['systemctl', 'is-enabled', 'haproxy.service'] in directory '/root'
2017-09-14 21:52:04,192 [salt.loaded.int.module.cmdmod][INFO    ][31775] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'haproxy.service'] in directory '/root'
2017-09-14 21:52:04,245 [salt.state       ][INFO    ][31775] {'haproxy': True}
2017-09-14 21:52:04,245 [salt.state       ][INFO    ][31775] Completed state [haproxy] at time 21:52:04.245014 duration_in_ms=81.557
2017-09-14 21:52:04,246 [salt.minion      ][INFO    ][31775] Returning information for job: 20170914215142810808
2017-09-14 21:52:11,286 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command service.status with jid 20170914215211283382
2017-09-14 21:52:11,309 [salt.minion      ][INFO    ][32570] Starting a new job with PID 32570
2017-09-14 21:52:11,469 [salt.loaded.int.module.cmdmod][INFO    ][32570] Executing command ['systemctl', 'status', 'haproxy.service', '-n', '0'] in directory '/root'
2017-09-14 21:52:11,482 [salt.loaded.int.module.cmdmod][INFO    ][32570] Executing command ['systemctl', 'is-active', 'haproxy.service'] in directory '/root'
2017-09-14 21:52:11,495 [salt.minion      ][INFO    ][32570] Returning information for job: 20170914215211283382
2017-09-14 21:52:12,701 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command service.restart with jid 20170914215212693009
2017-09-14 21:52:12,732 [salt.minion      ][INFO    ][32578] Starting a new job with PID 32578
2017-09-14 21:52:12,888 [salt.loaded.int.module.cmdmod][INFO    ][32578] Executing command ['systemctl', 'status', 'rsyslog.service', '-n', '0'] in directory '/root'
2017-09-14 21:52:12,904 [salt.loaded.int.module.cmdmod][INFO    ][32578] Executing command ['systemctl', 'is-enabled', 'rsyslog.service'] in directory '/root'
2017-09-14 21:52:12,923 [salt.loaded.int.module.cmdmod][INFO    ][32578] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'rsyslog.service'] in directory '/root'
2017-09-14 21:52:12,967 [salt.minion      ][INFO    ][32578] Returning information for job: 20170914215212693009
2017-09-14 22:18:35,326 [salt.utils.schedule][INFO    ][12711] Running scheduled job: __mine_interval
2017-09-14 22:40:49,120 [salt.minion      ][INFO    ][12711] User sudo_ubuntu Executing command cp.push_dir with jid 20170914224049113087
2017-09-14 22:40:49,159 [salt.minion      ][INFO    ][2919] Starting a new job with PID 2919
