2018-10-06 14:29:31,251 [salt.loaded.int.module.cmdmod:722 ][ERROR   ][1846] Command 'rabbitmqctl cluster_status' failed with return code: 127
2018-10-06 14:29:31,252 [salt.loaded.int.module.cmdmod:724 ][ERROR   ][1846] stdout: /bin/sh: 1: rabbitmqctl: not found
2018-10-06 14:29:31,252 [salt.loaded.int.module.cmdmod:728 ][ERROR   ][1846] retcode: 127
2018-10-06 14:29:31,274 [salt.loaded.int.module.cmdmod:722 ][ERROR   ][1846] Command 'rabbitmqctl cluster_status' failed with return code: 127
2018-10-06 14:29:31,275 [salt.loaded.int.module.cmdmod:724 ][ERROR   ][1846] stdout: /bin/sh: 1: rabbitmqctl: not found
2018-10-06 14:29:31,275 [salt.loaded.int.module.cmdmod:728 ][ERROR   ][1846] retcode: 127
2018-10-06 14:29:32,691 [salt.loaded.int.module.cmdmod:722 ][ERROR   ][1846] Command 'rabbitmqctl cluster_status' failed with return code: 127
2018-10-06 14:29:32,692 [salt.loaded.int.module.cmdmod:724 ][ERROR   ][1846] stdout: /bin/sh: 1: rabbitmqctl: not found
2018-10-06 14:29:32,692 [salt.loaded.int.module.cmdmod:728 ][ERROR   ][1846] retcode: 127
2018-10-06 14:29:32,714 [salt.loaded.int.module.cmdmod:722 ][ERROR   ][1846] Command 'rabbitmqctl cluster_status' failed with return code: 127
2018-10-06 14:29:32,715 [salt.loaded.int.module.cmdmod:724 ][ERROR   ][1846] stdout: /bin/sh: 1: rabbitmqctl: not found
2018-10-06 14:29:32,715 [salt.loaded.int.module.cmdmod:728 ][ERROR   ][1846] retcode: 127
2018-10-06 14:29:48,061 [salt.utils.decorators:613 ][WARNING ][1846] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-10-06 14:29:51,277 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2887] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2018-10-06 14:29:51,304 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2887] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2018-10-06 14:29:51,323 [salt.utils.parsers:1051][WARNING ][1512] Minion received a SIGTERM. Exiting.
2018-10-06 14:29:52,180 [salt.cli.daemons :293 ][INFO    ][2947] Setting up the Salt Minion "msg02.mcp-pike-odl-ha.local"
2018-10-06 14:29:52,278 [salt.cli.daemons :82  ][INFO    ][2947] Starting up the Salt Minion
2018-10-06 14:29:52,279 [salt.utils.event :1017][INFO    ][2947] Starting pull socket on /var/run/salt/minion/minion_event_dda4868e5c_pull.ipc
2018-10-06 14:29:52,862 [salt.minion      :976 ][INFO    ][2947] Creating minion process manager
2018-10-06 14:29:53,923 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][2947] Executing command ['date', '+%z'] in directory '/root'
2018-10-06 14:29:53,943 [salt.utils.schedule:568 ][INFO    ][2947] Updating job settings for scheduled job: __mine_interval
2018-10-06 14:29:53,947 [salt.minion      :1107][INFO    ][2947] Added mine.update to scheduler
2018-10-06 14:29:53,952 [salt.minion      :1965][INFO    ][2947] Minion is starting as user 'root'
2018-10-06 14:29:53,966 [salt.minion      :2324][INFO    ][2947] Minion is ready to receive requests!
2018-10-06 14:30:19,925 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command state.apply with jid 20181006143019913667
2018-10-06 14:30:19,942 [salt.minion      :1431][INFO    ][3037] Starting a new job with PID 3037
2018-10-06 14:30:23,089 [salt.state       :905 ][INFO    ][3037] Loading fresh modules for state activity
2018-10-06 14:30:23,158 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/init.sls'
2018-10-06 14:30:23,198 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/init.sls'
2018-10-06 14:30:23,338 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/env.sls'
2018-10-06 14:30:24,890 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/profile.sls'
2018-10-06 14:30:25,076 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/shell.sls'
2018-10-06 14:30:25,208 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/at.sls'
2018-10-06 14:30:25,323 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/cron.sls'
2018-10-06 14:30:25,817 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/repo.sls'
2018-10-06 14:30:26,064 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/package.sls'
2018-10-06 14:30:26,203 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/timezone.sls'
2018-10-06 14:30:26,323 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/kernel.sls'
2018-10-06 14:30:26,492 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/grub.sls'
2018-10-06 14:30:26,529 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/cpu.sls'
2018-10-06 14:30:26,654 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/sysfs.sls'
2018-10-06 14:30:26,779 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/locale.sls'
2018-10-06 14:30:26,899 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/user.sls'
2018-10-06 14:30:27,052 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/group.sls'
2018-10-06 14:30:27,175 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/limit.sls'
2018-10-06 14:30:27,284 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/systemd.sls'
2018-10-06 14:30:27,410 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/apt.sls'
2018-10-06 14:30:27,523 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/system/banner.sls'
2018-10-06 14:30:27,635 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/network/init.sls'
2018-10-06 14:30:27,767 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/network/hostname.sls'
2018-10-06 14:30:27,895 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/network/host.sls'
2018-10-06 14:30:28,109 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/network/interface.sls'
2018-10-06 14:30:28,345 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/network/proxy.sls'
2018-10-06 14:30:28,476 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/storage/init.sls'
2018-10-06 14:30:28,607 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'ntp/init.sls'
2018-10-06 14:30:28,631 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'ntp/client.sls'
2018-10-06 14:30:28,681 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'ntp/server.sls'
2018-10-06 14:30:28,723 [salt.state       :1770][INFO    ][3037] Running state [/etc/environment] at time 14:30:28.723831
2018-10-06 14:30:28,724 [salt.state       :1803][INFO    ][3037] Executing state file.blockreplace for [/etc/environment]
2018-10-06 14:30:28,731 [salt.state       :290 ][INFO    ][3037] File changed:
--- 
+++ 
@@ -1 +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

2018-10-06 14:30:28,732 [salt.state       :1941][INFO    ][3037] Completed state [/etc/environment] at time 14:30:28.732104 duration_in_ms=8.274
2018-10-06 14:30:28,732 [salt.state       :1770][INFO    ][3037] Running state [/etc/profile.d] at time 14:30:28.732335
2018-10-06 14:30:28,732 [salt.state       :1803][INFO    ][3037] Executing state file.directory for [/etc/profile.d]
2018-10-06 14:30:28,734 [salt.state       :290 ][INFO    ][3037] Directory /etc/profile.d is in the correct state
Directory /etc/profile.d updated
2018-10-06 14:30:28,735 [salt.state       :1941][INFO    ][3037] Completed state [/etc/profile.d] at time 14:30:28.734621 duration_in_ms=2.286
2018-10-06 14:30:28,736 [salt.state       :1770][INFO    ][3037] Running state [fake_linux_system_at] at time 14:30:28.736256
2018-10-06 14:30:28,736 [salt.state       :1803][INFO    ][3037] Executing state test.nop for [fake_linux_system_at]
2018-10-06 14:30:28,736 [salt.state       :290 ][INFO    ][3037] Success!
2018-10-06 14:30:28,737 [salt.state       :1941][INFO    ][3037] Completed state [fake_linux_system_at] at time 14:30:28.736991 duration_in_ms=0.735
2018-10-06 14:30:28,737 [salt.state       :1770][INFO    ][3037] Running state [fake_linux_system_cron] at time 14:30:28.737244
2018-10-06 14:30:28,737 [salt.state       :1803][INFO    ][3037] Executing state test.nop for [fake_linux_system_cron]
2018-10-06 14:30:28,737 [salt.state       :290 ][INFO    ][3037] Success!
2018-10-06 14:30:28,738 [salt.state       :1941][INFO    ][3037] Completed state [fake_linux_system_cron] at time 14:30:28.737973 duration_in_ms=0.728
2018-10-06 14:30:29,354 [salt.state       :1770][INFO    ][3037] Running state [/etc/apt/apt.conf.d/99prefer_ipv4-salt] at time 14:30:29.354236
2018-10-06 14:30:29,355 [salt.state       :1803][INFO    ][3037] Executing state file.managed for [/etc/apt/apt.conf.d/99prefer_ipv4-salt]
2018-10-06 14:30:29,379 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/files/apt.conf'
2018-10-06 14:30:29,392 [salt.state       :290 ][INFO    ][3037] File changed:
New file
2018-10-06 14:30:29,393 [salt.state       :1941][INFO    ][3037] Completed state [/etc/apt/apt.conf.d/99prefer_ipv4-salt] at time 14:30:29.393018 duration_in_ms=38.782
2018-10-06 14:30:29,393 [salt.state       :1770][INFO    ][3037] Running state [/etc/apt/apt.conf.d/99allow_downgrades-salt] at time 14:30:29.393279
2018-10-06 14:30:29,393 [salt.state       :1803][INFO    ][3037] Executing state file.managed for [/etc/apt/apt.conf.d/99allow_downgrades-salt]
2018-10-06 14:30:29,415 [salt.state       :290 ][INFO    ][3037] File changed:
New file
2018-10-06 14:30:29,415 [salt.state       :1941][INFO    ][3037] Completed state [/etc/apt/apt.conf.d/99allow_downgrades-salt] at time 14:30:29.415577 duration_in_ms=22.298
2018-10-06 14:30:29,416 [salt.state       :1770][INFO    ][3037] Running state [linux_repo_prereq_pkgs] at time 14:30:29.416856
2018-10-06 14:30:29,417 [salt.state       :1803][INFO    ][3037] Executing state pkg.installed for [linux_repo_prereq_pkgs]
2018-10-06 14:30:29,417 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-10-06 14:30:29,764 [salt.state       :290 ][INFO    ][3037] All specified packages are already installed
2018-10-06 14:30:29,765 [salt.state       :1941][INFO    ][3037] Completed state [linux_repo_prereq_pkgs] at time 14:30:29.765153 duration_in_ms=348.297
2018-10-06 14:30:29,765 [salt.state       :1770][INFO    ][3037] Running state [/etc/apt/apt.conf.d/99proxies-salt] at time 14:30:29.765415
2018-10-06 14:30:29,765 [salt.state       :1803][INFO    ][3037] Executing state file.managed for [/etc/apt/apt.conf.d/99proxies-salt]
2018-10-06 14:30:29,781 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/files/apt.conf.d_proxies'
2018-10-06 14:30:29,797 [salt.state       :290 ][INFO    ][3037] File changed:
New file
2018-10-06 14:30:29,798 [salt.state       :1941][INFO    ][3037] Completed state [/etc/apt/apt.conf.d/99proxies-salt] at time 14:30:29.798097 duration_in_ms=32.681
2018-10-06 14:30:29,798 [salt.state       :1770][INFO    ][3037] Running state [/etc/apt/apt.conf.d/99proxies-salt-mk_openstack] at time 14:30:29.798307
2018-10-06 14:30:29,798 [salt.state       :1803][INFO    ][3037] Executing state file.absent for [/etc/apt/apt.conf.d/99proxies-salt-mk_openstack]
2018-10-06 14:30:29,798 [salt.state       :290 ][INFO    ][3037] File /etc/apt/apt.conf.d/99proxies-salt-mk_openstack is not present
2018-10-06 14:30:29,799 [salt.state       :1941][INFO    ][3037] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mk_openstack] at time 14:30:29.799071 duration_in_ms=0.765
2018-10-06 14:30:29,799 [salt.state       :1770][INFO    ][3037] Running state [/etc/apt/preferences.d/mk_openstack] at time 14:30:29.799283
2018-10-06 14:30:29,799 [salt.state       :1803][INFO    ][3037] Executing state file.managed for [/etc/apt/preferences.d/mk_openstack]
2018-10-06 14:30:30,300 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006143030285260
2018-10-06 14:30:30,304 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/files/preferences_repo'
2018-10-06 14:30:30,317 [salt.minion      :1431][INFO    ][3119] Starting a new job with PID 3119
2018-10-06 14:30:30,340 [salt.minion      :1708][INFO    ][3119] Returning information for job: 20181006143030285260
2018-10-06 14:30:30,429 [salt.state       :290 ][INFO    ][3037] File changed:
New file
2018-10-06 14:30:30,429 [salt.state       :1941][INFO    ][3037] Completed state [/etc/apt/preferences.d/mk_openstack] at time 14:30:30.429470 duration_in_ms=630.187
2018-10-06 14:30:30,433 [salt.state       :1770][INFO    ][3037] Running state [deb [arch=amd64] http://apt.mirantis.com/xenial/ nightly pike] at time 14:30:30.433186
2018-10-06 14:30:30,433 [salt.state       :1803][INFO    ][3037] Executing state pkgrepo.managed for [deb [arch=amd64] http://apt.mirantis.com/xenial/ nightly pike]
2018-10-06 14:30:31,077 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['apt-key', 'add', '/var/cache/salt/minion/extrn_files/base/apt.mirantis.com/public.gpg'] in directory '/root'
2018-10-06 14:30:31,287 [salt.state       :290 ][INFO    ][3037] {'repo': 'deb [arch=amd64] http://apt.mirantis.com/xenial nightly pike'}
2018-10-06 14:30:31,288 [salt.state       :1941][INFO    ][3037] Completed state [deb [arch=amd64] http://apt.mirantis.com/xenial/ nightly pike] at time 14:30:31.288273 duration_in_ms=855.087
2018-10-06 14:30:31,288 [salt.state       :1770][INFO    ][3037] Running state [/etc/apt/apt.conf.d/99proxies-salt-uca-staging] at time 14:30:31.288640
2018-10-06 14:30:31,288 [salt.state       :1803][INFO    ][3037] Executing state file.absent for [/etc/apt/apt.conf.d/99proxies-salt-uca-staging]
2018-10-06 14:30:31,289 [salt.state       :290 ][INFO    ][3037] File /etc/apt/apt.conf.d/99proxies-salt-uca-staging is not present
2018-10-06 14:30:31,289 [salt.state       :1941][INFO    ][3037] Completed state [/etc/apt/apt.conf.d/99proxies-salt-uca-staging] at time 14:30:31.289699 duration_in_ms=1.059
2018-10-06 14:30:31,289 [salt.state       :1770][INFO    ][3037] Running state [/etc/apt/preferences.d/uca-staging] at time 14:30:31.289924
2018-10-06 14:30:31,290 [salt.state       :1803][INFO    ][3037] Executing state file.absent for [/etc/apt/preferences.d/uca-staging]
2018-10-06 14:30:31,290 [salt.state       :290 ][INFO    ][3037] File /etc/apt/preferences.d/uca-staging is not present
2018-10-06 14:30:31,291 [salt.state       :1941][INFO    ][3037] Completed state [/etc/apt/preferences.d/uca-staging] at time 14:30:31.290665 duration_in_ms=0.742
2018-10-06 14:30:31,297 [salt.state       :1770][INFO    ][3037] Running state [apt-key adv --keyserver keyserver.ubuntu.com --recv 9F68104E] at time 14:30:31.297023
2018-10-06 14:30:31,297 [salt.state       :1803][INFO    ][3037] Executing state cmd.run for [apt-key adv --keyserver keyserver.ubuntu.com --recv 9F68104E]
2018-10-06 14:30:31,298 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'test -e /etc/apt/sources.list.d/uca-staging.list' in directory '/root'
2018-10-06 14:30:31,311 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'apt-key adv --keyserver keyserver.ubuntu.com --recv 9F68104E' in directory '/root'
2018-10-06 14:30:31,509 [salt.state       :290 ][INFO    ][3037] {'pid': 3251, 'retcode': 0, 'stderr': 'gpg: requesting key 9F68104E from hkp server keyserver.ubuntu.com\ngpg: key 9F68104E: public key "Launchpad PPA for Ubuntu Cloud Archive Team" imported\ngpg: Total number processed: 1\ngpg:               imported: 1  (RSA: 1)', 'stdout': 'Executing: /tmp/tmp.gMYlT5MoSa/gpg.1.sh --keyserver\nkeyserver.ubuntu.com\n--recv\n9F68104E'}
2018-10-06 14:30:31,511 [salt.state       :1941][INFO    ][3037] Completed state [apt-key adv --keyserver keyserver.ubuntu.com --recv 9F68104E] at time 14:30:31.511394 duration_in_ms=214.37
2018-10-06 14:30:31,514 [salt.state       :1770][INFO    ][3037] Running state [deb http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial main] at time 14:30:31.514198
2018-10-06 14:30:31,514 [salt.state       :1803][INFO    ][3037] Executing state pkgrepo.managed for [deb http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial main]
2018-10-06 14:30:31,615 [salt.state       :290 ][INFO    ][3037] {'repo': 'deb http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial main'}
2018-10-06 14:30:31,615 [salt.state       :1941][INFO    ][3037] Completed state [deb http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial main] at time 14:30:31.615744 duration_in_ms=101.546
2018-10-06 14:30:31,616 [salt.state       :1770][INFO    ][3037] Running state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack] at time 14:30:31.616030
2018-10-06 14:30:31,616 [salt.state       :1803][INFO    ][3037] Executing state file.absent for [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack]
2018-10-06 14:30:31,616 [salt.state       :290 ][INFO    ][3037] File /etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack is not present
2018-10-06 14:30:31,616 [salt.state       :1941][INFO    ][3037] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack] at time 14:30:31.616849 duration_in_ms=0.819
2018-10-06 14:30:31,617 [salt.state       :1770][INFO    ][3037] Running state [/etc/apt/preferences.d/mirantis_openstack] at time 14:30:31.617065
2018-10-06 14:30:31,617 [salt.state       :1803][INFO    ][3037] Executing state file.managed for [/etc/apt/preferences.d/mirantis_openstack]
2018-10-06 14:30:31,739 [salt.state       :290 ][INFO    ][3037] File changed:
New file
2018-10-06 14:30:31,740 [salt.state       :1941][INFO    ][3037] Completed state [/etc/apt/preferences.d/mirantis_openstack] at time 14:30:31.740045 duration_in_ms=122.98
2018-10-06 14:30:31,742 [salt.state       :1770][INFO    ][3037] Running state [deb http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike main] at time 14:30:31.741982
2018-10-06 14:30:31,742 [salt.state       :1803][INFO    ][3037] Executing state pkgrepo.managed for [deb http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike main]
2018-10-06 14:30:32,193 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['apt-key', 'add', '/var/cache/salt/minion/extrn_files/base/mirror.fuel-infra.org/mcp-repos/pike/xenial/archive-mcppike.key'] in directory '/root'
2018-10-06 14:30:32,409 [salt.state       :290 ][INFO    ][3037] {'repo': 'deb http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike main'}
2018-10-06 14:30:32,409 [salt.state       :1941][INFO    ][3037] Completed state [deb http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike main] at time 14:30:32.409605 duration_in_ms=667.623
2018-10-06 14:30:32,410 [salt.state       :1770][INFO    ][3037] Running state [/etc/apt/apt.conf.d/99proxies-salt-uca] at time 14:30:32.409977
2018-10-06 14:30:32,410 [salt.state       :1803][INFO    ][3037] Executing state file.absent for [/etc/apt/apt.conf.d/99proxies-salt-uca]
2018-10-06 14:30:32,410 [salt.state       :290 ][INFO    ][3037] File /etc/apt/apt.conf.d/99proxies-salt-uca is not present
2018-10-06 14:30:32,411 [salt.state       :1941][INFO    ][3037] Completed state [/etc/apt/apt.conf.d/99proxies-salt-uca] at time 14:30:32.411072 duration_in_ms=1.095
2018-10-06 14:30:32,411 [salt.state       :1770][INFO    ][3037] Running state [/etc/apt/preferences.d/uca] at time 14:30:32.411302
2018-10-06 14:30:32,411 [salt.state       :1803][INFO    ][3037] Executing state file.managed for [/etc/apt/preferences.d/uca]
2018-10-06 14:30:32,873 [salt.state       :290 ][INFO    ][3037] File changed:
New file
2018-10-06 14:30:32,873 [salt.state       :1941][INFO    ][3037] Completed state [/etc/apt/preferences.d/uca] at time 14:30:32.873947 duration_in_ms=462.645
2018-10-06 14:30:32,878 [salt.state       :1770][INFO    ][3037] Running state [apt-key adv --keyserver keyserver.ubuntu.com --recv EC4926EA] at time 14:30:32.878137
2018-10-06 14:30:32,878 [salt.state       :1803][INFO    ][3037] Executing state cmd.run for [apt-key adv --keyserver keyserver.ubuntu.com --recv EC4926EA]
2018-10-06 14:30:32,879 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'test -e /etc/apt/sources.list.d/uca.list' in directory '/root'
2018-10-06 14:30:32,893 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'apt-key adv --keyserver keyserver.ubuntu.com --recv EC4926EA' in directory '/root'
2018-10-06 14:30:33,107 [salt.state       :290 ][INFO    ][3037] {'pid': 3532, 'retcode': 0, 'stderr': 'gpg: requesting key EC4926EA from hkp server keyserver.ubuntu.com\ngpg: key EC4926EA: public key "Canonical Cloud Archive Signing Key <ftpmaster@canonical.com>" imported\ngpg: Total number processed: 1\ngpg:               imported: 1  (RSA: 1)', 'stdout': 'Executing: /tmp/tmp.Yz3Rj2hRRn/gpg.1.sh --keyserver\nkeyserver.ubuntu.com\n--recv\nEC4926EA'}
2018-10-06 14:30:33,107 [salt.state       :1941][INFO    ][3037] Completed state [apt-key adv --keyserver keyserver.ubuntu.com --recv EC4926EA] at time 14:30:33.107691 duration_in_ms=229.554
2018-10-06 14:30:33,110 [salt.state       :1770][INFO    ][3037] Running state [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main] at time 14:30:33.110449
2018-10-06 14:30:33,111 [salt.state       :1803][INFO    ][3037] Executing state pkgrepo.managed for [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main]
2018-10-06 14:30:33,303 [salt.state       :290 ][INFO    ][3037] {'repo': 'deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main'}
2018-10-06 14:30:33,303 [salt.state       :1941][INFO    ][3037] Completed state [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main] at time 14:30:33.303745 duration_in_ms=193.295
2018-10-06 14:30:33,304 [salt.state       :1770][INFO    ][3037] Running state [pkg.refresh_db] at time 14:30:33.304914
2018-10-06 14:30:33,305 [salt.state       :1803][INFO    ][3037] Executing state module.run for [pkg.refresh_db]
2018-10-06 14:30:33,305 [salt.utils.decorators:613 ][WARNING ][3037] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-10-06 14:30:33,306 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-10-06 14:30:37,411 [salt.state       :290 ][INFO    ][3037] {'ret': {'http://apt.mirantis.com/xenial nightly InRelease': True, 'http://security.ubuntu.com/ubuntu xenial-security InRelease': None, 'http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial InRelease': True, 'http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike Release': True, 'http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike/main amd64 Packages': True, 'http://archive.ubuntu.com/ubuntu xenial-backports InRelease': None, 'http://archive.ubuntu.com/ubuntu xenial-updates InRelease': None, 'http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike InRelease': False, 'http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial/main Translation-en': True, 'http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike/main amd64 Packages': True, 'http://repo.saltstack.com/apt/ubuntu/16.04/amd64/2017.7 xenial InRelease': None, 'http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike Release.gpg': True, 'http://archive.ubuntu.com/ubuntu xenial InRelease': None, 'http://apt.mirantis.com/xenial nightly/pike amd64 Packages': True, 'http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial/main amd64 Packages': True, 'http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike InRelease': True}}
2018-10-06 14:30:37,411 [salt.state       :1941][INFO    ][3037] Completed state [pkg.refresh_db] at time 14:30:37.411846 duration_in_ms=4106.931
2018-10-06 14:30:37,413 [salt.state       :1770][INFO    ][3037] Running state [UTC] at time 14:30:37.413419
2018-10-06 14:30:37,413 [salt.state       :1803][INFO    ][3037] Executing state timezone.system for [UTC]
2018-10-06 14:30:37,415 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['timedatectl'] in directory '/root'
2018-10-06 14:30:37,453 [salt.state       :290 ][INFO    ][3037] Timezone UTC already set, UTC already set to UTC
2018-10-06 14:30:37,454 [salt.state       :1941][INFO    ][3037] Completed state [UTC] at time 14:30:37.454040 duration_in_ms=40.62
2018-10-06 14:30:37,454 [salt.state       :1770][INFO    ][3037] Running state [/etc/default/grub.d] at time 14:30:37.454479
2018-10-06 14:30:37,455 [salt.state       :1803][INFO    ][3037] Executing state file.directory for [/etc/default/grub.d]
2018-10-06 14:30:37,456 [salt.state       :290 ][INFO    ][3037] Directory /etc/default/grub.d is in the correct state
Directory /etc/default/grub.d updated
2018-10-06 14:30:37,456 [salt.state       :1941][INFO    ][3037] Completed state [/etc/default/grub.d] at time 14:30:37.456929 duration_in_ms=2.45
2018-10-06 14:30:37,457 [salt.state       :1770][INFO    ][3037] Running state [update-grub] at time 14:30:37.457166
2018-10-06 14:30:37,457 [salt.state       :1803][INFO    ][3037] Executing state cmd.wait for [update-grub]
2018-10-06 14:30:37,457 [salt.state       :290 ][INFO    ][3037] No changes made for update-grub
2018-10-06 14:30:37,457 [salt.state       :1941][INFO    ][3037] Completed state [update-grub] at time 14:30:37.457829 duration_in_ms=0.663
2018-10-06 14:30:37,460 [salt.state       :1770][INFO    ][3037] Running state [/boot/grub/grub.cfg] at time 14:30:37.460708
2018-10-06 14:30:37,461 [salt.state       :1803][INFO    ][3037] Executing state file.managed for [/boot/grub/grub.cfg]
2018-10-06 14:30:37,663 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['git', '--version'] in directory '/root'
2018-10-06 14:30:37,956 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'test -f /boot/grub/grub.cfg' in directory '/root'
2018-10-06 14:30:37,971 [salt.loaded.int.states.file:2150][WARNING ][3037] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-10-06 14:30:37,972 [salt.state       :290 ][INFO    ][3037] {'mode': '0400'}
2018-10-06 14:30:37,973 [salt.state       :1941][INFO    ][3037] Completed state [/boot/grub/grub.cfg] at time 14:30:37.973044 duration_in_ms=512.335
2018-10-06 14:30:37,973 [salt.state       :1770][INFO    ][3037] Running state [nf_conntrack] at time 14:30:37.973434
2018-10-06 14:30:37,973 [salt.state       :1803][INFO    ][3037] Executing state kmod.present for [nf_conntrack]
2018-10-06 14:30:37,974 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'lsmod' in directory '/root'
2018-10-06 14:30:38,469 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'lsmod' in directory '/root'
2018-10-06 14:30:38,486 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'modprobe nf_conntrack' in directory '/root'
2018-10-06 14:30:38,506 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'lsmod' in directory '/root'
2018-10-06 14:30:38,617 [salt.state       :290 ][INFO    ][3037] {'nf_conntrack': 'loaded'}
2018-10-06 14:30:38,618 [salt.state       :1941][INFO    ][3037] Completed state [nf_conntrack] at time 14:30:38.618026 duration_in_ms=644.591
2018-10-06 14:30:38,618 [salt.state       :1770][INFO    ][3037] Running state [kernel.panic] at time 14:30:38.618386
2018-10-06 14:30:38,618 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [kernel.panic]
2018-10-06 14:30:38,632 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w kernel.panic="60"' in directory '/root'
2018-10-06 14:30:38,644 [salt.state       :290 ][INFO    ][3037] {'kernel.panic': 60}
2018-10-06 14:30:38,644 [salt.state       :1941][INFO    ][3037] Completed state [kernel.panic] at time 14:30:38.644836 duration_in_ms=26.449
2018-10-06 14:30:38,645 [salt.state       :1770][INFO    ][3037] Running state [net.ipv4.tcp_keepalive_probes] at time 14:30:38.645202
2018-10-06 14:30:38,645 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [net.ipv4.tcp_keepalive_probes]
2018-10-06 14:30:38,646 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w net.ipv4.tcp_keepalive_probes="8"' in directory '/root'
2018-10-06 14:30:38,658 [salt.state       :290 ][INFO    ][3037] {'net.ipv4.tcp_keepalive_probes': 8}
2018-10-06 14:30:38,659 [salt.state       :1941][INFO    ][3037] Completed state [net.ipv4.tcp_keepalive_probes] at time 14:30:38.659837 duration_in_ms=14.635
2018-10-06 14:30:38,660 [salt.state       :1770][INFO    ][3037] Running state [fs.file-max] at time 14:30:38.660257
2018-10-06 14:30:38,660 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [fs.file-max]
2018-10-06 14:30:38,661 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w fs.file-max="124165"' in directory '/root'
2018-10-06 14:30:38,676 [salt.state       :290 ][INFO    ][3037] {'fs.file-max': 124165}
2018-10-06 14:30:38,676 [salt.state       :1941][INFO    ][3037] Completed state [fs.file-max] at time 14:30:38.676647 duration_in_ms=16.389
2018-10-06 14:30:38,677 [salt.state       :1770][INFO    ][3037] Running state [net.core.somaxconn] at time 14:30:38.677024
2018-10-06 14:30:38,677 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [net.core.somaxconn]
2018-10-06 14:30:38,678 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w net.core.somaxconn="4096"' in directory '/root'
2018-10-06 14:30:38,691 [salt.state       :290 ][INFO    ][3037] {'net.core.somaxconn': 4096}
2018-10-06 14:30:38,691 [salt.state       :1941][INFO    ][3037] Completed state [net.core.somaxconn] at time 14:30:38.691715 duration_in_ms=14.691
2018-10-06 14:30:38,692 [salt.state       :1770][INFO    ][3037] Running state [net.ipv4.tcp_max_syn_backlog] at time 14:30:38.692097
2018-10-06 14:30:38,692 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [net.ipv4.tcp_max_syn_backlog]
2018-10-06 14:30:38,693 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w net.ipv4.tcp_max_syn_backlog="8192"' in directory '/root'
2018-10-06 14:30:38,705 [salt.state       :290 ][INFO    ][3037] {'net.ipv4.tcp_max_syn_backlog': 8192}
2018-10-06 14:30:38,706 [salt.state       :1941][INFO    ][3037] Completed state [net.ipv4.tcp_max_syn_backlog] at time 14:30:38.706197 duration_in_ms=14.099
2018-10-06 14:30:38,706 [salt.state       :1770][INFO    ][3037] Running state [net.ipv4.tcp_tw_reuse] at time 14:30:38.706573
2018-10-06 14:30:38,707 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [net.ipv4.tcp_tw_reuse]
2018-10-06 14:30:38,708 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w net.ipv4.tcp_tw_reuse="1"' in directory '/root'
2018-10-06 14:30:38,720 [salt.state       :290 ][INFO    ][3037] {'net.ipv4.tcp_tw_reuse': 1}
2018-10-06 14:30:38,721 [salt.state       :1941][INFO    ][3037] Completed state [net.ipv4.tcp_tw_reuse] at time 14:30:38.721063 duration_in_ms=14.49
2018-10-06 14:30:38,721 [salt.state       :1770][INFO    ][3037] Running state [net.ipv4.tcp_congestion_control] at time 14:30:38.721433
2018-10-06 14:30:38,721 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [net.ipv4.tcp_congestion_control]
2018-10-06 14:30:38,722 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w net.ipv4.tcp_congestion_control="yeah"' in directory '/root'
2018-10-06 14:30:38,739 [salt.state       :290 ][INFO    ][3037] {'net.ipv4.tcp_congestion_control': 'yeah'}
2018-10-06 14:30:38,739 [salt.state       :1941][INFO    ][3037] Completed state [net.ipv4.tcp_congestion_control] at time 14:30:38.739717 duration_in_ms=18.284
2018-10-06 14:30:38,740 [salt.state       :1770][INFO    ][3037] Running state [net.ipv4.tcp_retries2] at time 14:30:38.740103
2018-10-06 14:30:38,740 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [net.ipv4.tcp_retries2]
2018-10-06 14:30:38,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w net.ipv4.tcp_retries2="5"' in directory '/root'
2018-10-06 14:30:38,753 [salt.state       :290 ][INFO    ][3037] {'net.ipv4.tcp_retries2': 5}
2018-10-06 14:30:38,754 [salt.state       :1941][INFO    ][3037] Completed state [net.ipv4.tcp_retries2] at time 14:30:38.754129 duration_in_ms=14.025
2018-10-06 14:30:38,754 [salt.state       :1770][INFO    ][3037] Running state [net.core.netdev_max_backlog] at time 14:30:38.754496
2018-10-06 14:30:38,755 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [net.core.netdev_max_backlog]
2018-10-06 14:30:38,756 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w net.core.netdev_max_backlog="261144"' in directory '/root'
2018-10-06 14:30:38,769 [salt.state       :290 ][INFO    ][3037] {'net.core.netdev_max_backlog': 261144}
2018-10-06 14:30:38,769 [salt.state       :1941][INFO    ][3037] Completed state [net.core.netdev_max_backlog] at time 14:30:38.769329 duration_in_ms=14.832
2018-10-06 14:30:38,769 [salt.state       :1770][INFO    ][3037] Running state [net.ipv4.tcp_slow_start_after_idle] at time 14:30:38.769675
2018-10-06 14:30:38,770 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [net.ipv4.tcp_slow_start_after_idle]
2018-10-06 14:30:38,771 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w net.ipv4.tcp_slow_start_after_idle="0"' in directory '/root'
2018-10-06 14:30:38,784 [salt.state       :290 ][INFO    ][3037] {'net.ipv4.tcp_slow_start_after_idle': 0}
2018-10-06 14:30:38,784 [salt.state       :1941][INFO    ][3037] Completed state [net.ipv4.tcp_slow_start_after_idle] at time 14:30:38.784430 duration_in_ms=14.754
2018-10-06 14:30:38,784 [salt.state       :1770][INFO    ][3037] Running state [vm.swappiness] at time 14:30:38.784829
2018-10-06 14:30:38,785 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [vm.swappiness]
2018-10-06 14:30:38,786 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w vm.swappiness="10"' in directory '/root'
2018-10-06 14:30:38,797 [salt.state       :290 ][INFO    ][3037] {'vm.swappiness': 10}
2018-10-06 14:30:38,797 [salt.state       :1941][INFO    ][3037] Completed state [vm.swappiness] at time 14:30:38.797695 duration_in_ms=12.865
2018-10-06 14:30:38,798 [salt.state       :1770][INFO    ][3037] Running state [net.ipv4.tcp_keepalive_intvl] at time 14:30:38.798066
2018-10-06 14:30:38,798 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [net.ipv4.tcp_keepalive_intvl]
2018-10-06 14:30:38,799 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w net.ipv4.tcp_keepalive_intvl="3"' in directory '/root'
2018-10-06 14:30:38,812 [salt.state       :290 ][INFO    ][3037] {'net.ipv4.tcp_keepalive_intvl': 3}
2018-10-06 14:30:38,812 [salt.state       :1941][INFO    ][3037] Completed state [net.ipv4.tcp_keepalive_intvl] at time 14:30:38.812493 duration_in_ms=14.427
2018-10-06 14:30:38,812 [salt.state       :1770][INFO    ][3037] Running state [net.ipv4.neigh.default.gc_thresh1] at time 14:30:38.812876
2018-10-06 14:30:38,813 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [net.ipv4.neigh.default.gc_thresh1]
2018-10-06 14:30:38,814 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w net.ipv4.neigh.default.gc_thresh1="4096"' in directory '/root'
2018-10-06 14:30:38,827 [salt.state       :290 ][INFO    ][3037] {'net.ipv4.neigh.default.gc_thresh1': 4096}
2018-10-06 14:30:38,828 [salt.state       :1941][INFO    ][3037] Completed state [net.ipv4.neigh.default.gc_thresh1] at time 14:30:38.828097 duration_in_ms=15.221
2018-10-06 14:30:38,828 [salt.state       :1770][INFO    ][3037] Running state [net.ipv4.neigh.default.gc_thresh2] at time 14:30:38.828485
2018-10-06 14:30:38,828 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [net.ipv4.neigh.default.gc_thresh2]
2018-10-06 14:30:38,829 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w net.ipv4.neigh.default.gc_thresh2="8192"' in directory '/root'
2018-10-06 14:30:38,844 [salt.state       :290 ][INFO    ][3037] {'net.ipv4.neigh.default.gc_thresh2': 8192}
2018-10-06 14:30:38,844 [salt.state       :1941][INFO    ][3037] Completed state [net.ipv4.neigh.default.gc_thresh2] at time 14:30:38.844849 duration_in_ms=16.364
2018-10-06 14:30:38,845 [salt.state       :1770][INFO    ][3037] Running state [net.ipv4.neigh.default.gc_thresh3] at time 14:30:38.845230
2018-10-06 14:30:38,845 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [net.ipv4.neigh.default.gc_thresh3]
2018-10-06 14:30:38,846 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w net.ipv4.neigh.default.gc_thresh3="16384"' in directory '/root'
2018-10-06 14:30:38,859 [salt.state       :290 ][INFO    ][3037] {'net.ipv4.neigh.default.gc_thresh3': 16384}
2018-10-06 14:30:38,860 [salt.state       :1941][INFO    ][3037] Completed state [net.ipv4.neigh.default.gc_thresh3] at time 14:30:38.859995 duration_in_ms=14.764
2018-10-06 14:30:38,860 [salt.state       :1770][INFO    ][3037] Running state [net.ipv4.tcp_fin_timeout] at time 14:30:38.860355
2018-10-06 14:30:38,860 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [net.ipv4.tcp_fin_timeout]
2018-10-06 14:30:38,861 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w net.ipv4.tcp_fin_timeout="30"' in directory '/root'
2018-10-06 14:30:38,874 [salt.state       :290 ][INFO    ][3037] {'net.ipv4.tcp_fin_timeout': 30}
2018-10-06 14:30:38,875 [salt.state       :1941][INFO    ][3037] Completed state [net.ipv4.tcp_fin_timeout] at time 14:30:38.875400 duration_in_ms=15.044
2018-10-06 14:30:38,875 [salt.state       :1770][INFO    ][3037] Running state [net.ipv4.tcp_keepalive_time] at time 14:30:38.875806
2018-10-06 14:30:38,876 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [net.ipv4.tcp_keepalive_time]
2018-10-06 14:30:38,877 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w net.ipv4.tcp_keepalive_time="30"' in directory '/root'
2018-10-06 14:30:38,889 [salt.state       :290 ][INFO    ][3037] {'net.ipv4.tcp_keepalive_time': 30}
2018-10-06 14:30:38,890 [salt.state       :1941][INFO    ][3037] Completed state [net.ipv4.tcp_keepalive_time] at time 14:30:38.890326 duration_in_ms=14.519
2018-10-06 14:30:38,891 [salt.state       :1770][INFO    ][3037] Running state [net.nf_conntrack_max] at time 14:30:38.890695
2018-10-06 14:30:38,891 [salt.state       :1803][INFO    ][3037] Executing state sysctl.present for [net.nf_conntrack_max]
2018-10-06 14:30:38,892 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'sysctl -w net.nf_conntrack_max="1048576"' in directory '/root'
2018-10-06 14:30:38,904 [salt.state       :290 ][INFO    ][3037] {'net.nf_conntrack_max': 1048576}
2018-10-06 14:30:38,904 [salt.state       :1941][INFO    ][3037] Completed state [net.nf_conntrack_max] at time 14:30:38.904658 duration_in_ms=13.963
2018-10-06 14:30:38,905 [salt.state       :1770][INFO    ][3037] Running state [linux_sysfs_package] at time 14:30:38.905061
2018-10-06 14:30:38,905 [salt.state       :1803][INFO    ][3037] Executing state pkg.installed for [linux_sysfs_package]
2018-10-06 14:30:38,928 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['apt-cache', '-q', 'policy', 'sysfsutils'] in directory '/root'
2018-10-06 14:30:39,009 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-10-06 14:30:40,366 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006143040350092
2018-10-06 14:30:40,382 [salt.minion      :1431][INFO    ][4576] Starting a new job with PID 4576
2018-10-06 14:30:40,401 [salt.minion      :1708][INFO    ][4576] Returning information for job: 20181006143040350092
2018-10-06 14:30:40,870 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-10-06 14:30:40,896 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'sysfsutils'] in directory '/root'
2018-10-06 14:30:43,872 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-10-06 14:30:43,907 [salt.state       :290 ][INFO    ][3037] Made the following changes:
'libsysfs2' changed from 'absent' to '2.1.0+repack-4'
'sysfsutils' changed from 'absent' to '2.1.0+repack-4'

2018-10-06 14:30:43,933 [salt.state       :905 ][INFO    ][3037] Loading fresh modules for state activity
2018-10-06 14:30:43,971 [salt.state       :1941][INFO    ][3037] Completed state [linux_sysfs_package] at time 14:30:43.971537 duration_in_ms=5066.476
2018-10-06 14:30:43,977 [salt.state       :1770][INFO    ][3037] Running state [/etc/sysfs.d] at time 14:30:43.977102
2018-10-06 14:30:43,977 [salt.state       :1803][INFO    ][3037] Executing state file.directory for [/etc/sysfs.d]
2018-10-06 14:30:43,981 [salt.state       :290 ][INFO    ][3037] Directory /etc/sysfs.d is in the correct state
Directory /etc/sysfs.d updated
2018-10-06 14:30:43,981 [salt.state       :1941][INFO    ][3037] Completed state [/etc/sysfs.d] at time 14:30:43.981438 duration_in_ms=4.336
2018-10-06 14:30:44,505 [salt.state       :1770][INFO    ][3037] Running state [ondemand] at time 14:30:44.505623
2018-10-06 14:30:44,506 [salt.state       :1803][INFO    ][3037] Executing state service.dead for [ondemand]
2018-10-06 14:30:44,507 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemctl', 'status', 'ondemand.service', '-n', '0'] in directory '/root'
2018-10-06 14:30:44,525 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemctl', 'is-active', 'ondemand.service'] in directory '/root'
2018-10-06 14:30:44,544 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-10-06 14:30:44,565 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemd-run', '--scope', 'systemctl', 'stop', 'ondemand.service'] in directory '/root'
2018-10-06 14:30:44,625 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemctl', 'is-active', 'ondemand.service'] in directory '/root'
2018-10-06 14:30:44,640 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-10-06 14:30:44,664 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-10-06 14:30:44,690 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemd-run', '--scope', '/usr/sbin/update-rc.d', '-f', 'ondemand', 'remove'] in directory '/root'
2018-10-06 14:30:44,865 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-10-06 14:30:44,889 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'runlevel' in directory '/root'
2018-10-06 14:30:44,902 [salt.state       :290 ][INFO    ][3037] {'ondemand': True}
2018-10-06 14:30:44,903 [salt.state       :1941][INFO    ][3037] Completed state [ondemand] at time 14:30:44.903497 duration_in_ms=397.875
2018-10-06 14:30:44,904 [salt.state       :1770][INFO    ][3037] Running state [cs_CZ.UTF-8] at time 14:30:44.904746
2018-10-06 14:30:44,905 [salt.state       :1803][INFO    ][3037] Executing state locale.present for [cs_CZ.UTF-8]
2018-10-06 14:30:44,905 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'locale -a' in directory '/root'
2018-10-06 14:30:44,923 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['locale-gen', 'cs_CZ.utf8'] in directory '/root'
2018-10-06 14:30:45,589 [salt.state       :290 ][INFO    ][3037] {'locale': 'cs_CZ.UTF-8'}
2018-10-06 14:30:45,590 [salt.state       :1941][INFO    ][3037] Completed state [cs_CZ.UTF-8] at time 14:30:45.589930 duration_in_ms=685.184
2018-10-06 14:30:45,590 [salt.state       :1770][INFO    ][3037] Running state [en_US.UTF-8] at time 14:30:45.590400
2018-10-06 14:30:45,590 [salt.state       :1803][INFO    ][3037] Executing state locale.present for [en_US.UTF-8]
2018-10-06 14:30:45,592 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'locale -a' in directory '/root'
2018-10-06 14:30:45,611 [salt.state       :290 ][INFO    ][3037] Locale en_US.UTF-8 is already present
2018-10-06 14:30:45,611 [salt.state       :1941][INFO    ][3037] Completed state [en_US.UTF-8] at time 14:30:45.611761 duration_in_ms=21.361
2018-10-06 14:30:45,615 [salt.state       :1770][INFO    ][3037] Running state [en_US.UTF-8] at time 14:30:45.615691
2018-10-06 14:30:45,616 [salt.state       :1803][INFO    ][3037] Executing state locale.system for [en_US.UTF-8]
2018-10-06 14:30:45,617 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'localectl' in directory '/root'
2018-10-06 14:30:45,661 [salt.state       :290 ][INFO    ][3037] System locale en_US.UTF-8 already set
2018-10-06 14:30:45,662 [salt.state       :1941][INFO    ][3037] Completed state [en_US.UTF-8] at time 14:30:45.662336 duration_in_ms=46.645
2018-10-06 14:30:45,663 [salt.state       :1770][INFO    ][3037] Running state [root] at time 14:30:45.663861
2018-10-06 14:30:45,664 [salt.state       :1803][INFO    ][3037] Executing state group.present for [root]
2018-10-06 14:30:45,665 [salt.state       :290 ][INFO    ][3037] Group root is present and up to date
2018-10-06 14:30:45,665 [salt.state       :1941][INFO    ][3037] Completed state [root] at time 14:30:45.665488 duration_in_ms=1.627
2018-10-06 14:30:45,668 [salt.state       :1770][INFO    ][3037] Running state [root] at time 14:30:45.668702
2018-10-06 14:30:45,669 [salt.state       :1803][INFO    ][3037] Executing state user.present for [root]
2018-10-06 14:30:45,674 [salt.state       :290 ][INFO    ][3037] User root is present and up to date
2018-10-06 14:30:45,674 [salt.state       :1941][INFO    ][3037] Completed state [root] at time 14:30:45.674601 duration_in_ms=5.899
2018-10-06 14:30:45,675 [salt.state       :1770][INFO    ][3037] Running state [/root] at time 14:30:45.675845
2018-10-06 14:30:45,676 [salt.state       :1803][INFO    ][3037] Executing state file.directory for [/root]
2018-10-06 14:30:45,676 [salt.state       :290 ][INFO    ][3037] Directory /root is in the correct state
Directory /root updated
2018-10-06 14:30:45,677 [salt.state       :1941][INFO    ][3037] Completed state [/root] at time 14:30:45.677013 duration_in_ms=1.168
2018-10-06 14:30:45,677 [salt.state       :1770][INFO    ][3037] Running state [/etc/sudoers.d/90-salt-user-root] at time 14:30:45.677211
2018-10-06 14:30:45,677 [salt.state       :1803][INFO    ][3037] Executing state file.absent for [/etc/sudoers.d/90-salt-user-root]
2018-10-06 14:30:45,677 [salt.state       :290 ][INFO    ][3037] File /etc/sudoers.d/90-salt-user-root is not present
2018-10-06 14:30:45,677 [salt.state       :1941][INFO    ][3037] Completed state [/etc/sudoers.d/90-salt-user-root] at time 14:30:45.677881 duration_in_ms=0.67
2018-10-06 14:30:45,678 [salt.state       :1770][INFO    ][3037] Running state [ubuntu] at time 14:30:45.678101
2018-10-06 14:30:45,678 [salt.state       :1803][INFO    ][3037] Executing state group.present for [ubuntu]
2018-10-06 14:30:45,678 [salt.state       :290 ][INFO    ][3037] Group ubuntu is present and up to date
2018-10-06 14:30:45,679 [salt.state       :1941][INFO    ][3037] Completed state [ubuntu] at time 14:30:45.678748 duration_in_ms=0.646
2018-10-06 14:30:45,680 [salt.state       :1770][INFO    ][3037] Running state [ubuntu] at time 14:30:45.680834
2018-10-06 14:30:45,681 [salt.state       :1803][INFO    ][3037] Executing state user.present for [ubuntu]
2018-10-06 14:30:45,684 [salt.state       :290 ][INFO    ][3037] {'passwd': 'XXX-REDACTED-XXX'}
2018-10-06 14:30:45,684 [salt.state       :1941][INFO    ][3037] Completed state [ubuntu] at time 14:30:45.684647 duration_in_ms=3.812
2018-10-06 14:30:45,685 [salt.state       :1770][INFO    ][3037] Running state [/home/ubuntu] at time 14:30:45.685677
2018-10-06 14:30:45,685 [salt.state       :1803][INFO    ][3037] Executing state file.directory for [/home/ubuntu]
2018-10-06 14:30:45,687 [salt.state       :290 ][INFO    ][3037] {'mode': '0700'}
2018-10-06 14:30:45,687 [salt.state       :1941][INFO    ][3037] Completed state [/home/ubuntu] at time 14:30:45.687351 duration_in_ms=1.673
2018-10-06 14:30:45,688 [salt.state       :1770][INFO    ][3037] Running state [/etc/sudoers.d/90-salt-user-ubuntu] at time 14:30:45.688250
2018-10-06 14:30:45,688 [salt.state       :1803][INFO    ][3037] Executing state file.managed for [/etc/sudoers.d/90-salt-user-ubuntu]
2018-10-06 14:30:45,713 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/files/sudoer'
2018-10-06 14:30:45,732 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command '/usr/sbin/visudo -c -f /tmp/__salt.tmp.F84EDF' in directory '/root'
2018-10-06 14:30:45,755 [salt.state       :290 ][INFO    ][3037] File changed:
New file
2018-10-06 14:30:45,755 [salt.state       :1941][INFO    ][3037] Completed state [/etc/sudoers.d/90-salt-user-ubuntu] at time 14:30:45.755766 duration_in_ms=67.515
2018-10-06 14:30:45,756 [salt.state       :1770][INFO    ][3037] Running state [/etc/security/limits.d/90-salt-default.conf] at time 14:30:45.756068
2018-10-06 14:30:45,756 [salt.state       :1803][INFO    ][3037] Executing state file.managed for [/etc/security/limits.d/90-salt-default.conf]
2018-10-06 14:30:45,780 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/files/limits.conf'
2018-10-06 14:30:45,884 [salt.state       :290 ][INFO    ][3037] File changed:
New file
2018-10-06 14:30:45,884 [salt.state       :1941][INFO    ][3037] Completed state [/etc/security/limits.d/90-salt-default.conf] at time 14:30:45.884322 duration_in_ms=128.254
2018-10-06 14:30:45,884 [salt.state       :1770][INFO    ][3037] Running state [/etc/systemd/system.conf.d/90-salt.conf] at time 14:30:45.884564
2018-10-06 14:30:45,884 [salt.state       :1803][INFO    ][3037] Executing state file.managed for [/etc/systemd/system.conf.d/90-salt.conf]
2018-10-06 14:30:45,904 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/files/systemd.conf'
2018-10-06 14:30:46,005 [salt.state       :290 ][INFO    ][3037] File changed:
New file
2018-10-06 14:30:46,005 [salt.state       :1941][INFO    ][3037] Completed state [/etc/systemd/system.conf.d/90-salt.conf] at time 14:30:46.005449 duration_in_ms=120.885
2018-10-06 14:30:46,008 [salt.state       :1770][INFO    ][3037] Running state [service.systemctl_reload] at time 14:30:46.008475
2018-10-06 14:30:46,008 [salt.state       :1803][INFO    ][3037] Executing state module.wait for [service.systemctl_reload]
2018-10-06 14:30:46,009 [salt.state       :290 ][INFO    ][3037] No changes made for service.systemctl_reload
2018-10-06 14:30:46,009 [salt.state       :1941][INFO    ][3037] Completed state [service.systemctl_reload] at time 14:30:46.009158 duration_in_ms=0.683
2018-10-06 14:30:46,009 [salt.state       :1770][INFO    ][3037] Running state [service.systemctl_reload] at time 14:30:46.009341
2018-10-06 14:30:46,009 [salt.state       :1803][INFO    ][3037] Executing state module.mod_watch for [service.systemctl_reload]
2018-10-06 14:30:46,009 [salt.utils.decorators:613 ][WARNING ][3037] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-10-06 14:30:46,010 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2018-10-06 14:30:46,136 [salt.state       :290 ][INFO    ][3037] {'ret': True}
2018-10-06 14:30:46,136 [salt.state       :1941][INFO    ][3037] Completed state [service.systemctl_reload] at time 14:30:46.136716 duration_in_ms=127.374
2018-10-06 14:30:46,137 [salt.state       :1770][INFO    ][3037] Running state [/etc/issue] at time 14:30:46.137179
2018-10-06 14:30:46,137 [salt.state       :1803][INFO    ][3037] Executing state file.managed for [/etc/issue]
2018-10-06 14:30:46,140 [salt.state       :290 ][INFO    ][3037] File changed:
--- 
+++ 
@@ -1,2 +1,9 @@
-Ubuntu 16.04.5 LTS \n \l
-
+=================================== WARNING ====================================
+You have accessed a computer managed by COMPANY_NAME_HERE.
+You are required to have authorisation from COMPANY_NAME_HERE
+before you proceed and you are strictly limited to use set out within that
+authorisation. Unauthorised access to or misuse of this system is prohibited
+and constitutes an offence under the Computer Misuse Act 1990.
+If you disclose any information obtained through this system without authority
+COMPANY_NAME_HERE may take legal action against you.
+================================================================================

2018-10-06 14:30:46,140 [salt.state       :1941][INFO    ][3037] Completed state [/etc/issue] at time 14:30:46.140680 duration_in_ms=3.501
2018-10-06 14:30:46,140 [salt.state       :1770][INFO    ][3037] Running state [/etc/hostname] at time 14:30:46.140929
2018-10-06 14:30:46,141 [salt.state       :1803][INFO    ][3037] Executing state file.managed for [/etc/hostname]
2018-10-06 14:30:46,164 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'linux/files/hostname'
2018-10-06 14:30:46,175 [salt.state       :290 ][INFO    ][3037] File changed:
--- 
+++ 
@@ -1 +1 @@
-ubuntu
+msg02

2018-10-06 14:30:46,176 [salt.state       :1941][INFO    ][3037] Completed state [/etc/hostname] at time 14:30:46.176180 duration_in_ms=35.251
2018-10-06 14:30:46,178 [salt.state       :1770][INFO    ][3037] Running state [hostname msg02] at time 14:30:46.178346
2018-10-06 14:30:46,178 [salt.state       :1803][INFO    ][3037] Executing state cmd.run for [hostname msg02]
2018-10-06 14:30:46,179 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'test "$(hostname)" = "msg02"' in directory '/root'
2018-10-06 14:30:46,193 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command 'hostname msg02' in directory '/root'
2018-10-06 14:30:46,205 [salt.state       :290 ][INFO    ][3037] {'pid': 5199, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-10-06 14:30:46,206 [salt.state       :1941][INFO    ][3037] Completed state [hostname msg02] at time 14:30:46.206435 duration_in_ms=28.089
2018-10-06 14:30:46,208 [salt.state       :1770][INFO    ][3037] Running state [mdb02] at time 14:30:46.208406
2018-10-06 14:30:46,208 [salt.state       :1803][INFO    ][3037] Executing state host.present for [mdb02]
2018-10-06 14:30:46,209 [salt.state       :290 ][INFO    ][3037] {'host': 'mdb02'}
2018-10-06 14:30:46,210 [salt.state       :1941][INFO    ][3037] Completed state [mdb02] at time 14:30:46.210175 duration_in_ms=1.769
2018-10-06 14:30:46,210 [salt.state       :1770][INFO    ][3037] Running state [mdb02.mcp-pike-odl-ha.local] at time 14:30:46.210513
2018-10-06 14:30:46,211 [salt.state       :1803][INFO    ][3037] Executing state host.present for [mdb02.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,212 [salt.state       :290 ][INFO    ][3037] {'host': 'mdb02.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,212 [salt.state       :1941][INFO    ][3037] Completed state [mdb02.mcp-pike-odl-ha.local] at time 14:30:46.212192 duration_in_ms=1.678
2018-10-06 14:30:46,212 [salt.state       :1770][INFO    ][3037] Running state [mdb03] at time 14:30:46.212505
2018-10-06 14:30:46,212 [salt.state       :1803][INFO    ][3037] Executing state host.present for [mdb03]
2018-10-06 14:30:46,213 [salt.state       :290 ][INFO    ][3037] {'host': 'mdb03'}
2018-10-06 14:30:46,213 [salt.state       :1941][INFO    ][3037] Completed state [mdb03] at time 14:30:46.213817 duration_in_ms=1.312
2018-10-06 14:30:46,214 [salt.state       :1770][INFO    ][3037] Running state [mdb03.mcp-pike-odl-ha.local] at time 14:30:46.214102
2018-10-06 14:30:46,214 [salt.state       :1803][INFO    ][3037] Executing state host.present for [mdb03.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,216 [salt.state       :290 ][INFO    ][3037] {'host': 'mdb03.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,216 [salt.state       :1941][INFO    ][3037] Completed state [mdb03.mcp-pike-odl-ha.local] at time 14:30:46.216342 duration_in_ms=2.239
2018-10-06 14:30:46,216 [salt.state       :1770][INFO    ][3037] Running state [mdb01] at time 14:30:46.216631
2018-10-06 14:30:46,216 [salt.state       :1803][INFO    ][3037] Executing state host.present for [mdb01]
2018-10-06 14:30:46,217 [salt.state       :290 ][INFO    ][3037] {'host': 'mdb01'}
2018-10-06 14:30:46,217 [salt.state       :1941][INFO    ][3037] Completed state [mdb01] at time 14:30:46.217935 duration_in_ms=1.304
2018-10-06 14:30:46,218 [salt.state       :1770][INFO    ][3037] Running state [mdb01.mcp-pike-odl-ha.local] at time 14:30:46.218233
2018-10-06 14:30:46,218 [salt.state       :1803][INFO    ][3037] Executing state host.present for [mdb01.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,220 [salt.state       :290 ][INFO    ][3037] {'host': 'mdb01.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,220 [salt.state       :1941][INFO    ][3037] Completed state [mdb01.mcp-pike-odl-ha.local] at time 14:30:46.220201 duration_in_ms=1.968
2018-10-06 14:30:46,220 [salt.state       :1770][INFO    ][3037] Running state [mdb] at time 14:30:46.220488
2018-10-06 14:30:46,220 [salt.state       :1803][INFO    ][3037] Executing state host.present for [mdb]
2018-10-06 14:30:46,221 [salt.state       :290 ][INFO    ][3037] {'host': 'mdb'}
2018-10-06 14:30:46,221 [salt.state       :1941][INFO    ][3037] Completed state [mdb] at time 14:30:46.221834 duration_in_ms=1.346
2018-10-06 14:30:46,222 [salt.state       :1770][INFO    ][3037] Running state [mdb.mcp-pike-odl-ha.local] at time 14:30:46.222168
2018-10-06 14:30:46,222 [salt.state       :1803][INFO    ][3037] Executing state host.present for [mdb.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,224 [salt.state       :290 ][INFO    ][3037] {'host': 'mdb.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,224 [salt.state       :1941][INFO    ][3037] Completed state [mdb.mcp-pike-odl-ha.local] at time 14:30:46.224775 duration_in_ms=2.607
2018-10-06 14:30:46,225 [salt.state       :1770][INFO    ][3037] Running state [cfg01] at time 14:30:46.225074
2018-10-06 14:30:46,225 [salt.state       :1803][INFO    ][3037] Executing state host.present for [cfg01]
2018-10-06 14:30:46,226 [salt.state       :290 ][INFO    ][3037] {'host': 'cfg01'}
2018-10-06 14:30:46,226 [salt.state       :1941][INFO    ][3037] Completed state [cfg01] at time 14:30:46.226392 duration_in_ms=1.318
2018-10-06 14:30:46,226 [salt.state       :1770][INFO    ][3037] Running state [cfg01.mcp-pike-odl-ha.local] at time 14:30:46.226682
2018-10-06 14:30:46,226 [salt.state       :1803][INFO    ][3037] Executing state host.present for [cfg01.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,227 [salt.state       :290 ][INFO    ][3037] {'host': 'cfg01.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,228 [salt.state       :1941][INFO    ][3037] Completed state [cfg01.mcp-pike-odl-ha.local] at time 14:30:46.228046 duration_in_ms=1.364
2018-10-06 14:30:46,228 [salt.state       :1770][INFO    ][3037] Running state [prx01] at time 14:30:46.228378
2018-10-06 14:30:46,228 [salt.state       :1803][INFO    ][3037] Executing state host.present for [prx01]
2018-10-06 14:30:46,229 [salt.state       :290 ][INFO    ][3037] {'host': 'prx01'}
2018-10-06 14:30:46,229 [salt.state       :1941][INFO    ][3037] Completed state [prx01] at time 14:30:46.229736 duration_in_ms=1.358
2018-10-06 14:30:46,230 [salt.state       :1770][INFO    ][3037] Running state [prx01.mcp-pike-odl-ha.local] at time 14:30:46.230038
2018-10-06 14:30:46,230 [salt.state       :1803][INFO    ][3037] Executing state host.present for [prx01.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,231 [salt.state       :290 ][INFO    ][3037] {'host': 'prx01.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,231 [salt.state       :1941][INFO    ][3037] Completed state [prx01.mcp-pike-odl-ha.local] at time 14:30:46.231426 duration_in_ms=1.388
2018-10-06 14:30:46,231 [salt.state       :1770][INFO    ][3037] Running state [kvm01] at time 14:30:46.231740
2018-10-06 14:30:46,231 [salt.state       :1803][INFO    ][3037] Executing state host.present for [kvm01]
2018-10-06 14:30:46,232 [salt.state       :290 ][INFO    ][3037] {'host': 'kvm01'}
2018-10-06 14:30:46,233 [salt.state       :1941][INFO    ][3037] Completed state [kvm01] at time 14:30:46.233105 duration_in_ms=1.365
2018-10-06 14:30:46,233 [salt.state       :1770][INFO    ][3037] Running state [kvm01.mcp-pike-odl-ha.local] at time 14:30:46.233396
2018-10-06 14:30:46,233 [salt.state       :1803][INFO    ][3037] Executing state host.present for [kvm01.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,234 [salt.state       :290 ][INFO    ][3037] {'host': 'kvm01.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,235 [salt.state       :1941][INFO    ][3037] Completed state [kvm01.mcp-pike-odl-ha.local] at time 14:30:46.234735 duration_in_ms=1.339
2018-10-06 14:30:46,235 [salt.state       :1770][INFO    ][3037] Running state [kvm03] at time 14:30:46.235606
2018-10-06 14:30:46,235 [salt.state       :1803][INFO    ][3037] Executing state host.present for [kvm03]
2018-10-06 14:30:46,236 [salt.state       :290 ][INFO    ][3037] {'host': 'kvm03'}
2018-10-06 14:30:46,237 [salt.state       :1941][INFO    ][3037] Completed state [kvm03] at time 14:30:46.236977 duration_in_ms=1.372
2018-10-06 14:30:46,237 [salt.state       :1770][INFO    ][3037] Running state [kvm03.mcp-pike-odl-ha.local] at time 14:30:46.237268
2018-10-06 14:30:46,237 [salt.state       :1803][INFO    ][3037] Executing state host.present for [kvm03.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,238 [salt.state       :290 ][INFO    ][3037] {'host': 'kvm03.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,238 [salt.state       :1941][INFO    ][3037] Completed state [kvm03.mcp-pike-odl-ha.local] at time 14:30:46.238684 duration_in_ms=1.416
2018-10-06 14:30:46,239 [salt.state       :1770][INFO    ][3037] Running state [kvm02] at time 14:30:46.239275
2018-10-06 14:30:46,239 [salt.state       :1803][INFO    ][3037] Executing state host.present for [kvm02]
2018-10-06 14:30:46,240 [salt.state       :290 ][INFO    ][3037] {'host': 'kvm02'}
2018-10-06 14:30:46,240 [salt.state       :1941][INFO    ][3037] Completed state [kvm02] at time 14:30:46.240890 duration_in_ms=1.616
2018-10-06 14:30:46,241 [salt.state       :1770][INFO    ][3037] Running state [kvm02.mcp-pike-odl-ha.local] at time 14:30:46.241205
2018-10-06 14:30:46,241 [salt.state       :1803][INFO    ][3037] Executing state host.present for [kvm02.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,242 [salt.state       :290 ][INFO    ][3037] {'host': 'kvm02.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,242 [salt.state       :1941][INFO    ][3037] Completed state [kvm02.mcp-pike-odl-ha.local] at time 14:30:46.242577 duration_in_ms=1.372
2018-10-06 14:30:46,244 [salt.state       :1770][INFO    ][3037] Running state [dbs] at time 14:30:46.244021
2018-10-06 14:30:46,244 [salt.state       :1803][INFO    ][3037] Executing state host.present for [dbs]
2018-10-06 14:30:46,245 [salt.state       :290 ][INFO    ][3037] {'host': 'dbs'}
2018-10-06 14:30:46,245 [salt.state       :1941][INFO    ][3037] Completed state [dbs] at time 14:30:46.245388 duration_in_ms=1.367
2018-10-06 14:30:46,245 [salt.state       :1770][INFO    ][3037] Running state [dbs.mcp-pike-odl-ha.local] at time 14:30:46.245707
2018-10-06 14:30:46,245 [salt.state       :1803][INFO    ][3037] Executing state host.present for [dbs.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,246 [salt.state       :290 ][INFO    ][3037] {'host': 'dbs.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,247 [salt.state       :1941][INFO    ][3037] Completed state [dbs.mcp-pike-odl-ha.local] at time 14:30:46.247077 duration_in_ms=1.37
2018-10-06 14:30:46,247 [salt.state       :1770][INFO    ][3037] Running state [prx] at time 14:30:46.247443
2018-10-06 14:30:46,247 [salt.state       :1803][INFO    ][3037] Executing state host.present for [prx]
2018-10-06 14:30:46,248 [salt.state       :290 ][INFO    ][3037] {'host': 'prx'}
2018-10-06 14:30:46,248 [salt.state       :1941][INFO    ][3037] Completed state [prx] at time 14:30:46.248783 duration_in_ms=1.34
2018-10-06 14:30:46,249 [salt.state       :1770][INFO    ][3037] Running state [prx.mcp-pike-odl-ha.local] at time 14:30:46.249084
2018-10-06 14:30:46,249 [salt.state       :1803][INFO    ][3037] Executing state host.present for [prx.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,250 [salt.state       :290 ][INFO    ][3037] {'host': 'prx.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,250 [salt.state       :1941][INFO    ][3037] Completed state [prx.mcp-pike-odl-ha.local] at time 14:30:46.250463 duration_in_ms=1.378
2018-10-06 14:30:46,251 [salt.state       :1770][INFO    ][3037] Running state [prx02] at time 14:30:46.251107
2018-10-06 14:30:46,251 [salt.state       :1803][INFO    ][3037] Executing state host.present for [prx02]
2018-10-06 14:30:46,252 [salt.state       :290 ][INFO    ][3037] {'host': 'prx02'}
2018-10-06 14:30:46,252 [salt.state       :1941][INFO    ][3037] Completed state [prx02] at time 14:30:46.252604 duration_in_ms=1.497
2018-10-06 14:30:46,252 [salt.state       :1770][INFO    ][3037] Running state [prx02.mcp-pike-odl-ha.local] at time 14:30:46.252906
2018-10-06 14:30:46,253 [salt.state       :1803][INFO    ][3037] Executing state host.present for [prx02.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,254 [salt.state       :290 ][INFO    ][3037] {'host': 'prx02.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,254 [salt.state       :1941][INFO    ][3037] Completed state [prx02.mcp-pike-odl-ha.local] at time 14:30:46.254261 duration_in_ms=1.354
2018-10-06 14:30:46,254 [salt.state       :1770][INFO    ][3037] Running state [msg02] at time 14:30:46.254571
2018-10-06 14:30:46,255 [salt.state       :1803][INFO    ][3037] Executing state host.present for [msg02]
2018-10-06 14:30:46,256 [salt.state       :290 ][INFO    ][3037] {'host': 'msg02'}
2018-10-06 14:30:46,256 [salt.state       :1941][INFO    ][3037] Completed state [msg02] at time 14:30:46.256280 duration_in_ms=1.709
2018-10-06 14:30:46,256 [salt.state       :1770][INFO    ][3037] Running state [msg02.mcp-pike-odl-ha.local] at time 14:30:46.256585
2018-10-06 14:30:46,256 [salt.state       :1803][INFO    ][3037] Executing state host.present for [msg02.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,257 [salt.state       :290 ][INFO    ][3037] {'host': 'msg02.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,257 [salt.state       :1941][INFO    ][3037] Completed state [msg02.mcp-pike-odl-ha.local] at time 14:30:46.257939 duration_in_ms=1.354
2018-10-06 14:30:46,258 [salt.state       :1770][INFO    ][3037] Running state [msg03] at time 14:30:46.258250
2018-10-06 14:30:46,258 [salt.state       :1803][INFO    ][3037] Executing state host.present for [msg03]
2018-10-06 14:30:46,259 [salt.state       :290 ][INFO    ][3037] {'host': 'msg03'}
2018-10-06 14:30:46,259 [salt.state       :1941][INFO    ][3037] Completed state [msg03] at time 14:30:46.259653 duration_in_ms=1.403
2018-10-06 14:30:46,260 [salt.state       :1770][INFO    ][3037] Running state [msg03.mcp-pike-odl-ha.local] at time 14:30:46.259972
2018-10-06 14:30:46,260 [salt.state       :1803][INFO    ][3037] Executing state host.present for [msg03.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,261 [salt.state       :290 ][INFO    ][3037] {'host': 'msg03.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,261 [salt.state       :1941][INFO    ][3037] Completed state [msg03.mcp-pike-odl-ha.local] at time 14:30:46.261371 duration_in_ms=1.398
2018-10-06 14:30:46,261 [salt.state       :1770][INFO    ][3037] Running state [msg01] at time 14:30:46.261677
2018-10-06 14:30:46,261 [salt.state       :1803][INFO    ][3037] Executing state host.present for [msg01]
2018-10-06 14:30:46,263 [salt.state       :290 ][INFO    ][3037] {'host': 'msg01'}
2018-10-06 14:30:46,263 [salt.state       :1941][INFO    ][3037] Completed state [msg01] at time 14:30:46.263610 duration_in_ms=1.933
2018-10-06 14:30:46,263 [salt.state       :1770][INFO    ][3037] Running state [msg01.mcp-pike-odl-ha.local] at time 14:30:46.263938
2018-10-06 14:30:46,264 [salt.state       :1803][INFO    ][3037] Executing state host.present for [msg01.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,265 [salt.state       :290 ][INFO    ][3037] {'host': 'msg01.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,265 [salt.state       :1941][INFO    ][3037] Completed state [msg01.mcp-pike-odl-ha.local] at time 14:30:46.265318 duration_in_ms=1.379
2018-10-06 14:30:46,265 [salt.state       :1770][INFO    ][3037] Running state [msg] at time 14:30:46.265628
2018-10-06 14:30:46,265 [salt.state       :1803][INFO    ][3037] Executing state host.present for [msg]
2018-10-06 14:30:46,266 [salt.state       :290 ][INFO    ][3037] {'host': 'msg'}
2018-10-06 14:30:46,267 [salt.state       :1941][INFO    ][3037] Completed state [msg] at time 14:30:46.267020 duration_in_ms=1.391
2018-10-06 14:30:46,267 [salt.state       :1770][INFO    ][3037] Running state [msg.mcp-pike-odl-ha.local] at time 14:30:46.267368
2018-10-06 14:30:46,267 [salt.state       :1803][INFO    ][3037] Executing state host.present for [msg.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,268 [salt.state       :290 ][INFO    ][3037] {'host': 'msg.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,268 [salt.state       :1941][INFO    ][3037] Completed state [msg.mcp-pike-odl-ha.local] at time 14:30:46.268739 duration_in_ms=1.371
2018-10-06 14:30:46,269 [salt.state       :1770][INFO    ][3037] Running state [cfg01] at time 14:30:46.269055
2018-10-06 14:30:46,269 [salt.state       :1803][INFO    ][3037] Executing state host.present for [cfg01]
2018-10-06 14:30:46,269 [salt.state       :290 ][INFO    ][3037] Host cfg01 (192.168.10.11) already present
2018-10-06 14:30:46,269 [salt.state       :1941][INFO    ][3037] Completed state [cfg01] at time 14:30:46.269839 duration_in_ms=0.785
2018-10-06 14:30:46,270 [salt.state       :1770][INFO    ][3037] Running state [cfg01.mcp-pike-odl-ha.local] at time 14:30:46.270128
2018-10-06 14:30:46,270 [salt.state       :1803][INFO    ][3037] Executing state host.present for [cfg01.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,270 [salt.state       :290 ][INFO    ][3037] Host cfg01.mcp-pike-odl-ha.local (192.168.10.11) already present
2018-10-06 14:30:46,271 [salt.state       :1941][INFO    ][3037] Completed state [cfg01.mcp-pike-odl-ha.local] at time 14:30:46.270951 duration_in_ms=0.823
2018-10-06 14:30:46,271 [salt.state       :1770][INFO    ][3037] Running state [cmp002] at time 14:30:46.271271
2018-10-06 14:30:46,271 [salt.state       :1803][INFO    ][3037] Executing state host.present for [cmp002]
2018-10-06 14:30:46,274 [salt.state       :290 ][INFO    ][3037] {'host': 'cmp002'}
2018-10-06 14:30:46,274 [salt.state       :1941][INFO    ][3037] Completed state [cmp002] at time 14:30:46.274294 duration_in_ms=3.024
2018-10-06 14:30:46,274 [salt.state       :1770][INFO    ][3037] Running state [cmp002.mcp-pike-odl-ha.local] at time 14:30:46.274615
2018-10-06 14:30:46,275 [salt.state       :1803][INFO    ][3037] Executing state host.present for [cmp002.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,276 [salt.state       :290 ][INFO    ][3037] {'host': 'cmp002.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,276 [salt.state       :1941][INFO    ][3037] Completed state [cmp002.mcp-pike-odl-ha.local] at time 14:30:46.276711 duration_in_ms=2.095
2018-10-06 14:30:46,277 [salt.state       :1770][INFO    ][3037] Running state [cmp001] at time 14:30:46.277026
2018-10-06 14:30:46,277 [salt.state       :1803][INFO    ][3037] Executing state host.present for [cmp001]
2018-10-06 14:30:46,278 [salt.state       :290 ][INFO    ][3037] {'host': 'cmp001'}
2018-10-06 14:30:46,278 [salt.state       :1941][INFO    ][3037] Completed state [cmp001] at time 14:30:46.278408 duration_in_ms=1.381
2018-10-06 14:30:46,278 [salt.state       :1770][INFO    ][3037] Running state [cmp001.mcp-pike-odl-ha.local] at time 14:30:46.278725
2018-10-06 14:30:46,279 [salt.state       :1803][INFO    ][3037] Executing state host.present for [cmp001.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,280 [salt.state       :290 ][INFO    ][3037] {'host': 'cmp001.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,280 [salt.state       :1941][INFO    ][3037] Completed state [cmp001.mcp-pike-odl-ha.local] at time 14:30:46.280245 duration_in_ms=1.52
2018-10-06 14:30:46,280 [salt.state       :1770][INFO    ][3037] Running state [dbs01] at time 14:30:46.280563
2018-10-06 14:30:46,280 [salt.state       :1803][INFO    ][3037] Executing state host.present for [dbs01]
2018-10-06 14:30:46,281 [salt.state       :290 ][INFO    ][3037] {'host': 'dbs01'}
2018-10-06 14:30:46,281 [salt.state       :1941][INFO    ][3037] Completed state [dbs01] at time 14:30:46.281947 duration_in_ms=1.383
2018-10-06 14:30:46,282 [salt.state       :1770][INFO    ][3037] Running state [dbs01.mcp-pike-odl-ha.local] at time 14:30:46.282263
2018-10-06 14:30:46,282 [salt.state       :1803][INFO    ][3037] Executing state host.present for [dbs01.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,283 [salt.state       :290 ][INFO    ][3037] {'host': 'dbs01.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,283 [salt.state       :1941][INFO    ][3037] Completed state [dbs01.mcp-pike-odl-ha.local] at time 14:30:46.283761 duration_in_ms=1.498
2018-10-06 14:30:46,284 [salt.state       :1770][INFO    ][3037] Running state [dbs02] at time 14:30:46.284076
2018-10-06 14:30:46,284 [salt.state       :1803][INFO    ][3037] Executing state host.present for [dbs02]
2018-10-06 14:30:46,285 [salt.state       :290 ][INFO    ][3037] {'host': 'dbs02'}
2018-10-06 14:30:46,285 [salt.state       :1941][INFO    ][3037] Completed state [dbs02] at time 14:30:46.285481 duration_in_ms=1.405
2018-10-06 14:30:46,285 [salt.state       :1770][INFO    ][3037] Running state [dbs02.mcp-pike-odl-ha.local] at time 14:30:46.285799
2018-10-06 14:30:46,286 [salt.state       :1803][INFO    ][3037] Executing state host.present for [dbs02.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,287 [salt.state       :290 ][INFO    ][3037] {'host': 'dbs02.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,287 [salt.state       :1941][INFO    ][3037] Completed state [dbs02.mcp-pike-odl-ha.local] at time 14:30:46.287568 duration_in_ms=1.769
2018-10-06 14:30:46,287 [salt.state       :1770][INFO    ][3037] Running state [dbs03] at time 14:30:46.287912
2018-10-06 14:30:46,288 [salt.state       :1803][INFO    ][3037] Executing state host.present for [dbs03]
2018-10-06 14:30:46,289 [salt.state       :290 ][INFO    ][3037] {'host': 'dbs03'}
2018-10-06 14:30:46,289 [salt.state       :1941][INFO    ][3037] Completed state [dbs03] at time 14:30:46.289327 duration_in_ms=1.415
2018-10-06 14:30:46,289 [salt.state       :1770][INFO    ][3037] Running state [dbs03.mcp-pike-odl-ha.local] at time 14:30:46.289648
2018-10-06 14:30:46,289 [salt.state       :1803][INFO    ][3037] Executing state host.present for [dbs03.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,291 [salt.state       :290 ][INFO    ][3037] {'host': 'dbs03.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,291 [salt.state       :1941][INFO    ][3037] Completed state [dbs03.mcp-pike-odl-ha.local] at time 14:30:46.291221 duration_in_ms=1.572
2018-10-06 14:30:46,291 [salt.state       :1770][INFO    ][3037] Running state [odl01] at time 14:30:46.291597
2018-10-06 14:30:46,291 [salt.state       :1803][INFO    ][3037] Executing state host.present for [odl01]
2018-10-06 14:30:46,292 [salt.state       :290 ][INFO    ][3037] {'host': 'odl01'}
2018-10-06 14:30:46,293 [salt.state       :1941][INFO    ][3037] Completed state [odl01] at time 14:30:46.293054 duration_in_ms=1.457
2018-10-06 14:30:46,293 [salt.state       :1770][INFO    ][3037] Running state [odl01.mcp-pike-odl-ha.local] at time 14:30:46.293381
2018-10-06 14:30:46,293 [salt.state       :1803][INFO    ][3037] Executing state host.present for [odl01.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,294 [salt.state       :290 ][INFO    ][3037] {'host': 'odl01.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,295 [salt.state       :1941][INFO    ][3037] Completed state [odl01.mcp-pike-odl-ha.local] at time 14:30:46.295125 duration_in_ms=1.745
2018-10-06 14:30:46,295 [salt.state       :1770][INFO    ][3037] Running state [mas01] at time 14:30:46.295488
2018-10-06 14:30:46,295 [salt.state       :1803][INFO    ][3037] Executing state host.present for [mas01]
2018-10-06 14:30:46,296 [salt.state       :290 ][INFO    ][3037] {'host': 'mas01'}
2018-10-06 14:30:46,297 [salt.state       :1941][INFO    ][3037] Completed state [mas01] at time 14:30:46.296970 duration_in_ms=1.482
2018-10-06 14:30:46,297 [salt.state       :1770][INFO    ][3037] Running state [mas01.mcp-pike-odl-ha.local] at time 14:30:46.297292
2018-10-06 14:30:46,297 [salt.state       :1803][INFO    ][3037] Executing state host.present for [mas01.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,298 [salt.state       :290 ][INFO    ][3037] {'host': 'mas01.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,298 [salt.state       :1941][INFO    ][3037] Completed state [mas01.mcp-pike-odl-ha.local] at time 14:30:46.298723 duration_in_ms=1.431
2018-10-06 14:30:46,299 [salt.state       :1770][INFO    ][3037] Running state [ctl02] at time 14:30:46.299402
2018-10-06 14:30:46,299 [salt.state       :1803][INFO    ][3037] Executing state host.present for [ctl02]
2018-10-06 14:30:46,300 [salt.state       :290 ][INFO    ][3037] {'host': 'ctl02'}
2018-10-06 14:30:46,300 [salt.state       :1941][INFO    ][3037] Completed state [ctl02] at time 14:30:46.300853 duration_in_ms=1.451
2018-10-06 14:30:46,301 [salt.state       :1770][INFO    ][3037] Running state [ctl02.mcp-pike-odl-ha.local] at time 14:30:46.301178
2018-10-06 14:30:46,301 [salt.state       :1803][INFO    ][3037] Executing state host.present for [ctl02.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,302 [salt.state       :290 ][INFO    ][3037] {'host': 'ctl02.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,302 [salt.state       :1941][INFO    ][3037] Completed state [ctl02.mcp-pike-odl-ha.local] at time 14:30:46.302621 duration_in_ms=1.443
2018-10-06 14:30:46,303 [salt.state       :1770][INFO    ][3037] Running state [ctl03] at time 14:30:46.303546
2018-10-06 14:30:46,303 [salt.state       :1803][INFO    ][3037] Executing state host.present for [ctl03]
2018-10-06 14:30:46,304 [salt.state       :290 ][INFO    ][3037] {'host': 'ctl03'}
2018-10-06 14:30:46,305 [salt.state       :1941][INFO    ][3037] Completed state [ctl03] at time 14:30:46.305049 duration_in_ms=1.503
2018-10-06 14:30:46,305 [salt.state       :1770][INFO    ][3037] Running state [ctl03.mcp-pike-odl-ha.local] at time 14:30:46.305376
2018-10-06 14:30:46,305 [salt.state       :1803][INFO    ][3037] Executing state host.present for [ctl03.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,306 [salt.state       :290 ][INFO    ][3037] {'host': 'ctl03.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,307 [salt.state       :1941][INFO    ][3037] Completed state [ctl03.mcp-pike-odl-ha.local] at time 14:30:46.306968 duration_in_ms=1.593
2018-10-06 14:30:46,307 [salt.state       :1770][INFO    ][3037] Running state [ctl01] at time 14:30:46.307316
2018-10-06 14:30:46,307 [salt.state       :1803][INFO    ][3037] Executing state host.present for [ctl01]
2018-10-06 14:30:46,308 [salt.state       :290 ][INFO    ][3037] {'host': 'ctl01'}
2018-10-06 14:30:46,308 [salt.state       :1941][INFO    ][3037] Completed state [ctl01] at time 14:30:46.308828 duration_in_ms=1.511
2018-10-06 14:30:46,309 [salt.state       :1770][INFO    ][3037] Running state [ctl01.mcp-pike-odl-ha.local] at time 14:30:46.309159
2018-10-06 14:30:46,309 [salt.state       :1803][INFO    ][3037] Executing state host.present for [ctl01.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,310 [salt.state       :290 ][INFO    ][3037] {'host': 'ctl01.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,310 [salt.state       :1941][INFO    ][3037] Completed state [ctl01.mcp-pike-odl-ha.local] at time 14:30:46.310643 duration_in_ms=1.484
2018-10-06 14:30:46,311 [salt.state       :1770][INFO    ][3037] Running state [ctl] at time 14:30:46.311004
2018-10-06 14:30:46,311 [salt.state       :1803][INFO    ][3037] Executing state host.present for [ctl]
2018-10-06 14:30:46,312 [salt.state       :290 ][INFO    ][3037] {'host': 'ctl'}
2018-10-06 14:30:46,312 [salt.state       :1941][INFO    ][3037] Completed state [ctl] at time 14:30:46.312546 duration_in_ms=1.542
2018-10-06 14:30:46,312 [salt.state       :1770][INFO    ][3037] Running state [ctl.mcp-pike-odl-ha.local] at time 14:30:46.312876
2018-10-06 14:30:46,313 [salt.state       :1803][INFO    ][3037] Executing state host.present for [ctl.mcp-pike-odl-ha.local]
2018-10-06 14:30:46,314 [salt.state       :290 ][INFO    ][3037] {'host': 'ctl.mcp-pike-odl-ha.local'}
2018-10-06 14:30:46,314 [salt.state       :1941][INFO    ][3037] Completed state [ctl.mcp-pike-odl-ha.local] at time 14:30:46.314349 duration_in_ms=1.474
2018-10-06 14:30:46,314 [salt.state       :1770][INFO    ][3037] Running state [/etc/network/interfaces.d/50-cloud-init.cfg] at time 14:30:46.314581
2018-10-06 14:30:46,315 [salt.state       :1803][INFO    ][3037] Executing state file.absent for [/etc/network/interfaces.d/50-cloud-init.cfg]
2018-10-06 14:30:46,315 [salt.state       :290 ][INFO    ][3037] {'removed': '/etc/network/interfaces.d/50-cloud-init.cfg'}
2018-10-06 14:30:46,316 [salt.state       :1941][INFO    ][3037] Completed state [/etc/network/interfaces.d/50-cloud-init.cfg] at time 14:30:46.315985 duration_in_ms=1.404
2018-10-06 14:30:46,316 [salt.state       :1770][INFO    ][3037] Running state [ens2] at time 14:30:46.316607
2018-10-06 14:30:46,316 [salt.state       :1803][INFO    ][3037] Executing state network.managed for [ens2]
2018-10-06 14:30:46,448 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['ifdown', 'ens2'] in directory '/root'
2018-10-06 14:30:47,632 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['ifup', 'ens2'] in directory '/root'
2018-10-06 14:30:48,882 [salt.state       :290 ][INFO    ][3037] {'interface': 'Added network interface.', 'status': 'Interface ens2 restart to validate'}
2018-10-06 14:30:48,884 [salt.state       :1941][INFO    ][3037] Completed state [ens2] at time 14:30:48.884430 duration_in_ms=2567.822
2018-10-06 14:30:48,885 [salt.state       :1770][INFO    ][3037] Running state [ens3] at time 14:30:48.885001
2018-10-06 14:30:48,885 [salt.state       :1803][INFO    ][3037] Executing state network.managed for [ens3]
2018-10-06 14:30:48,915 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['ifup', 'ens3'] in directory '/root'
2018-10-06 14:30:49,568 [salt.state       :290 ][INFO    ][3037] {'interface': 'Added network interface.', 'status': 'Interface ens3 is up'}
2018-10-06 14:30:49,569 [salt.state       :1941][INFO    ][3037] Completed state [ens3] at time 14:30:49.569410 duration_in_ms=684.408
2018-10-06 14:30:49,570 [salt.state       :1770][INFO    ][3037] Running state [/etc/profile.d/proxy.sh] at time 14:30:49.569960
2018-10-06 14:30:49,570 [salt.state       :1803][INFO    ][3037] Executing state file.absent for [/etc/profile.d/proxy.sh]
2018-10-06 14:30:49,571 [salt.state       :290 ][INFO    ][3037] File /etc/profile.d/proxy.sh is not present
2018-10-06 14:30:49,571 [salt.state       :1941][INFO    ][3037] Completed state [/etc/profile.d/proxy.sh] at time 14:30:49.571604 duration_in_ms=1.645
2018-10-06 14:30:49,572 [salt.state       :1770][INFO    ][3037] Running state [/etc/apt/apt.conf.d/95proxies] at time 14:30:49.572046
2018-10-06 14:30:49,572 [salt.state       :1803][INFO    ][3037] Executing state file.absent for [/etc/apt/apt.conf.d/95proxies]
2018-10-06 14:30:49,572 [salt.state       :290 ][INFO    ][3037] File /etc/apt/apt.conf.d/95proxies is not present
2018-10-06 14:30:49,573 [salt.state       :1941][INFO    ][3037] Completed state [/etc/apt/apt.conf.d/95proxies] at time 14:30:49.573372 duration_in_ms=1.326
2018-10-06 14:30:49,575 [salt.state       :1770][INFO    ][3037] Running state [ntp] at time 14:30:49.574967
2018-10-06 14:30:49,575 [salt.state       :1803][INFO    ][3037] Executing state pkg.installed for [ntp]
2018-10-06 14:30:49,836 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-10-06 14:30:49,870 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'ntp'] in directory '/root'
2018-10-06 14:30:50,484 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006143050471790
2018-10-06 14:30:50,505 [salt.minion      :1431][INFO    ][5527] Starting a new job with PID 5527
2018-10-06 14:30:50,527 [salt.minion      :1708][INFO    ][5527] Returning information for job: 20181006143050471790
2018-10-06 14:30:53,358 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-10-06 14:30:53,393 [salt.state       :290 ][INFO    ][3037] Made the following changes:
'ntp' changed from 'absent' to '1:4.2.8p4+dfsg-3ubuntu5.9'
'libopts25' changed from 'absent' to '1:5.18.7-3'

2018-10-06 14:30:53,413 [salt.state       :905 ][INFO    ][3037] Loading fresh modules for state activity
2018-10-06 14:30:53,455 [salt.state       :1941][INFO    ][3037] Completed state [ntp] at time 14:30:53.455712 duration_in_ms=3880.744
2018-10-06 14:30:53,460 [salt.state       :1770][INFO    ][3037] Running state [/etc/ntp.conf] at time 14:30:53.460742
2018-10-06 14:30:53,461 [salt.state       :1803][INFO    ][3037] Executing state file.managed for [/etc/ntp.conf]
2018-10-06 14:30:53,483 [salt.fileclient  :1215][INFO    ][3037] Fetching file from saltenv 'base', ** done ** 'ntp/files/ntp.conf'
2018-10-06 14:30:53,556 [salt.state       :290 ][INFO    ][3037] File changed:
--- 
+++ 
@@ -1,66 +1,25 @@
-# /etc/ntp.conf, configuration for ntpd; see ntp.conf(5) for help
 
-driftfile /var/lib/ntp/ntp.drift
 
-# Enable this if you want statistics to be logged.
-#statsdir /var/log/ntpstats/
+# ntpd will only synchronize your clock.
 
-statistics loopstats peerstats clockstats
-filegen loopstats file loopstats type day enable
-filegen peerstats file peerstats type day enable
-filegen clockstats file clockstats type day enable
+# For details, see:
+# - the ntp.conf man page
+# - http://support.ntp.org/bin/view/Support/GettingStarted
+# - https://wiki.archlinux.org/index.php/Network_Time_Protocol_daemon
 
-# Specify one or more NTP servers.
+# Associate to cloud NTP pool servers
+server 1.pool.ntp.org iburst
+server 0.pool.ntp.org
 
-# Use servers from the NTP Pool Project. Approved by Ubuntu Technical Board
-# on 2011-02-08 (LP: #104525). See http://www.pool.ntp.org/join.html for
-# more information.
-pool 0.ubuntu.pool.ntp.org iburst
-pool 1.ubuntu.pool.ntp.org iburst
-pool 2.ubuntu.pool.ntp.org iburst
-pool 3.ubuntu.pool.ntp.org iburst
+# Exchange time with everybody, but don't allow configuration.
+restrict -4 default kod nomodify notrap nopeer noquery
+restrict -6 default kod nomodify notrap nopeer noquery
 
-# Use Ubuntu's ntp server as a fallback.
-pool ntp.ubuntu.com
-
-# Access control configuration; see /usr/share/doc/ntp-doc/html/accopt.html for
-# details.  The web page <http://support.ntp.org/bin/view/Support/AccessRestrictions>
-# might also be helpful.
-#
-# Note that "restrict" applies to both servers and clients, so a configuration
-# that might be intended to block requests from certain clients could also end
-# up blocking replies from your own upstream servers.
-
-# By default, exchange time with everybody, but don't allow configuration.
-restrict -4 default kod notrap nomodify nopeer noquery limited
-restrict -6 default kod notrap nomodify nopeer noquery limited
-
-# Local users may interrogate the ntp server more closely.
+# Only allow read-only access from localhost
 restrict 127.0.0.1
 restrict ::1
 
-# Needed for adding pool entries
-restrict source notrap nomodify noquery
+# mode7 is required for collectd monitoring
 
-# Clients from this (example!) subnet have unlimited access, but only if
-# cryptographically authenticated.
-#restrict 192.168.123.0 mask 255.255.255.0 notrust
-
-
-# If you want to provide time to your local subnet, change the next line.
-# (Again, the address is an example only.)
-#broadcast 192.168.123.255
-
-# If you want to listen to time broadcasts on your local subnet, de-comment the
-# next lines.  Please do this only if you trust everybody on the network!
-#disable auth
-#broadcastclient
-
-#Changes recquired to use pps synchonisation as explained in documentation:
-#http://www.ntp.org/ntpfaq/NTP-s-config-adv.htm#AEN3918
-
-#server 127.127.8.1 mode 135 prefer    # Meinberg GPS167 with PPS
-#fudge 127.127.8.1 time1 0.0042        # relative to PPS for my hardware
-
-#server 127.127.22.1                   # ATOM(PPS)
-#fudge 127.127.22.1 flag3 1            # enable PPS API
+# Location of drift file
+driftfile /var/lib/ntp/ntp.drift

2018-10-06 14:30:53,556 [salt.state       :1941][INFO    ][3037] Completed state [/etc/ntp.conf] at time 14:30:53.556879 duration_in_ms=96.137
2018-10-06 14:30:54,038 [salt.state       :1770][INFO    ][3037] Running state [ntp] at time 14:30:54.038045
2018-10-06 14:30:54,038 [salt.state       :1803][INFO    ][3037] Executing state service.running for [ntp]
2018-10-06 14:30:54,039 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemctl', 'status', 'ntp.service', '-n', '0'] in directory '/root'
2018-10-06 14:30:54,057 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemctl', 'is-active', 'ntp.service'] in directory '/root'
2018-10-06 14:30:54,071 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemctl', 'is-enabled', 'ntp.service'] in directory '/root'
2018-10-06 14:30:54,092 [salt.state       :290 ][INFO    ][3037] The service ntp is already running
2018-10-06 14:30:54,093 [salt.state       :1941][INFO    ][3037] Completed state [ntp] at time 14:30:54.093393 duration_in_ms=55.348
2018-10-06 14:30:54,093 [salt.state       :1770][INFO    ][3037] Running state [ntp] at time 14:30:54.093683
2018-10-06 14:30:54,094 [salt.state       :1803][INFO    ][3037] Executing state service.mod_watch for [ntp]
2018-10-06 14:30:54,095 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemctl', 'is-active', 'ntp.service'] in directory '/root'
2018-10-06 14:30:54,113 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'ntp.service'] in directory '/root'
2018-10-06 14:30:54,195 [salt.state       :290 ][INFO    ][3037] {'ntp': True}
2018-10-06 14:30:54,196 [salt.state       :1941][INFO    ][3037] Completed state [ntp] at time 14:30:54.196328 duration_in_ms=102.644
2018-10-06 14:30:54,203 [salt.minion      :1708][INFO    ][3037] Returning information for job: 20181006143019913667
2018-10-06 14:31:17,222 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command state.apply with jid 20181006143117212897
2018-10-06 14:31:17,236 [salt.minion      :1431][INFO    ][6434] Starting a new job with PID 6434
2018-10-06 14:31:20,320 [salt.state       :905 ][INFO    ][6434] Loading fresh modules for state activity
2018-10-06 14:31:24,895 [salt.state       :1770][INFO    ][6434] Running state [/etc/environment] at time 14:31:24.895923
2018-10-06 14:31:24,896 [salt.state       :1803][INFO    ][6434] Executing state file.blockreplace for [/etc/environment]
2018-10-06 14:31:24,901 [salt.state       :290 ][INFO    ][6434] No changes needed to be made
2018-10-06 14:31:24,901 [salt.state       :1941][INFO    ][6434] Completed state [/etc/environment] at time 14:31:24.901881 duration_in_ms=5.958
2018-10-06 14:31:24,902 [salt.state       :1770][INFO    ][6434] Running state [/etc/profile.d] at time 14:31:24.902106
2018-10-06 14:31:24,902 [salt.state       :1803][INFO    ][6434] Executing state file.directory for [/etc/profile.d]
2018-10-06 14:31:24,903 [salt.state       :290 ][INFO    ][6434] Directory /etc/profile.d is in the correct state
Directory /etc/profile.d updated
2018-10-06 14:31:24,904 [salt.state       :1941][INFO    ][6434] Completed state [/etc/profile.d] at time 14:31:24.904067 duration_in_ms=1.961
2018-10-06 14:31:24,904 [salt.state       :1770][INFO    ][6434] Running state [fake_linux_system_at] at time 14:31:24.904680
2018-10-06 14:31:24,904 [salt.state       :1803][INFO    ][6434] Executing state test.nop for [fake_linux_system_at]
2018-10-06 14:31:24,906 [salt.state       :290 ][INFO    ][6434] Success!
2018-10-06 14:31:24,906 [salt.state       :1941][INFO    ][6434] Completed state [fake_linux_system_at] at time 14:31:24.906474 duration_in_ms=1.794
2018-10-06 14:31:24,906 [salt.state       :1770][INFO    ][6434] Running state [fake_linux_system_cron] at time 14:31:24.906689
2018-10-06 14:31:24,906 [salt.state       :1803][INFO    ][6434] Executing state test.nop for [fake_linux_system_cron]
2018-10-06 14:31:24,907 [salt.state       :290 ][INFO    ][6434] Success!
2018-10-06 14:31:24,907 [salt.state       :1941][INFO    ][6434] Completed state [fake_linux_system_cron] at time 14:31:24.907309 duration_in_ms=0.62
2018-10-06 14:31:25,549 [salt.state       :1770][INFO    ][6434] Running state [/etc/apt/apt.conf.d/99prefer_ipv4-salt] at time 14:31:25.549661
2018-10-06 14:31:25,550 [salt.state       :1803][INFO    ][6434] Executing state file.managed for [/etc/apt/apt.conf.d/99prefer_ipv4-salt]
2018-10-06 14:31:25,582 [salt.state       :290 ][INFO    ][6434] File /etc/apt/apt.conf.d/99prefer_ipv4-salt is in the correct state
2018-10-06 14:31:25,582 [salt.state       :1941][INFO    ][6434] Completed state [/etc/apt/apt.conf.d/99prefer_ipv4-salt] at time 14:31:25.582721 duration_in_ms=33.061
2018-10-06 14:31:25,583 [salt.state       :1770][INFO    ][6434] Running state [/etc/apt/apt.conf.d/99allow_downgrades-salt] at time 14:31:25.582974
2018-10-06 14:31:25,583 [salt.state       :1803][INFO    ][6434] Executing state file.managed for [/etc/apt/apt.conf.d/99allow_downgrades-salt]
2018-10-06 14:31:25,606 [salt.state       :290 ][INFO    ][6434] File /etc/apt/apt.conf.d/99allow_downgrades-salt is in the correct state
2018-10-06 14:31:25,607 [salt.state       :1941][INFO    ][6434] Completed state [/etc/apt/apt.conf.d/99allow_downgrades-salt] at time 14:31:25.607296 duration_in_ms=24.322
2018-10-06 14:31:25,608 [salt.state       :1770][INFO    ][6434] Running state [linux_repo_prereq_pkgs] at time 14:31:25.608760
2018-10-06 14:31:25,609 [salt.state       :1803][INFO    ][6434] Executing state pkg.installed for [linux_repo_prereq_pkgs]
2018-10-06 14:31:25,610 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-10-06 14:31:25,989 [salt.state       :290 ][INFO    ][6434] All specified packages are already installed
2018-10-06 14:31:25,989 [salt.state       :1941][INFO    ][6434] Completed state [linux_repo_prereq_pkgs] at time 14:31:25.989868 duration_in_ms=381.108
2018-10-06 14:31:25,990 [salt.state       :1770][INFO    ][6434] Running state [/etc/apt/apt.conf.d/99proxies-salt] at time 14:31:25.990241
2018-10-06 14:31:25,990 [salt.state       :1803][INFO    ][6434] Executing state file.managed for [/etc/apt/apt.conf.d/99proxies-salt]
2018-10-06 14:31:26,022 [salt.state       :290 ][INFO    ][6434] File /etc/apt/apt.conf.d/99proxies-salt is in the correct state
2018-10-06 14:31:26,022 [salt.state       :1941][INFO    ][6434] Completed state [/etc/apt/apt.conf.d/99proxies-salt] at time 14:31:26.022583 duration_in_ms=32.342
2018-10-06 14:31:26,022 [salt.state       :1770][INFO    ][6434] Running state [/etc/apt/apt.conf.d/99proxies-salt-mk_openstack] at time 14:31:26.022830
2018-10-06 14:31:26,023 [salt.state       :1803][INFO    ][6434] Executing state file.absent for [/etc/apt/apt.conf.d/99proxies-salt-mk_openstack]
2018-10-06 14:31:26,023 [salt.state       :290 ][INFO    ][6434] File /etc/apt/apt.conf.d/99proxies-salt-mk_openstack is not present
2018-10-06 14:31:26,023 [salt.state       :1941][INFO    ][6434] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mk_openstack] at time 14:31:26.023623 duration_in_ms=0.793
2018-10-06 14:31:26,023 [salt.state       :1770][INFO    ][6434] Running state [/etc/apt/preferences.d/mk_openstack] at time 14:31:26.023845
2018-10-06 14:31:26,024 [salt.state       :1803][INFO    ][6434] Executing state file.managed for [/etc/apt/preferences.d/mk_openstack]
2018-10-06 14:31:26,144 [salt.state       :290 ][INFO    ][6434] File /etc/apt/preferences.d/mk_openstack is in the correct state
2018-10-06 14:31:26,144 [salt.state       :1941][INFO    ][6434] Completed state [/etc/apt/preferences.d/mk_openstack] at time 14:31:26.144529 duration_in_ms=120.683
2018-10-06 14:31:26,148 [salt.state       :1770][INFO    ][6434] Running state [deb [arch=amd64] http://apt.mirantis.com/xenial/ nightly pike] at time 14:31:26.148008
2018-10-06 14:31:26,148 [salt.state       :1803][INFO    ][6434] Executing state pkgrepo.managed for [deb [arch=amd64] http://apt.mirantis.com/xenial/ nightly pike]
2018-10-06 14:31:26,191 [salt.state       :290 ][INFO    ][6434] Package repo 'deb [arch=amd64] http://apt.mirantis.com/xenial/ nightly pike' already configured
2018-10-06 14:31:26,191 [salt.state       :1941][INFO    ][6434] Completed state [deb [arch=amd64] http://apt.mirantis.com/xenial/ nightly pike] at time 14:31:26.191888 duration_in_ms=43.88
2018-10-06 14:31:26,192 [salt.state       :1770][INFO    ][6434] Running state [/etc/apt/apt.conf.d/99proxies-salt-uca-staging] at time 14:31:26.192125
2018-10-06 14:31:26,192 [salt.state       :1803][INFO    ][6434] Executing state file.absent for [/etc/apt/apt.conf.d/99proxies-salt-uca-staging]
2018-10-06 14:31:26,192 [salt.state       :290 ][INFO    ][6434] File /etc/apt/apt.conf.d/99proxies-salt-uca-staging is not present
2018-10-06 14:31:26,192 [salt.state       :1941][INFO    ][6434] Completed state [/etc/apt/apt.conf.d/99proxies-salt-uca-staging] at time 14:31:26.192843 duration_in_ms=0.717
2018-10-06 14:31:26,193 [salt.state       :1770][INFO    ][6434] Running state [/etc/apt/preferences.d/uca-staging] at time 14:31:26.193072
2018-10-06 14:31:26,193 [salt.state       :1803][INFO    ][6434] Executing state file.absent for [/etc/apt/preferences.d/uca-staging]
2018-10-06 14:31:26,193 [salt.state       :290 ][INFO    ][6434] File /etc/apt/preferences.d/uca-staging is not present
2018-10-06 14:31:26,193 [salt.state       :1941][INFO    ][6434] Completed state [/etc/apt/preferences.d/uca-staging] at time 14:31:26.193730 duration_in_ms=0.658
2018-10-06 14:31:26,198 [salt.state       :1770][INFO    ][6434] Running state [apt-key adv --keyserver keyserver.ubuntu.com --recv 9F68104E] at time 14:31:26.198754
2018-10-06 14:31:26,199 [salt.state       :1803][INFO    ][6434] Executing state cmd.run for [apt-key adv --keyserver keyserver.ubuntu.com --recv 9F68104E]
2018-10-06 14:31:26,199 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'test -e /etc/apt/sources.list.d/uca-staging.list' in directory '/root'
2018-10-06 14:31:26,212 [salt.state       :290 ][INFO    ][6434] unless execution succeeded
2018-10-06 14:31:26,214 [salt.state       :1941][INFO    ][6434] Completed state [apt-key adv --keyserver keyserver.ubuntu.com --recv 9F68104E] at time 14:31:26.214040 duration_in_ms=15.286
2018-10-06 14:31:26,216 [salt.state       :1770][INFO    ][6434] Running state [deb http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial main] at time 14:31:26.216896
2018-10-06 14:31:26,217 [salt.state       :1803][INFO    ][6434] Executing state pkgrepo.managed for [deb http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial main]
2018-10-06 14:31:26,257 [salt.state       :290 ][INFO    ][6434] Package repo 'deb http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial main' already configured
2018-10-06 14:31:26,257 [salt.state       :1941][INFO    ][6434] Completed state [deb http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial main] at time 14:31:26.257645 duration_in_ms=40.748
2018-10-06 14:31:26,257 [salt.state       :1770][INFO    ][6434] Running state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack] at time 14:31:26.257897
2018-10-06 14:31:26,258 [salt.state       :1803][INFO    ][6434] Executing state file.absent for [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack]
2018-10-06 14:31:26,258 [salt.state       :290 ][INFO    ][6434] File /etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack is not present
2018-10-06 14:31:26,258 [salt.state       :1941][INFO    ][6434] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack] at time 14:31:26.258697 duration_in_ms=0.799
2018-10-06 14:31:26,258 [salt.state       :1770][INFO    ][6434] Running state [/etc/apt/preferences.d/mirantis_openstack] at time 14:31:26.258924
2018-10-06 14:31:26,259 [salt.state       :1803][INFO    ][6434] Executing state file.managed for [/etc/apt/preferences.d/mirantis_openstack]
2018-10-06 14:31:26,380 [salt.state       :290 ][INFO    ][6434] File /etc/apt/preferences.d/mirantis_openstack is in the correct state
2018-10-06 14:31:26,380 [salt.state       :1941][INFO    ][6434] Completed state [/etc/apt/preferences.d/mirantis_openstack] at time 14:31:26.380261 duration_in_ms=121.337
2018-10-06 14:31:26,382 [salt.state       :1770][INFO    ][6434] Running state [deb http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike main] at time 14:31:26.382262
2018-10-06 14:31:26,382 [salt.state       :1803][INFO    ][6434] Executing state pkgrepo.managed for [deb http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike main]
2018-10-06 14:31:26,531 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command ['apt-key', 'add', '/var/cache/salt/minion/extrn_files/base/mirror.fuel-infra.org/mcp-repos/pike/xenial/archive-mcppike.key'] in directory '/root'
2018-10-06 14:31:26,717 [salt.state       :290 ][INFO    ][6434] Configured package repo 'deb http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike main'
2018-10-06 14:31:26,718 [salt.state       :1941][INFO    ][6434] Completed state [deb http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike main] at time 14:31:26.718142 duration_in_ms=335.88
2018-10-06 14:31:26,718 [salt.state       :1770][INFO    ][6434] Running state [/etc/apt/apt.conf.d/99proxies-salt-uca] at time 14:31:26.718520
2018-10-06 14:31:26,718 [salt.state       :1803][INFO    ][6434] Executing state file.absent for [/etc/apt/apt.conf.d/99proxies-salt-uca]
2018-10-06 14:31:26,719 [salt.state       :290 ][INFO    ][6434] File /etc/apt/apt.conf.d/99proxies-salt-uca is not present
2018-10-06 14:31:26,719 [salt.state       :1941][INFO    ][6434] Completed state [/etc/apt/apt.conf.d/99proxies-salt-uca] at time 14:31:26.719620 duration_in_ms=1.1
2018-10-06 14:31:26,719 [salt.state       :1770][INFO    ][6434] Running state [/etc/apt/preferences.d/uca] at time 14:31:26.719864
2018-10-06 14:31:26,720 [salt.state       :1803][INFO    ][6434] Executing state file.managed for [/etc/apt/preferences.d/uca]
2018-10-06 14:31:26,836 [salt.state       :290 ][INFO    ][6434] File /etc/apt/preferences.d/uca is in the correct state
2018-10-06 14:31:26,836 [salt.state       :1941][INFO    ][6434] Completed state [/etc/apt/preferences.d/uca] at time 14:31:26.836328 duration_in_ms=116.464
2018-10-06 14:31:26,840 [salt.state       :1770][INFO    ][6434] Running state [apt-key adv --keyserver keyserver.ubuntu.com --recv EC4926EA] at time 14:31:26.840389
2018-10-06 14:31:26,840 [salt.state       :1803][INFO    ][6434] Executing state cmd.run for [apt-key adv --keyserver keyserver.ubuntu.com --recv EC4926EA]
2018-10-06 14:31:26,841 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'test -e /etc/apt/sources.list.d/uca.list' in directory '/root'
2018-10-06 14:31:26,852 [salt.state       :290 ][INFO    ][6434] unless execution succeeded
2018-10-06 14:31:26,853 [salt.state       :1941][INFO    ][6434] Completed state [apt-key adv --keyserver keyserver.ubuntu.com --recv EC4926EA] at time 14:31:26.853636 duration_in_ms=13.246
2018-10-06 14:31:26,856 [salt.state       :1770][INFO    ][6434] Running state [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main] at time 14:31:26.856321
2018-10-06 14:31:26,856 [salt.state       :1803][INFO    ][6434] Executing state pkgrepo.managed for [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main]
2018-10-06 14:31:26,933 [salt.state       :290 ][INFO    ][6434] Package repo 'deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main' already configured
2018-10-06 14:31:26,934 [salt.state       :1941][INFO    ][6434] Completed state [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main] at time 14:31:26.934113 duration_in_ms=77.791
2018-10-06 14:31:26,935 [salt.state       :1770][INFO    ][6434] Running state [pkg.refresh_db] at time 14:31:26.935029
2018-10-06 14:31:26,935 [salt.state       :1803][INFO    ][6434] Executing state module.run for [pkg.refresh_db]
2018-10-06 14:31:26,935 [salt.utils.decorators:613 ][WARNING ][6434] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-10-06 14:31:26,936 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-10-06 14:31:27,275 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006143127262975
2018-10-06 14:31:27,297 [salt.minion      :1431][INFO    ][6788] Starting a new job with PID 6788
2018-10-06 14:31:27,315 [salt.minion      :1708][INFO    ][6788] Returning information for job: 20181006143127262975
2018-10-06 14:31:30,735 [salt.state       :290 ][INFO    ][6434] {'ret': {'http://apt.mirantis.com/xenial nightly InRelease': None, 'http://security.ubuntu.com/ubuntu xenial-security InRelease': None, 'http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial InRelease': None, 'http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike Release': None, 'http://archive.ubuntu.com/ubuntu xenial-backports InRelease': None, 'http://archive.ubuntu.com/ubuntu xenial-updates InRelease': None, 'http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike InRelease': False, 'http://repo.saltstack.com/apt/ubuntu/16.04/amd64/2017.7 xenial InRelease': None, 'http://archive.ubuntu.com/ubuntu xenial InRelease': None, 'http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike InRelease': None}}
2018-10-06 14:31:30,738 [salt.state       :1941][INFO    ][6434] Completed state [pkg.refresh_db] at time 14:31:30.738645 duration_in_ms=3803.615
2018-10-06 14:31:30,739 [salt.state       :1770][INFO    ][6434] Running state [UTC] at time 14:31:30.739939
2018-10-06 14:31:30,740 [salt.state       :1803][INFO    ][6434] Executing state timezone.system for [UTC]
2018-10-06 14:31:30,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command ['timedatectl'] in directory '/root'
2018-10-06 14:31:30,765 [salt.state       :290 ][INFO    ][6434] Timezone UTC already set, UTC already set to UTC
2018-10-06 14:31:30,766 [salt.state       :1941][INFO    ][6434] Completed state [UTC] at time 14:31:30.766327 duration_in_ms=26.388
2018-10-06 14:31:30,766 [salt.state       :1770][INFO    ][6434] Running state [/etc/default/grub.d] at time 14:31:30.766839
2018-10-06 14:31:30,767 [salt.state       :1803][INFO    ][6434] Executing state file.directory for [/etc/default/grub.d]
2018-10-06 14:31:30,768 [salt.state       :290 ][INFO    ][6434] Directory /etc/default/grub.d is in the correct state
Directory /etc/default/grub.d updated
2018-10-06 14:31:30,768 [salt.state       :1941][INFO    ][6434] Completed state [/etc/default/grub.d] at time 14:31:30.768612 duration_in_ms=1.774
2018-10-06 14:31:30,768 [salt.state       :1770][INFO    ][6434] Running state [update-grub] at time 14:31:30.768836
2018-10-06 14:31:30,769 [salt.state       :1803][INFO    ][6434] Executing state cmd.wait for [update-grub]
2018-10-06 14:31:30,769 [salt.state       :290 ][INFO    ][6434] No changes made for update-grub
2018-10-06 14:31:30,769 [salt.state       :1941][INFO    ][6434] Completed state [update-grub] at time 14:31:30.769896 duration_in_ms=1.06
2018-10-06 14:31:30,771 [salt.state       :1770][INFO    ][6434] Running state [/boot/grub/grub.cfg] at time 14:31:30.771663
2018-10-06 14:31:30,771 [salt.state       :1803][INFO    ][6434] Executing state file.managed for [/boot/grub/grub.cfg]
2018-10-06 14:31:30,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command ['git', '--version'] in directory '/root'
2018-10-06 14:31:31,002 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'test -f /boot/grub/grub.cfg' in directory '/root'
2018-10-06 14:31:31,012 [salt.loaded.int.states.file:2150][WARNING ][6434] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-10-06 14:31:31,013 [salt.state       :290 ][INFO    ][6434] File /boot/grub/grub.cfg exists with proper permissions. No changes made.
2018-10-06 14:31:31,014 [salt.state       :1941][INFO    ][6434] Completed state [/boot/grub/grub.cfg] at time 14:31:31.014410 duration_in_ms=242.747
2018-10-06 14:31:31,015 [salt.state       :1770][INFO    ][6434] Running state [nf_conntrack] at time 14:31:31.015050
2018-10-06 14:31:31,015 [salt.state       :1803][INFO    ][6434] Executing state kmod.present for [nf_conntrack]
2018-10-06 14:31:31,016 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'lsmod' in directory '/root'
2018-10-06 14:31:31,031 [salt.state       :290 ][INFO    ][6434] Kernel module nf_conntrack is already present
2018-10-06 14:31:31,032 [salt.state       :1941][INFO    ][6434] Completed state [nf_conntrack] at time 14:31:31.032621 duration_in_ms=17.571
2018-10-06 14:31:31,033 [salt.state       :1770][INFO    ][6434] Running state [kernel.panic] at time 14:31:31.033273
2018-10-06 14:31:31,033 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [kernel.panic]
2018-10-06 14:31:31,051 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n kernel.panic' in directory '/root'
2018-10-06 14:31:31,062 [salt.state       :290 ][INFO    ][6434] Sysctl value kernel.panic = 60 is already set
2018-10-06 14:31:31,062 [salt.state       :1941][INFO    ][6434] Completed state [kernel.panic] at time 14:31:31.062633 duration_in_ms=29.36
2018-10-06 14:31:31,063 [salt.state       :1770][INFO    ][6434] Running state [net.ipv4.tcp_keepalive_probes] at time 14:31:31.063210
2018-10-06 14:31:31,063 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [net.ipv4.tcp_keepalive_probes]
2018-10-06 14:31:31,064 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n net.ipv4.tcp_keepalive_probes' in directory '/root'
2018-10-06 14:31:31,074 [salt.state       :290 ][INFO    ][6434] Sysctl value net.ipv4.tcp_keepalive_probes = 8 is already set
2018-10-06 14:31:31,075 [salt.state       :1941][INFO    ][6434] Completed state [net.ipv4.tcp_keepalive_probes] at time 14:31:31.074924 duration_in_ms=11.713
2018-10-06 14:31:31,075 [salt.state       :1770][INFO    ][6434] Running state [fs.file-max] at time 14:31:31.075504
2018-10-06 14:31:31,076 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [fs.file-max]
2018-10-06 14:31:31,077 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n fs.file-max' in directory '/root'
2018-10-06 14:31:31,087 [salt.state       :290 ][INFO    ][6434] Sysctl value fs.file-max = 124165 is already set
2018-10-06 14:31:31,088 [salt.state       :1941][INFO    ][6434] Completed state [fs.file-max] at time 14:31:31.088354 duration_in_ms=12.85
2018-10-06 14:31:31,089 [salt.state       :1770][INFO    ][6434] Running state [net.core.somaxconn] at time 14:31:31.089525
2018-10-06 14:31:31,090 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [net.core.somaxconn]
2018-10-06 14:31:31,090 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n net.core.somaxconn' in directory '/root'
2018-10-06 14:31:31,100 [salt.state       :290 ][INFO    ][6434] Sysctl value net.core.somaxconn = 4096 is already set
2018-10-06 14:31:31,101 [salt.state       :1941][INFO    ][6434] Completed state [net.core.somaxconn] at time 14:31:31.101630 duration_in_ms=12.104
2018-10-06 14:31:31,102 [salt.state       :1770][INFO    ][6434] Running state [net.ipv4.tcp_max_syn_backlog] at time 14:31:31.102187
2018-10-06 14:31:31,102 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [net.ipv4.tcp_max_syn_backlog]
2018-10-06 14:31:31,103 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n net.ipv4.tcp_max_syn_backlog' in directory '/root'
2018-10-06 14:31:31,114 [salt.state       :290 ][INFO    ][6434] Sysctl value net.ipv4.tcp_max_syn_backlog = 8192 is already set
2018-10-06 14:31:31,114 [salt.state       :1941][INFO    ][6434] Completed state [net.ipv4.tcp_max_syn_backlog] at time 14:31:31.114593 duration_in_ms=12.406
2018-10-06 14:31:31,115 [salt.state       :1770][INFO    ][6434] Running state [net.ipv4.tcp_tw_reuse] at time 14:31:31.115179
2018-10-06 14:31:31,115 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [net.ipv4.tcp_tw_reuse]
2018-10-06 14:31:31,116 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n net.ipv4.tcp_tw_reuse' in directory '/root'
2018-10-06 14:31:31,127 [salt.state       :290 ][INFO    ][6434] Sysctl value net.ipv4.tcp_tw_reuse = 1 is already set
2018-10-06 14:31:31,128 [salt.state       :1941][INFO    ][6434] Completed state [net.ipv4.tcp_tw_reuse] at time 14:31:31.128147 duration_in_ms=12.968
2018-10-06 14:31:31,128 [salt.state       :1770][INFO    ][6434] Running state [net.ipv4.tcp_congestion_control] at time 14:31:31.128711
2018-10-06 14:31:31,129 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [net.ipv4.tcp_congestion_control]
2018-10-06 14:31:31,130 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n net.ipv4.tcp_congestion_control' in directory '/root'
2018-10-06 14:31:31,142 [salt.state       :290 ][INFO    ][6434] Sysctl value net.ipv4.tcp_congestion_control = yeah is already set
2018-10-06 14:31:31,143 [salt.state       :1941][INFO    ][6434] Completed state [net.ipv4.tcp_congestion_control] at time 14:31:31.143280 duration_in_ms=14.569
2018-10-06 14:31:31,143 [salt.state       :1770][INFO    ][6434] Running state [net.ipv4.tcp_retries2] at time 14:31:31.143909
2018-10-06 14:31:31,144 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [net.ipv4.tcp_retries2]
2018-10-06 14:31:31,146 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n net.ipv4.tcp_retries2' in directory '/root'
2018-10-06 14:31:31,157 [salt.state       :290 ][INFO    ][6434] Sysctl value net.ipv4.tcp_retries2 = 5 is already set
2018-10-06 14:31:31,158 [salt.state       :1941][INFO    ][6434] Completed state [net.ipv4.tcp_retries2] at time 14:31:31.158013 duration_in_ms=14.104
2018-10-06 14:31:31,158 [salt.state       :1770][INFO    ][6434] Running state [net.core.netdev_max_backlog] at time 14:31:31.158571
2018-10-06 14:31:31,159 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [net.core.netdev_max_backlog]
2018-10-06 14:31:31,160 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n net.core.netdev_max_backlog' in directory '/root'
2018-10-06 14:31:31,170 [salt.state       :290 ][INFO    ][6434] Sysctl value net.core.netdev_max_backlog = 261144 is already set
2018-10-06 14:31:31,170 [salt.state       :1941][INFO    ][6434] Completed state [net.core.netdev_max_backlog] at time 14:31:31.170766 duration_in_ms=12.195
2018-10-06 14:31:31,171 [salt.state       :1770][INFO    ][6434] Running state [net.ipv4.tcp_slow_start_after_idle] at time 14:31:31.171388
2018-10-06 14:31:31,171 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [net.ipv4.tcp_slow_start_after_idle]
2018-10-06 14:31:31,172 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n net.ipv4.tcp_slow_start_after_idle' in directory '/root'
2018-10-06 14:31:31,184 [salt.state       :290 ][INFO    ][6434] Sysctl value net.ipv4.tcp_slow_start_after_idle = 0 is already set
2018-10-06 14:31:31,185 [salt.state       :1941][INFO    ][6434] Completed state [net.ipv4.tcp_slow_start_after_idle] at time 14:31:31.184838 duration_in_ms=13.45
2018-10-06 14:31:31,185 [salt.state       :1770][INFO    ][6434] Running state [vm.swappiness] at time 14:31:31.185779
2018-10-06 14:31:31,186 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [vm.swappiness]
2018-10-06 14:31:31,187 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n vm.swappiness' in directory '/root'
2018-10-06 14:31:31,197 [salt.state       :290 ][INFO    ][6434] Sysctl value vm.swappiness = 10 is already set
2018-10-06 14:31:31,197 [salt.state       :1941][INFO    ][6434] Completed state [vm.swappiness] at time 14:31:31.197838 duration_in_ms=12.059
2018-10-06 14:31:31,198 [salt.state       :1770][INFO    ][6434] Running state [net.ipv4.tcp_keepalive_intvl] at time 14:31:31.198394
2018-10-06 14:31:31,198 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [net.ipv4.tcp_keepalive_intvl]
2018-10-06 14:31:31,199 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n net.ipv4.tcp_keepalive_intvl' in directory '/root'
2018-10-06 14:31:31,209 [salt.state       :290 ][INFO    ][6434] Sysctl value net.ipv4.tcp_keepalive_intvl = 3 is already set
2018-10-06 14:31:31,210 [salt.state       :1941][INFO    ][6434] Completed state [net.ipv4.tcp_keepalive_intvl] at time 14:31:31.210109 duration_in_ms=11.715
2018-10-06 14:31:31,210 [salt.state       :1770][INFO    ][6434] Running state [net.ipv4.neigh.default.gc_thresh1] at time 14:31:31.210659
2018-10-06 14:31:31,211 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [net.ipv4.neigh.default.gc_thresh1]
2018-10-06 14:31:31,212 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n net.ipv4.neigh.default.gc_thresh1' in directory '/root'
2018-10-06 14:31:31,223 [salt.state       :290 ][INFO    ][6434] Sysctl value net.ipv4.neigh.default.gc_thresh1 = 4096 is already set
2018-10-06 14:31:31,223 [salt.state       :1941][INFO    ][6434] Completed state [net.ipv4.neigh.default.gc_thresh1] at time 14:31:31.223806 duration_in_ms=13.147
2018-10-06 14:31:31,224 [salt.state       :1770][INFO    ][6434] Running state [net.ipv4.neigh.default.gc_thresh2] at time 14:31:31.224364
2018-10-06 14:31:31,224 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [net.ipv4.neigh.default.gc_thresh2]
2018-10-06 14:31:31,226 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n net.ipv4.neigh.default.gc_thresh2' in directory '/root'
2018-10-06 14:31:31,236 [salt.state       :290 ][INFO    ][6434] Sysctl value net.ipv4.neigh.default.gc_thresh2 = 8192 is already set
2018-10-06 14:31:31,236 [salt.state       :1941][INFO    ][6434] Completed state [net.ipv4.neigh.default.gc_thresh2] at time 14:31:31.236790 duration_in_ms=12.426
2018-10-06 14:31:31,237 [salt.state       :1770][INFO    ][6434] Running state [net.ipv4.neigh.default.gc_thresh3] at time 14:31:31.237771
2018-10-06 14:31:31,238 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [net.ipv4.neigh.default.gc_thresh3]
2018-10-06 14:31:31,239 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n net.ipv4.neigh.default.gc_thresh3' in directory '/root'
2018-10-06 14:31:31,249 [salt.state       :290 ][INFO    ][6434] Sysctl value net.ipv4.neigh.default.gc_thresh3 = 16384 is already set
2018-10-06 14:31:31,250 [salt.state       :1941][INFO    ][6434] Completed state [net.ipv4.neigh.default.gc_thresh3] at time 14:31:31.250252 duration_in_ms=12.481
2018-10-06 14:31:31,250 [salt.state       :1770][INFO    ][6434] Running state [net.ipv4.tcp_fin_timeout] at time 14:31:31.250806
2018-10-06 14:31:31,251 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [net.ipv4.tcp_fin_timeout]
2018-10-06 14:31:31,252 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n net.ipv4.tcp_fin_timeout' in directory '/root'
2018-10-06 14:31:31,263 [salt.state       :290 ][INFO    ][6434] Sysctl value net.ipv4.tcp_fin_timeout = 30 is already set
2018-10-06 14:31:31,263 [salt.state       :1941][INFO    ][6434] Completed state [net.ipv4.tcp_fin_timeout] at time 14:31:31.263723 duration_in_ms=12.917
2018-10-06 14:31:31,264 [salt.state       :1770][INFO    ][6434] Running state [net.ipv4.tcp_keepalive_time] at time 14:31:31.264267
2018-10-06 14:31:31,264 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [net.ipv4.tcp_keepalive_time]
2018-10-06 14:31:31,266 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n net.ipv4.tcp_keepalive_time' in directory '/root'
2018-10-06 14:31:31,276 [salt.state       :290 ][INFO    ][6434] Sysctl value net.ipv4.tcp_keepalive_time = 30 is already set
2018-10-06 14:31:31,277 [salt.state       :1941][INFO    ][6434] Completed state [net.ipv4.tcp_keepalive_time] at time 14:31:31.277678 duration_in_ms=13.411
2018-10-06 14:31:31,278 [salt.state       :1770][INFO    ][6434] Running state [net.nf_conntrack_max] at time 14:31:31.278201
2018-10-06 14:31:31,278 [salt.state       :1803][INFO    ][6434] Executing state sysctl.present for [net.nf_conntrack_max]
2018-10-06 14:31:31,279 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'sysctl -n net.nf_conntrack_max' in directory '/root'
2018-10-06 14:31:31,290 [salt.state       :290 ][INFO    ][6434] Sysctl value net.nf_conntrack_max = 1048576 is already set
2018-10-06 14:31:31,290 [salt.state       :1941][INFO    ][6434] Completed state [net.nf_conntrack_max] at time 14:31:31.290799 duration_in_ms=12.598
2018-10-06 14:31:31,291 [salt.state       :1770][INFO    ][6434] Running state [linux_sysfs_package] at time 14:31:31.291436
2018-10-06 14:31:31,291 [salt.state       :1803][INFO    ][6434] Executing state pkg.installed for [linux_sysfs_package]
2018-10-06 14:31:31,301 [salt.state       :290 ][INFO    ][6434] All specified packages are already installed
2018-10-06 14:31:31,302 [salt.state       :1941][INFO    ][6434] Completed state [linux_sysfs_package] at time 14:31:31.302290 duration_in_ms=10.854
2018-10-06 14:31:31,304 [salt.state       :1770][INFO    ][6434] Running state [/etc/sysfs.d] at time 14:31:31.304185
2018-10-06 14:31:31,304 [salt.state       :1803][INFO    ][6434] Executing state file.directory for [/etc/sysfs.d]
2018-10-06 14:31:31,305 [salt.state       :290 ][INFO    ][6434] Directory /etc/sysfs.d is in the correct state
Directory /etc/sysfs.d updated
2018-10-06 14:31:31,306 [salt.state       :1941][INFO    ][6434] Completed state [/etc/sysfs.d] at time 14:31:31.306345 duration_in_ms=2.16
2018-10-06 14:31:31,306 [salt.state       :1770][INFO    ][6434] Running state [ondemand] at time 14:31:31.306756
2018-10-06 14:31:31,307 [salt.state       :1803][INFO    ][6434] Executing state service.dead for [ondemand]
2018-10-06 14:31:31,308 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command ['systemctl', 'status', 'ondemand.service', '-n', '0'] in directory '/root'
2018-10-06 14:31:31,322 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command ['systemctl', 'is-active', 'ondemand.service'] in directory '/root'
2018-10-06 14:31:31,335 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-10-06 14:31:31,354 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'runlevel' in directory '/root'
2018-10-06 14:31:31,366 [salt.state       :290 ][INFO    ][6434] The service ondemand is already dead
2018-10-06 14:31:31,367 [salt.state       :1941][INFO    ][6434] Completed state [ondemand] at time 14:31:31.367243 duration_in_ms=60.487
2018-10-06 14:31:31,367 [salt.state       :1770][INFO    ][6434] Running state [cs_CZ.UTF-8] at time 14:31:31.367911
2018-10-06 14:31:31,368 [salt.state       :1803][INFO    ][6434] Executing state locale.present for [cs_CZ.UTF-8]
2018-10-06 14:31:31,369 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'locale -a' in directory '/root'
2018-10-06 14:31:31,380 [salt.state       :290 ][INFO    ][6434] Locale cs_CZ.UTF-8 is already present
2018-10-06 14:31:31,381 [salt.state       :1941][INFO    ][6434] Completed state [cs_CZ.UTF-8] at time 14:31:31.381522 duration_in_ms=13.611
2018-10-06 14:31:31,382 [salt.state       :1770][INFO    ][6434] Running state [en_US.UTF-8] at time 14:31:31.382077
2018-10-06 14:31:31,382 [salt.state       :1803][INFO    ][6434] Executing state locale.present for [en_US.UTF-8]
2018-10-06 14:31:31,383 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'locale -a' in directory '/root'
2018-10-06 14:31:31,393 [salt.state       :290 ][INFO    ][6434] Locale en_US.UTF-8 is already present
2018-10-06 14:31:31,394 [salt.state       :1941][INFO    ][6434] Completed state [en_US.UTF-8] at time 14:31:31.394449 duration_in_ms=12.372
2018-10-06 14:31:31,396 [salt.state       :1770][INFO    ][6434] Running state [en_US.UTF-8] at time 14:31:31.396574
2018-10-06 14:31:31,397 [salt.state       :1803][INFO    ][6434] Executing state locale.system for [en_US.UTF-8]
2018-10-06 14:31:31,398 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'localectl' in directory '/root'
2018-10-06 14:31:31,422 [salt.state       :290 ][INFO    ][6434] System locale en_US.UTF-8 already set
2018-10-06 14:31:31,423 [salt.state       :1941][INFO    ][6434] Completed state [en_US.UTF-8] at time 14:31:31.423069 duration_in_ms=26.496
2018-10-06 14:31:31,424 [salt.state       :1770][INFO    ][6434] Running state [root] at time 14:31:31.424046
2018-10-06 14:31:31,424 [salt.state       :1803][INFO    ][6434] Executing state group.present for [root]
2018-10-06 14:31:31,425 [salt.state       :290 ][INFO    ][6434] Group root is present and up to date
2018-10-06 14:31:31,425 [salt.state       :1941][INFO    ][6434] Completed state [root] at time 14:31:31.425462 duration_in_ms=1.416
2018-10-06 14:31:31,426 [salt.state       :1770][INFO    ][6434] Running state [root] at time 14:31:31.426944
2018-10-06 14:31:31,427 [salt.state       :1803][INFO    ][6434] Executing state user.present for [root]
2018-10-06 14:31:31,428 [salt.state       :290 ][INFO    ][6434] User root is present and up to date
2018-10-06 14:31:31,428 [salt.state       :1941][INFO    ][6434] Completed state [root] at time 14:31:31.428687 duration_in_ms=1.742
2018-10-06 14:31:31,430 [salt.state       :1770][INFO    ][6434] Running state [/root] at time 14:31:31.430098
2018-10-06 14:31:31,430 [salt.state       :1803][INFO    ][6434] Executing state file.directory for [/root]
2018-10-06 14:31:31,431 [salt.state       :290 ][INFO    ][6434] Directory /root is in the correct state
Directory /root updated
2018-10-06 14:31:31,431 [salt.state       :1941][INFO    ][6434] Completed state [/root] at time 14:31:31.431371 duration_in_ms=1.273
2018-10-06 14:31:31,431 [salt.state       :1770][INFO    ][6434] Running state [/etc/sudoers.d/90-salt-user-root] at time 14:31:31.431599
2018-10-06 14:31:31,431 [salt.state       :1803][INFO    ][6434] Executing state file.absent for [/etc/sudoers.d/90-salt-user-root]
2018-10-06 14:31:31,432 [salt.state       :290 ][INFO    ][6434] File /etc/sudoers.d/90-salt-user-root is not present
2018-10-06 14:31:31,432 [salt.state       :1941][INFO    ][6434] Completed state [/etc/sudoers.d/90-salt-user-root] at time 14:31:31.432299 duration_in_ms=0.7
2018-10-06 14:31:31,432 [salt.state       :1770][INFO    ][6434] Running state [ubuntu] at time 14:31:31.432511
2018-10-06 14:31:31,432 [salt.state       :1803][INFO    ][6434] Executing state group.present for [ubuntu]
2018-10-06 14:31:31,433 [salt.state       :290 ][INFO    ][6434] Group ubuntu is present and up to date
2018-10-06 14:31:31,434 [salt.state       :1941][INFO    ][6434] Completed state [ubuntu] at time 14:31:31.434101 duration_in_ms=1.59
2018-10-06 14:31:31,435 [salt.state       :1770][INFO    ][6434] Running state [ubuntu] at time 14:31:31.435008
2018-10-06 14:31:31,435 [salt.state       :1803][INFO    ][6434] Executing state user.present for [ubuntu]
2018-10-06 14:31:31,436 [salt.state       :290 ][INFO    ][6434] User ubuntu is present and up to date
2018-10-06 14:31:31,436 [salt.state       :1941][INFO    ][6434] Completed state [ubuntu] at time 14:31:31.436522 duration_in_ms=1.514
2018-10-06 14:31:31,437 [salt.state       :1770][INFO    ][6434] Running state [/home/ubuntu] at time 14:31:31.437635
2018-10-06 14:31:31,437 [salt.state       :1803][INFO    ][6434] Executing state file.directory for [/home/ubuntu]
2018-10-06 14:31:31,438 [salt.state       :290 ][INFO    ][6434] Directory /home/ubuntu is in the correct state
Directory /home/ubuntu updated
2018-10-06 14:31:31,438 [salt.state       :1941][INFO    ][6434] Completed state [/home/ubuntu] at time 14:31:31.438831 duration_in_ms=1.195
2018-10-06 14:31:31,439 [salt.state       :1770][INFO    ][6434] Running state [/etc/sudoers.d/90-salt-user-ubuntu] at time 14:31:31.439888
2018-10-06 14:31:31,440 [salt.state       :1803][INFO    ][6434] Executing state file.managed for [/etc/sudoers.d/90-salt-user-ubuntu]
2018-10-06 14:31:31,465 [salt.state       :290 ][INFO    ][6434] File /etc/sudoers.d/90-salt-user-ubuntu is in the correct state
2018-10-06 14:31:31,465 [salt.state       :1941][INFO    ][6434] Completed state [/etc/sudoers.d/90-salt-user-ubuntu] at time 14:31:31.465512 duration_in_ms=25.624
2018-10-06 14:31:31,465 [salt.state       :1770][INFO    ][6434] Running state [/etc/security/limits.d/90-salt-default.conf] at time 14:31:31.465728
2018-10-06 14:31:31,465 [salt.state       :1803][INFO    ][6434] Executing state file.managed for [/etc/security/limits.d/90-salt-default.conf]
2018-10-06 14:31:31,579 [salt.state       :290 ][INFO    ][6434] File /etc/security/limits.d/90-salt-default.conf is in the correct state
2018-10-06 14:31:31,579 [salt.state       :1941][INFO    ][6434] Completed state [/etc/security/limits.d/90-salt-default.conf] at time 14:31:31.579569 duration_in_ms=113.841
2018-10-06 14:31:31,579 [salt.state       :1770][INFO    ][6434] Running state [/etc/systemd/system.conf.d/90-salt.conf] at time 14:31:31.579820
2018-10-06 14:31:31,580 [salt.state       :1803][INFO    ][6434] Executing state file.managed for [/etc/systemd/system.conf.d/90-salt.conf]
2018-10-06 14:31:31,691 [salt.state       :290 ][INFO    ][6434] File /etc/systemd/system.conf.d/90-salt.conf is in the correct state
2018-10-06 14:31:31,692 [salt.state       :1941][INFO    ][6434] Completed state [/etc/systemd/system.conf.d/90-salt.conf] at time 14:31:31.692241 duration_in_ms=112.42
2018-10-06 14:31:31,693 [salt.state       :1770][INFO    ][6434] Running state [service.systemctl_reload] at time 14:31:31.693378
2018-10-06 14:31:31,693 [salt.state       :1803][INFO    ][6434] Executing state module.wait for [service.systemctl_reload]
2018-10-06 14:31:31,693 [salt.state       :290 ][INFO    ][6434] No changes made for service.systemctl_reload
2018-10-06 14:31:31,694 [salt.state       :1941][INFO    ][6434] Completed state [service.systemctl_reload] at time 14:31:31.694101 duration_in_ms=0.723
2018-10-06 14:31:31,694 [salt.state       :1770][INFO    ][6434] Running state [/etc/issue] at time 14:31:31.694322
2018-10-06 14:31:31,694 [salt.state       :1803][INFO    ][6434] Executing state file.managed for [/etc/issue]
2018-10-06 14:31:31,696 [salt.state       :290 ][INFO    ][6434] File /etc/issue is in the correct state
2018-10-06 14:31:31,696 [salt.state       :1941][INFO    ][6434] Completed state [/etc/issue] at time 14:31:31.696212 duration_in_ms=1.89
2018-10-06 14:31:31,696 [salt.state       :1770][INFO    ][6434] Running state [/etc/hostname] at time 14:31:31.696425
2018-10-06 14:31:31,696 [salt.state       :1803][INFO    ][6434] Executing state file.managed for [/etc/hostname]
2018-10-06 14:31:31,718 [salt.state       :290 ][INFO    ][6434] File /etc/hostname is in the correct state
2018-10-06 14:31:31,718 [salt.state       :1941][INFO    ][6434] Completed state [/etc/hostname] at time 14:31:31.718289 duration_in_ms=21.864
2018-10-06 14:31:31,719 [salt.state       :1770][INFO    ][6434] Running state [hostname msg02] at time 14:31:31.719316
2018-10-06 14:31:31,719 [salt.state       :1803][INFO    ][6434] Executing state cmd.run for [hostname msg02]
2018-10-06 14:31:31,720 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'test "$(hostname)" = "msg02"' in directory '/root'
2018-10-06 14:31:31,734 [salt.state       :290 ][INFO    ][6434] unless execution succeeded
2018-10-06 14:31:31,734 [salt.state       :1941][INFO    ][6434] Completed state [hostname msg02] at time 14:31:31.734466 duration_in_ms=15.163
2018-10-06 14:31:31,735 [salt.state       :1770][INFO    ][6434] Running state [mdb02] at time 14:31:31.735080
2018-10-06 14:31:31,735 [salt.state       :1803][INFO    ][6434] Executing state host.present for [mdb02]
2018-10-06 14:31:31,736 [salt.state       :290 ][INFO    ][6434] Host mdb02 (192.168.10.33) already present
2018-10-06 14:31:31,736 [salt.state       :1941][INFO    ][6434] Completed state [mdb02] at time 14:31:31.736611 duration_in_ms=1.531
2018-10-06 14:31:31,737 [salt.state       :1770][INFO    ][6434] Running state [mdb02.mcp-pike-odl-ha.local] at time 14:31:31.737241
2018-10-06 14:31:31,737 [salt.state       :1803][INFO    ][6434] Executing state host.present for [mdb02.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,738 [salt.state       :290 ][INFO    ][6434] Host mdb02.mcp-pike-odl-ha.local (192.168.10.33) already present
2018-10-06 14:31:31,738 [salt.state       :1941][INFO    ][6434] Completed state [mdb02.mcp-pike-odl-ha.local] at time 14:31:31.738243 duration_in_ms=1.002
2018-10-06 14:31:31,738 [salt.state       :1770][INFO    ][6434] Running state [mdb03] at time 14:31:31.738537
2018-10-06 14:31:31,738 [salt.state       :1803][INFO    ][6434] Executing state host.present for [mdb03]
2018-10-06 14:31:31,739 [salt.state       :290 ][INFO    ][6434] Host mdb03 (192.168.10.34) already present
2018-10-06 14:31:31,739 [salt.state       :1941][INFO    ][6434] Completed state [mdb03] at time 14:31:31.739529 duration_in_ms=0.992
2018-10-06 14:31:31,739 [salt.state       :1770][INFO    ][6434] Running state [mdb03.mcp-pike-odl-ha.local] at time 14:31:31.739831
2018-10-06 14:31:31,740 [salt.state       :1803][INFO    ][6434] Executing state host.present for [mdb03.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,740 [salt.state       :290 ][INFO    ][6434] Host mdb03.mcp-pike-odl-ha.local (192.168.10.34) already present
2018-10-06 14:31:31,740 [salt.state       :1941][INFO    ][6434] Completed state [mdb03.mcp-pike-odl-ha.local] at time 14:31:31.740721 duration_in_ms=0.891
2018-10-06 14:31:31,741 [salt.state       :1770][INFO    ][6434] Running state [mdb01] at time 14:31:31.741015
2018-10-06 14:31:31,741 [salt.state       :1803][INFO    ][6434] Executing state host.present for [mdb01]
2018-10-06 14:31:31,741 [salt.state       :290 ][INFO    ][6434] Host mdb01 (192.168.10.32) already present
2018-10-06 14:31:31,741 [salt.state       :1941][INFO    ][6434] Completed state [mdb01] at time 14:31:31.741922 duration_in_ms=0.907
2018-10-06 14:31:31,742 [salt.state       :1770][INFO    ][6434] Running state [mdb01.mcp-pike-odl-ha.local] at time 14:31:31.742209
2018-10-06 14:31:31,742 [salt.state       :1803][INFO    ][6434] Executing state host.present for [mdb01.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,742 [salt.state       :290 ][INFO    ][6434] Host mdb01.mcp-pike-odl-ha.local (192.168.10.32) already present
2018-10-06 14:31:31,743 [salt.state       :1941][INFO    ][6434] Completed state [mdb01.mcp-pike-odl-ha.local] at time 14:31:31.743092 duration_in_ms=0.883
2018-10-06 14:31:31,743 [salt.state       :1770][INFO    ][6434] Running state [mdb] at time 14:31:31.743408
2018-10-06 14:31:31,743 [salt.state       :1803][INFO    ][6434] Executing state host.present for [mdb]
2018-10-06 14:31:31,744 [salt.state       :290 ][INFO    ][6434] Host mdb (192.168.10.31) already present
2018-10-06 14:31:31,744 [salt.state       :1941][INFO    ][6434] Completed state [mdb] at time 14:31:31.744379 duration_in_ms=0.971
2018-10-06 14:31:31,744 [salt.state       :1770][INFO    ][6434] Running state [mdb.mcp-pike-odl-ha.local] at time 14:31:31.744657
2018-10-06 14:31:31,745 [salt.state       :1803][INFO    ][6434] Executing state host.present for [mdb.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,746 [salt.state       :290 ][INFO    ][6434] Host mdb.mcp-pike-odl-ha.local (192.168.10.31) already present
2018-10-06 14:31:31,746 [salt.state       :1941][INFO    ][6434] Completed state [mdb.mcp-pike-odl-ha.local] at time 14:31:31.746196 duration_in_ms=1.539
2018-10-06 14:31:31,746 [salt.state       :1770][INFO    ][6434] Running state [cfg01] at time 14:31:31.746487
2018-10-06 14:31:31,746 [salt.state       :1803][INFO    ][6434] Executing state host.present for [cfg01]
2018-10-06 14:31:31,747 [salt.state       :290 ][INFO    ][6434] Host cfg01 (192.168.10.11) already present
2018-10-06 14:31:31,747 [salt.state       :1941][INFO    ][6434] Completed state [cfg01] at time 14:31:31.747447 duration_in_ms=0.961
2018-10-06 14:31:31,747 [salt.state       :1770][INFO    ][6434] Running state [cfg01.mcp-pike-odl-ha.local] at time 14:31:31.747748
2018-10-06 14:31:31,747 [salt.state       :1803][INFO    ][6434] Executing state host.present for [cfg01.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,749 [salt.state       :290 ][INFO    ][6434] Host cfg01.mcp-pike-odl-ha.local (192.168.10.11) already present
2018-10-06 14:31:31,749 [salt.state       :1941][INFO    ][6434] Completed state [cfg01.mcp-pike-odl-ha.local] at time 14:31:31.749956 duration_in_ms=2.208
2018-10-06 14:31:31,750 [salt.state       :1770][INFO    ][6434] Running state [prx01] at time 14:31:31.750246
2018-10-06 14:31:31,750 [salt.state       :1803][INFO    ][6434] Executing state host.present for [prx01]
2018-10-06 14:31:31,750 [salt.state       :290 ][INFO    ][6434] Host prx01 (192.168.10.14) already present
2018-10-06 14:31:31,751 [salt.state       :1941][INFO    ][6434] Completed state [prx01] at time 14:31:31.751136 duration_in_ms=0.89
2018-10-06 14:31:31,751 [salt.state       :1770][INFO    ][6434] Running state [prx01.mcp-pike-odl-ha.local] at time 14:31:31.751479
2018-10-06 14:31:31,751 [salt.state       :1803][INFO    ][6434] Executing state host.present for [prx01.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,752 [salt.state       :290 ][INFO    ][6434] Host prx01.mcp-pike-odl-ha.local (192.168.10.14) already present
2018-10-06 14:31:31,752 [salt.state       :1941][INFO    ][6434] Completed state [prx01.mcp-pike-odl-ha.local] at time 14:31:31.752388 duration_in_ms=0.909
2018-10-06 14:31:31,752 [salt.state       :1770][INFO    ][6434] Running state [kvm01] at time 14:31:31.752667
2018-10-06 14:31:31,752 [salt.state       :1803][INFO    ][6434] Executing state host.present for [kvm01]
2018-10-06 14:31:31,753 [salt.state       :290 ][INFO    ][6434] Host kvm01 (192.168.10.20) already present
2018-10-06 14:31:31,753 [salt.state       :1941][INFO    ][6434] Completed state [kvm01] at time 14:31:31.753546 duration_in_ms=0.878
2018-10-06 14:31:31,753 [salt.state       :1770][INFO    ][6434] Running state [kvm01.mcp-pike-odl-ha.local] at time 14:31:31.753835
2018-10-06 14:31:31,754 [salt.state       :1803][INFO    ][6434] Executing state host.present for [kvm01.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,754 [salt.state       :290 ][INFO    ][6434] Host kvm01.mcp-pike-odl-ha.local (192.168.10.20) already present
2018-10-06 14:31:31,754 [salt.state       :1941][INFO    ][6434] Completed state [kvm01.mcp-pike-odl-ha.local] at time 14:31:31.754674 duration_in_ms=0.839
2018-10-06 14:31:31,754 [salt.state       :1770][INFO    ][6434] Running state [kvm03] at time 14:31:31.754958
2018-10-06 14:31:31,755 [salt.state       :1803][INFO    ][6434] Executing state host.present for [kvm03]
2018-10-06 14:31:31,755 [salt.state       :290 ][INFO    ][6434] Host kvm03 (192.168.10.22) already present
2018-10-06 14:31:31,755 [salt.state       :1941][INFO    ][6434] Completed state [kvm03] at time 14:31:31.755889 duration_in_ms=0.932
2018-10-06 14:31:31,756 [salt.state       :1770][INFO    ][6434] Running state [kvm03.mcp-pike-odl-ha.local] at time 14:31:31.756177
2018-10-06 14:31:31,756 [salt.state       :1803][INFO    ][6434] Executing state host.present for [kvm03.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,756 [salt.state       :290 ][INFO    ][6434] Host kvm03.mcp-pike-odl-ha.local (192.168.10.22) already present
2018-10-06 14:31:31,757 [salt.state       :1941][INFO    ][6434] Completed state [kvm03.mcp-pike-odl-ha.local] at time 14:31:31.757066 duration_in_ms=0.888
2018-10-06 14:31:31,757 [salt.state       :1770][INFO    ][6434] Running state [kvm02] at time 14:31:31.757354
2018-10-06 14:31:31,757 [salt.state       :1803][INFO    ][6434] Executing state host.present for [kvm02]
2018-10-06 14:31:31,758 [salt.state       :290 ][INFO    ][6434] Host kvm02 (192.168.10.21) already present
2018-10-06 14:31:31,758 [salt.state       :1941][INFO    ][6434] Completed state [kvm02] at time 14:31:31.758202 duration_in_ms=0.848
2018-10-06 14:31:31,758 [salt.state       :1770][INFO    ][6434] Running state [kvm02.mcp-pike-odl-ha.local] at time 14:31:31.758491
2018-10-06 14:31:31,758 [salt.state       :1803][INFO    ][6434] Executing state host.present for [kvm02.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,759 [salt.state       :290 ][INFO    ][6434] Host kvm02.mcp-pike-odl-ha.local (192.168.10.21) already present
2018-10-06 14:31:31,759 [salt.state       :1941][INFO    ][6434] Completed state [kvm02.mcp-pike-odl-ha.local] at time 14:31:31.759377 duration_in_ms=0.887
2018-10-06 14:31:31,759 [salt.state       :1770][INFO    ][6434] Running state [dbs] at time 14:31:31.759667
2018-10-06 14:31:31,759 [salt.state       :1803][INFO    ][6434] Executing state host.present for [dbs]
2018-10-06 14:31:31,760 [salt.state       :290 ][INFO    ][6434] Host dbs (192.168.10.23) already present
2018-10-06 14:31:31,760 [salt.state       :1941][INFO    ][6434] Completed state [dbs] at time 14:31:31.760537 duration_in_ms=0.87
2018-10-06 14:31:31,760 [salt.state       :1770][INFO    ][6434] Running state [dbs.mcp-pike-odl-ha.local] at time 14:31:31.760825
2018-10-06 14:31:31,761 [salt.state       :1803][INFO    ][6434] Executing state host.present for [dbs.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,761 [salt.state       :290 ][INFO    ][6434] Host dbs.mcp-pike-odl-ha.local (192.168.10.23) already present
2018-10-06 14:31:31,761 [salt.state       :1941][INFO    ][6434] Completed state [dbs.mcp-pike-odl-ha.local] at time 14:31:31.761678 duration_in_ms=0.854
2018-10-06 14:31:31,762 [salt.state       :1770][INFO    ][6434] Running state [prx] at time 14:31:31.761978
2018-10-06 14:31:31,762 [salt.state       :1803][INFO    ][6434] Executing state host.present for [prx]
2018-10-06 14:31:31,762 [salt.state       :290 ][INFO    ][6434] Host prx (192.168.10.13) already present
2018-10-06 14:31:31,762 [salt.state       :1941][INFO    ][6434] Completed state [prx] at time 14:31:31.762850 duration_in_ms=0.872
2018-10-06 14:31:31,763 [salt.state       :1770][INFO    ][6434] Running state [prx.mcp-pike-odl-ha.local] at time 14:31:31.763144
2018-10-06 14:31:31,763 [salt.state       :1803][INFO    ][6434] Executing state host.present for [prx.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,763 [salt.state       :290 ][INFO    ][6434] Host prx.mcp-pike-odl-ha.local (192.168.10.13) already present
2018-10-06 14:31:31,764 [salt.state       :1941][INFO    ][6434] Completed state [prx.mcp-pike-odl-ha.local] at time 14:31:31.764077 duration_in_ms=0.933
2018-10-06 14:31:31,764 [salt.state       :1770][INFO    ][6434] Running state [prx02] at time 14:31:31.764381
2018-10-06 14:31:31,764 [salt.state       :1803][INFO    ][6434] Executing state host.present for [prx02]
2018-10-06 14:31:31,765 [salt.state       :290 ][INFO    ][6434] Host prx02 (192.168.10.15) already present
2018-10-06 14:31:31,765 [salt.state       :1941][INFO    ][6434] Completed state [prx02] at time 14:31:31.765605 duration_in_ms=1.224
2018-10-06 14:31:31,765 [salt.state       :1770][INFO    ][6434] Running state [prx02.mcp-pike-odl-ha.local] at time 14:31:31.765892
2018-10-06 14:31:31,766 [salt.state       :1803][INFO    ][6434] Executing state host.present for [prx02.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,766 [salt.state       :290 ][INFO    ][6434] Host prx02.mcp-pike-odl-ha.local (192.168.10.15) already present
2018-10-06 14:31:31,766 [salt.state       :1941][INFO    ][6434] Completed state [prx02.mcp-pike-odl-ha.local] at time 14:31:31.766741 duration_in_ms=0.85
2018-10-06 14:31:31,767 [salt.state       :1770][INFO    ][6434] Running state [msg02] at time 14:31:31.767023
2018-10-06 14:31:31,767 [salt.state       :1803][INFO    ][6434] Executing state host.present for [msg02]
2018-10-06 14:31:31,767 [salt.state       :290 ][INFO    ][6434] Host msg02 (192.168.10.29) already present
2018-10-06 14:31:31,767 [salt.state       :1941][INFO    ][6434] Completed state [msg02] at time 14:31:31.767910 duration_in_ms=0.887
2018-10-06 14:31:31,768 [salt.state       :1770][INFO    ][6434] Running state [msg02.mcp-pike-odl-ha.local] at time 14:31:31.768199
2018-10-06 14:31:31,768 [salt.state       :1803][INFO    ][6434] Executing state host.present for [msg02.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,768 [salt.state       :290 ][INFO    ][6434] Host msg02.mcp-pike-odl-ha.local (192.168.10.29) already present
2018-10-06 14:31:31,769 [salt.state       :1941][INFO    ][6434] Completed state [msg02.mcp-pike-odl-ha.local] at time 14:31:31.769394 duration_in_ms=1.195
2018-10-06 14:31:31,771 [salt.state       :1770][INFO    ][6434] Running state [file.replace] at time 14:31:31.771011
2018-10-06 14:31:31,771 [salt.state       :1803][INFO    ][6434] Executing state module.run for [file.replace]
2018-10-06 14:31:31,776 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command 'grep -q "msg02 msg02.mcp-pike-odl-ha.local" /etc/hosts' in directory '/root'
2018-10-06 14:31:31,789 [salt.utils.decorators:613 ][WARNING ][6434] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-10-06 14:31:31,793 [salt.state       :290 ][INFO    ][6434] {'ret': '--- \n+++ \n@@ -18,7 +18,7 @@\n 192.168.10.23\t\tdbs dbs.mcp-pike-odl-ha.local\n 192.168.10.13\t\tprx prx.mcp-pike-odl-ha.local\n 192.168.10.15\t\tprx02 prx02.mcp-pike-odl-ha.local\n-192.168.10.29\t\tmsg02 msg02.mcp-pike-odl-ha.local\n+192.168.10.29\t\tmsg02.mcp-pike-odl-ha.local msg02\n 192.168.10.30\t\tmsg03 msg03.mcp-pike-odl-ha.local\n 192.168.10.28\t\tmsg01 msg01.mcp-pike-odl-ha.local\n 192.168.10.27\t\tmsg msg.mcp-pike-odl-ha.local\n'}
2018-10-06 14:31:31,793 [salt.state       :1941][INFO    ][6434] Completed state [file.replace] at time 14:31:31.793807 duration_in_ms=22.796
2018-10-06 14:31:31,794 [salt.state       :1770][INFO    ][6434] Running state [msg03] at time 14:31:31.794331
2018-10-06 14:31:31,794 [salt.state       :1803][INFO    ][6434] Executing state host.present for [msg03]
2018-10-06 14:31:31,795 [salt.state       :290 ][INFO    ][6434] Host msg03 (192.168.10.30) already present
2018-10-06 14:31:31,795 [salt.state       :1941][INFO    ][6434] Completed state [msg03] at time 14:31:31.795446 duration_in_ms=1.116
2018-10-06 14:31:31,795 [salt.state       :1770][INFO    ][6434] Running state [msg03.mcp-pike-odl-ha.local] at time 14:31:31.795775
2018-10-06 14:31:31,796 [salt.state       :1803][INFO    ][6434] Executing state host.present for [msg03.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,796 [salt.state       :290 ][INFO    ][6434] Host msg03.mcp-pike-odl-ha.local (192.168.10.30) already present
2018-10-06 14:31:31,796 [salt.state       :1941][INFO    ][6434] Completed state [msg03.mcp-pike-odl-ha.local] at time 14:31:31.796701 duration_in_ms=0.926
2018-10-06 14:31:31,797 [salt.state       :1770][INFO    ][6434] Running state [msg01] at time 14:31:31.797334
2018-10-06 14:31:31,797 [salt.state       :1803][INFO    ][6434] Executing state host.present for [msg01]
2018-10-06 14:31:31,798 [salt.state       :290 ][INFO    ][6434] Host msg01 (192.168.10.28) already present
2018-10-06 14:31:31,798 [salt.state       :1941][INFO    ][6434] Completed state [msg01] at time 14:31:31.798261 duration_in_ms=0.927
2018-10-06 14:31:31,798 [salt.state       :1770][INFO    ][6434] Running state [msg01.mcp-pike-odl-ha.local] at time 14:31:31.798543
2018-10-06 14:31:31,798 [salt.state       :1803][INFO    ][6434] Executing state host.present for [msg01.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,799 [salt.state       :290 ][INFO    ][6434] Host msg01.mcp-pike-odl-ha.local (192.168.10.28) already present
2018-10-06 14:31:31,799 [salt.state       :1941][INFO    ][6434] Completed state [msg01.mcp-pike-odl-ha.local] at time 14:31:31.799724 duration_in_ms=1.181
2018-10-06 14:31:31,800 [salt.state       :1770][INFO    ][6434] Running state [msg] at time 14:31:31.800030
2018-10-06 14:31:31,800 [salt.state       :1803][INFO    ][6434] Executing state host.present for [msg]
2018-10-06 14:31:31,800 [salt.state       :290 ][INFO    ][6434] Host msg (192.168.10.27) already present
2018-10-06 14:31:31,800 [salt.state       :1941][INFO    ][6434] Completed state [msg] at time 14:31:31.800911 duration_in_ms=0.881
2018-10-06 14:31:31,801 [salt.state       :1770][INFO    ][6434] Running state [msg.mcp-pike-odl-ha.local] at time 14:31:31.801243
2018-10-06 14:31:31,801 [salt.state       :1803][INFO    ][6434] Executing state host.present for [msg.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,801 [salt.state       :290 ][INFO    ][6434] Host msg.mcp-pike-odl-ha.local (192.168.10.27) already present
2018-10-06 14:31:31,802 [salt.state       :1941][INFO    ][6434] Completed state [msg.mcp-pike-odl-ha.local] at time 14:31:31.802089 duration_in_ms=0.845
2018-10-06 14:31:31,802 [salt.state       :1770][INFO    ][6434] Running state [cfg01] at time 14:31:31.802386
2018-10-06 14:31:31,802 [salt.state       :1803][INFO    ][6434] Executing state host.present for [cfg01]
2018-10-06 14:31:31,803 [salt.state       :290 ][INFO    ][6434] Host cfg01 (192.168.10.11) already present
2018-10-06 14:31:31,803 [salt.state       :1941][INFO    ][6434] Completed state [cfg01] at time 14:31:31.803264 duration_in_ms=0.878
2018-10-06 14:31:31,803 [salt.state       :1770][INFO    ][6434] Running state [cfg01.mcp-pike-odl-ha.local] at time 14:31:31.803607
2018-10-06 14:31:31,803 [salt.state       :1803][INFO    ][6434] Executing state host.present for [cfg01.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,804 [salt.state       :290 ][INFO    ][6434] Host cfg01.mcp-pike-odl-ha.local (192.168.10.11) already present
2018-10-06 14:31:31,804 [salt.state       :1941][INFO    ][6434] Completed state [cfg01.mcp-pike-odl-ha.local] at time 14:31:31.804486 duration_in_ms=0.88
2018-10-06 14:31:31,804 [salt.state       :1770][INFO    ][6434] Running state [cmp002] at time 14:31:31.804771
2018-10-06 14:31:31,805 [salt.state       :1803][INFO    ][6434] Executing state host.present for [cmp002]
2018-10-06 14:31:31,805 [salt.state       :290 ][INFO    ][6434] Host cmp002 (192.168.10.53) already present
2018-10-06 14:31:31,806 [salt.state       :1941][INFO    ][6434] Completed state [cmp002] at time 14:31:31.805999 duration_in_ms=1.228
2018-10-06 14:31:31,806 [salt.state       :1770][INFO    ][6434] Running state [cmp002.mcp-pike-odl-ha.local] at time 14:31:31.806299
2018-10-06 14:31:31,806 [salt.state       :1803][INFO    ][6434] Executing state host.present for [cmp002.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,807 [salt.state       :290 ][INFO    ][6434] Host cmp002.mcp-pike-odl-ha.local (192.168.10.53) already present
2018-10-06 14:31:31,807 [salt.state       :1941][INFO    ][6434] Completed state [cmp002.mcp-pike-odl-ha.local] at time 14:31:31.807203 duration_in_ms=0.903
2018-10-06 14:31:31,807 [salt.state       :1770][INFO    ][6434] Running state [cmp001] at time 14:31:31.807549
2018-10-06 14:31:31,807 [salt.state       :1803][INFO    ][6434] Executing state host.present for [cmp001]
2018-10-06 14:31:31,808 [salt.state       :290 ][INFO    ][6434] Host cmp001 (192.168.10.52) already present
2018-10-06 14:31:31,808 [salt.state       :1941][INFO    ][6434] Completed state [cmp001] at time 14:31:31.808443 duration_in_ms=0.894
2018-10-06 14:31:31,808 [salt.state       :1770][INFO    ][6434] Running state [cmp001.mcp-pike-odl-ha.local] at time 14:31:31.808727
2018-10-06 14:31:31,809 [salt.state       :1803][INFO    ][6434] Executing state host.present for [cmp001.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,809 [salt.state       :290 ][INFO    ][6434] Host cmp001.mcp-pike-odl-ha.local (192.168.10.52) already present
2018-10-06 14:31:31,809 [salt.state       :1941][INFO    ][6434] Completed state [cmp001.mcp-pike-odl-ha.local] at time 14:31:31.809943 duration_in_ms=1.217
2018-10-06 14:31:31,810 [salt.state       :1770][INFO    ][6434] Running state [dbs01] at time 14:31:31.810243
2018-10-06 14:31:31,810 [salt.state       :1803][INFO    ][6434] Executing state host.present for [dbs01]
2018-10-06 14:31:31,810 [salt.state       :290 ][INFO    ][6434] Host dbs01 (192.168.10.24) already present
2018-10-06 14:31:31,811 [salt.state       :1941][INFO    ][6434] Completed state [dbs01] at time 14:31:31.811136 duration_in_ms=0.894
2018-10-06 14:31:31,811 [salt.state       :1770][INFO    ][6434] Running state [dbs01.mcp-pike-odl-ha.local] at time 14:31:31.811494
2018-10-06 14:31:31,811 [salt.state       :1803][INFO    ][6434] Executing state host.present for [dbs01.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,812 [salt.state       :290 ][INFO    ][6434] Host dbs01.mcp-pike-odl-ha.local (192.168.10.24) already present
2018-10-06 14:31:31,812 [salt.state       :1941][INFO    ][6434] Completed state [dbs01.mcp-pike-odl-ha.local] at time 14:31:31.812377 duration_in_ms=0.883
2018-10-06 14:31:31,812 [salt.state       :1770][INFO    ][6434] Running state [dbs02] at time 14:31:31.812680
2018-10-06 14:31:31,812 [salt.state       :1803][INFO    ][6434] Executing state host.present for [dbs02]
2018-10-06 14:31:31,813 [salt.state       :290 ][INFO    ][6434] Host dbs02 (192.168.10.25) already present
2018-10-06 14:31:31,813 [salt.state       :1941][INFO    ][6434] Completed state [dbs02] at time 14:31:31.813593 duration_in_ms=0.913
2018-10-06 14:31:31,813 [salt.state       :1770][INFO    ][6434] Running state [dbs02.mcp-pike-odl-ha.local] at time 14:31:31.813892
2018-10-06 14:31:31,814 [salt.state       :1803][INFO    ][6434] Executing state host.present for [dbs02.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,814 [salt.state       :290 ][INFO    ][6434] Host dbs02.mcp-pike-odl-ha.local (192.168.10.25) already present
2018-10-06 14:31:31,814 [salt.state       :1941][INFO    ][6434] Completed state [dbs02.mcp-pike-odl-ha.local] at time 14:31:31.814759 duration_in_ms=0.867
2018-10-06 14:31:31,815 [salt.state       :1770][INFO    ][6434] Running state [dbs03] at time 14:31:31.815057
2018-10-06 14:31:31,815 [salt.state       :1803][INFO    ][6434] Executing state host.present for [dbs03]
2018-10-06 14:31:31,815 [salt.state       :290 ][INFO    ][6434] Host dbs03 (192.168.10.26) already present
2018-10-06 14:31:31,816 [salt.state       :1941][INFO    ][6434] Completed state [dbs03] at time 14:31:31.815973 duration_in_ms=0.917
2018-10-06 14:31:31,816 [salt.state       :1770][INFO    ][6434] Running state [dbs03.mcp-pike-odl-ha.local] at time 14:31:31.816281
2018-10-06 14:31:31,816 [salt.state       :1803][INFO    ][6434] Executing state host.present for [dbs03.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,817 [salt.state       :290 ][INFO    ][6434] Host dbs03.mcp-pike-odl-ha.local (192.168.10.26) already present
2018-10-06 14:31:31,817 [salt.state       :1941][INFO    ][6434] Completed state [dbs03.mcp-pike-odl-ha.local] at time 14:31:31.817182 duration_in_ms=0.9
2018-10-06 14:31:31,817 [salt.state       :1770][INFO    ][6434] Running state [odl01] at time 14:31:31.817501
2018-10-06 14:31:31,817 [salt.state       :1803][INFO    ][6434] Executing state host.present for [odl01]
2018-10-06 14:31:31,818 [salt.state       :290 ][INFO    ][6434] Host odl01 (192.168.10.39) already present
2018-10-06 14:31:31,818 [salt.state       :1941][INFO    ][6434] Completed state [odl01] at time 14:31:31.818497 duration_in_ms=0.997
2018-10-06 14:31:31,818 [salt.state       :1770][INFO    ][6434] Running state [odl01.mcp-pike-odl-ha.local] at time 14:31:31.818802
2018-10-06 14:31:31,819 [salt.state       :1803][INFO    ][6434] Executing state host.present for [odl01.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,819 [salt.state       :290 ][INFO    ][6434] Host odl01.mcp-pike-odl-ha.local (192.168.10.39) already present
2018-10-06 14:31:31,819 [salt.state       :1941][INFO    ][6434] Completed state [odl01.mcp-pike-odl-ha.local] at time 14:31:31.819704 duration_in_ms=0.902
2018-10-06 14:31:31,820 [salt.state       :1770][INFO    ][6434] Running state [mas01] at time 14:31:31.820013
2018-10-06 14:31:31,820 [salt.state       :1803][INFO    ][6434] Executing state host.present for [mas01]
2018-10-06 14:31:31,820 [salt.state       :290 ][INFO    ][6434] Host mas01 (192.168.10.12) already present
2018-10-06 14:31:31,821 [salt.state       :1941][INFO    ][6434] Completed state [mas01] at time 14:31:31.820849 duration_in_ms=0.835
2018-10-06 14:31:31,821 [salt.state       :1770][INFO    ][6434] Running state [mas01.mcp-pike-odl-ha.local] at time 14:31:31.821543
2018-10-06 14:31:31,821 [salt.state       :1803][INFO    ][6434] Executing state host.present for [mas01.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,822 [salt.state       :290 ][INFO    ][6434] Host mas01.mcp-pike-odl-ha.local (192.168.10.12) already present
2018-10-06 14:31:31,822 [salt.state       :1941][INFO    ][6434] Completed state [mas01.mcp-pike-odl-ha.local] at time 14:31:31.822367 duration_in_ms=0.823
2018-10-06 14:31:31,822 [salt.state       :1770][INFO    ][6434] Running state [ctl02] at time 14:31:31.822668
2018-10-06 14:31:31,822 [salt.state       :1803][INFO    ][6434] Executing state host.present for [ctl02]
2018-10-06 14:31:31,823 [salt.state       :290 ][INFO    ][6434] Host ctl02 (192.168.10.37) already present
2018-10-06 14:31:31,823 [salt.state       :1941][INFO    ][6434] Completed state [ctl02] at time 14:31:31.823515 duration_in_ms=0.846
2018-10-06 14:31:31,823 [salt.state       :1770][INFO    ][6434] Running state [ctl02.mcp-pike-odl-ha.local] at time 14:31:31.823835
2018-10-06 14:31:31,824 [salt.state       :1803][INFO    ][6434] Executing state host.present for [ctl02.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,824 [salt.state       :290 ][INFO    ][6434] Host ctl02.mcp-pike-odl-ha.local (192.168.10.37) already present
2018-10-06 14:31:31,824 [salt.state       :1941][INFO    ][6434] Completed state [ctl02.mcp-pike-odl-ha.local] at time 14:31:31.824666 duration_in_ms=0.831
2018-10-06 14:31:31,825 [salt.state       :1770][INFO    ][6434] Running state [ctl03] at time 14:31:31.825002
2018-10-06 14:31:31,825 [salt.state       :1803][INFO    ][6434] Executing state host.present for [ctl03]
2018-10-06 14:31:31,825 [salt.state       :290 ][INFO    ][6434] Host ctl03 (192.168.10.38) already present
2018-10-06 14:31:31,825 [salt.state       :1941][INFO    ][6434] Completed state [ctl03] at time 14:31:31.825888 duration_in_ms=0.886
2018-10-06 14:31:31,826 [salt.state       :1770][INFO    ][6434] Running state [ctl03.mcp-pike-odl-ha.local] at time 14:31:31.826191
2018-10-06 14:31:31,826 [salt.state       :1803][INFO    ][6434] Executing state host.present for [ctl03.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,826 [salt.state       :290 ][INFO    ][6434] Host ctl03.mcp-pike-odl-ha.local (192.168.10.38) already present
2018-10-06 14:31:31,827 [salt.state       :1941][INFO    ][6434] Completed state [ctl03.mcp-pike-odl-ha.local] at time 14:31:31.827058 duration_in_ms=0.866
2018-10-06 14:31:31,827 [salt.state       :1770][INFO    ][6434] Running state [ctl01] at time 14:31:31.827419
2018-10-06 14:31:31,827 [salt.state       :1803][INFO    ][6434] Executing state host.present for [ctl01]
2018-10-06 14:31:31,828 [salt.state       :290 ][INFO    ][6434] Host ctl01 (192.168.10.36) already present
2018-10-06 14:31:31,828 [salt.state       :1941][INFO    ][6434] Completed state [ctl01] at time 14:31:31.828299 duration_in_ms=0.879
2018-10-06 14:31:31,828 [salt.state       :1770][INFO    ][6434] Running state [ctl01.mcp-pike-odl-ha.local] at time 14:31:31.828609
2018-10-06 14:31:31,828 [salt.state       :1803][INFO    ][6434] Executing state host.present for [ctl01.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,829 [salt.state       :290 ][INFO    ][6434] Host ctl01.mcp-pike-odl-ha.local (192.168.10.36) already present
2018-10-06 14:31:31,829 [salt.state       :1941][INFO    ][6434] Completed state [ctl01.mcp-pike-odl-ha.local] at time 14:31:31.829844 duration_in_ms=1.235
2018-10-06 14:31:31,830 [salt.state       :1770][INFO    ][6434] Running state [ctl] at time 14:31:31.830151
2018-10-06 14:31:31,830 [salt.state       :1803][INFO    ][6434] Executing state host.present for [ctl]
2018-10-06 14:31:31,830 [salt.state       :290 ][INFO    ][6434] Host ctl (192.168.10.35) already present
2018-10-06 14:31:31,831 [salt.state       :1941][INFO    ][6434] Completed state [ctl] at time 14:31:31.830996 duration_in_ms=0.845
2018-10-06 14:31:31,831 [salt.state       :1770][INFO    ][6434] Running state [ctl.mcp-pike-odl-ha.local] at time 14:31:31.831307
2018-10-06 14:31:31,831 [salt.state       :1803][INFO    ][6434] Executing state host.present for [ctl.mcp-pike-odl-ha.local]
2018-10-06 14:31:31,832 [salt.state       :290 ][INFO    ][6434] Host ctl.mcp-pike-odl-ha.local (192.168.10.35) already present
2018-10-06 14:31:31,832 [salt.state       :1941][INFO    ][6434] Completed state [ctl.mcp-pike-odl-ha.local] at time 14:31:31.832162 duration_in_ms=0.855
2018-10-06 14:31:31,832 [salt.state       :1770][INFO    ][6434] Running state [/etc/network/interfaces.d/50-cloud-init.cfg] at time 14:31:31.832370
2018-10-06 14:31:31,832 [salt.state       :1803][INFO    ][6434] Executing state file.absent for [/etc/network/interfaces.d/50-cloud-init.cfg]
2018-10-06 14:31:31,832 [salt.state       :290 ][INFO    ][6434] File /etc/network/interfaces.d/50-cloud-init.cfg is not present
2018-10-06 14:31:31,833 [salt.state       :1941][INFO    ][6434] Completed state [/etc/network/interfaces.d/50-cloud-init.cfg] at time 14:31:31.833064 duration_in_ms=0.694
2018-10-06 14:31:31,833 [salt.state       :1770][INFO    ][6434] Running state [ens2] at time 14:31:31.833297
2018-10-06 14:31:31,833 [salt.state       :1803][INFO    ][6434] Executing state network.managed for [ens2]
2018-10-06 14:31:31,961 [salt.state       :290 ][INFO    ][6434] Interface ens2 is up to date.
2018-10-06 14:31:31,962 [salt.state       :1941][INFO    ][6434] Completed state [ens2] at time 14:31:31.962187 duration_in_ms=128.889
2018-10-06 14:31:31,962 [salt.state       :1770][INFO    ][6434] Running state [ens3] at time 14:31:31.962582
2018-10-06 14:31:31,962 [salt.state       :1803][INFO    ][6434] Executing state network.managed for [ens3]
2018-10-06 14:31:31,987 [salt.state       :290 ][INFO    ][6434] Interface ens3 is up to date.
2018-10-06 14:31:31,988 [salt.state       :1941][INFO    ][6434] Completed state [ens3] at time 14:31:31.988010 duration_in_ms=25.428
2018-10-06 14:31:31,988 [salt.state       :1770][INFO    ][6434] Running state [/etc/profile.d/proxy.sh] at time 14:31:31.988391
2018-10-06 14:31:31,988 [salt.state       :1803][INFO    ][6434] Executing state file.absent for [/etc/profile.d/proxy.sh]
2018-10-06 14:31:31,989 [salt.state       :290 ][INFO    ][6434] File /etc/profile.d/proxy.sh is not present
2018-10-06 14:31:31,990 [salt.state       :1941][INFO    ][6434] Completed state [/etc/profile.d/proxy.sh] at time 14:31:31.990061 duration_in_ms=1.67
2018-10-06 14:31:31,990 [salt.state       :1770][INFO    ][6434] Running state [/etc/apt/apt.conf.d/95proxies] at time 14:31:31.990275
2018-10-06 14:31:31,990 [salt.state       :1803][INFO    ][6434] Executing state file.absent for [/etc/apt/apt.conf.d/95proxies]
2018-10-06 14:31:31,990 [salt.state       :290 ][INFO    ][6434] File /etc/apt/apt.conf.d/95proxies is not present
2018-10-06 14:31:31,990 [salt.state       :1941][INFO    ][6434] Completed state [/etc/apt/apt.conf.d/95proxies] at time 14:31:31.990938 duration_in_ms=0.662
2018-10-06 14:31:31,991 [salt.state       :1770][INFO    ][6434] Running state [ntp] at time 14:31:31.991159
2018-10-06 14:31:31,991 [salt.state       :1803][INFO    ][6434] Executing state pkg.installed for [ntp]
2018-10-06 14:31:32,001 [salt.state       :290 ][INFO    ][6434] All specified packages are already installed
2018-10-06 14:31:32,001 [salt.state       :1941][INFO    ][6434] Completed state [ntp] at time 14:31:32.001223 duration_in_ms=10.064
2018-10-06 14:31:32,003 [salt.state       :1770][INFO    ][6434] Running state [/etc/ntp.conf] at time 14:31:32.002986
2018-10-06 14:31:32,003 [salt.state       :1803][INFO    ][6434] Executing state file.managed for [/etc/ntp.conf]
2018-10-06 14:31:32,084 [salt.state       :290 ][INFO    ][6434] File /etc/ntp.conf is in the correct state
2018-10-06 14:31:32,084 [salt.state       :1941][INFO    ][6434] Completed state [/etc/ntp.conf] at time 14:31:32.084639 duration_in_ms=81.653
2018-10-06 14:31:32,086 [salt.state       :1770][INFO    ][6434] Running state [ntp] at time 14:31:32.086276
2018-10-06 14:31:32,086 [salt.state       :1803][INFO    ][6434] Executing state service.running for [ntp]
2018-10-06 14:31:32,087 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command ['systemctl', 'status', 'ntp.service', '-n', '0'] in directory '/root'
2018-10-06 14:31:32,104 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command ['systemctl', 'is-active', 'ntp.service'] in directory '/root'
2018-10-06 14:31:32,120 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6434] Executing command ['systemctl', 'is-enabled', 'ntp.service'] in directory '/root'
2018-10-06 14:31:32,138 [salt.state       :290 ][INFO    ][6434] The service ntp is already running
2018-10-06 14:31:32,138 [salt.state       :1941][INFO    ][6434] Completed state [ntp] at time 14:31:32.138450 duration_in_ms=52.174
2018-10-06 14:31:32,143 [salt.minion      :1708][INFO    ][6434] Returning information for job: 20181006143117212897
2018-10-06 14:31:36,210 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command ssh.set_auth_key with jid 20181006143136195303
2018-10-06 14:31:36,228 [salt.minion      :1431][INFO    ][7152] Starting a new job with PID 7152
2018-10-06 14:31:36,249 [salt.minion      :1708][INFO    ][7152] Returning information for job: 20181006143136195303
2018-10-06 14:32:12,709 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command pkg.upgrade with jid 20181006143212696430
2018-10-06 14:32:12,734 [salt.minion      :1431][INFO    ][7175] Starting a new job with PID 7175
2018-10-06 14:32:12,757 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][7175] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-10-06 14:32:13,176 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][7175] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'upgrade'] in directory '/root'
2018-10-06 14:32:19,462 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][7175] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-10-06 14:32:19,496 [salt.minion      :1708][INFO    ][7175] Returning information for job: 20181006143212696430
2018-10-06 14:41:28,392 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command test.ping with jid 20181006144128381518
2018-10-06 14:41:28,409 [salt.minion      :1431][INFO    ][8115] Starting a new job with PID 8115
2018-10-06 14:41:28,432 [salt.minion      :1708][INFO    ][8115] Returning information for job: 20181006144128381518
2018-10-06 14:41:50,650 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command state.sls with jid 20181006144150639439
2018-10-06 14:41:50,666 [salt.minion      :1431][INFO    ][8120] Starting a new job with PID 8120
2018-10-06 14:41:53,752 [salt.state       :905 ][INFO    ][8120] Loading fresh modules for state activity
2018-10-06 14:41:53,815 [salt.fileclient  :1215][INFO    ][8120] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2018-10-06 14:41:53,850 [salt.fileclient  :1215][INFO    ][8120] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2018-10-06 14:41:54,459 [salt.state       :1770][INFO    ][8120] Running state [keepalived] at time 14:41:54.459173
2018-10-06 14:41:54,459 [salt.state       :1803][INFO    ][8120] Executing state pkg.installed for [keepalived]
2018-10-06 14:41:54,460 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8120] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-10-06 14:41:54,785 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8120] Executing command ['apt-cache', '-q', 'policy', 'keepalived'] in directory '/root'
2018-10-06 14:41:54,877 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8120] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-10-06 14:41:56,699 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8120] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-10-06 14:41:56,727 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8120] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'keepalived'] in directory '/root'
2018-10-06 14:42:00,226 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8120] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-10-06 14:42:00,265 [salt.state       :290 ][INFO    ][8120] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.1'
'libsensors4' changed from 'absent' to '1:3.4.0-2'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.1'
'keepalived' changed from 'absent' to '1:1.2.24-1ubuntu0.16.04.1'
'ipvsadm' changed from 'absent' to '1:1.28-3'
'libnl-route-3-200' changed from 'absent' to '3.2.27-1ubuntu0.16.04.1'

2018-10-06 14:42:00,282 [salt.state       :905 ][INFO    ][8120] Loading fresh modules for state activity
2018-10-06 14:42:00,323 [salt.state       :1941][INFO    ][8120] Completed state [keepalived] at time 14:42:00.323717 duration_in_ms=5864.544
2018-10-06 14:42:00,329 [salt.state       :1770][INFO    ][8120] Running state [lsof] at time 14:42:00.329409
2018-10-06 14:42:00,329 [salt.state       :1803][INFO    ][8120] Executing state pkg.installed for [lsof]
2018-10-06 14:42:00,781 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006144200708000
2018-10-06 14:42:00,794 [salt.minion      :1431][INFO    ][9496] Starting a new job with PID 9496
2018-10-06 14:42:00,807 [salt.minion      :1708][INFO    ][9496] Returning information for job: 20181006144200708000
2018-10-06 14:42:00,916 [salt.state       :290 ][INFO    ][8120] All specified packages are already installed
2018-10-06 14:42:00,916 [salt.state       :1941][INFO    ][8120] Completed state [lsof] at time 14:42:00.916799 duration_in_ms=587.39
2018-10-06 14:42:00,919 [salt.state       :1770][INFO    ][8120] Running state [/etc/keepalived/keepalived.conf] at time 14:42:00.919546
2018-10-06 14:42:00,919 [salt.state       :1803][INFO    ][8120] Executing state file.managed for [/etc/keepalived/keepalived.conf]
2018-10-06 14:42:00,958 [salt.fileclient  :1215][INFO    ][8120] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2018-10-06 14:42:01,008 [salt.state       :290 ][INFO    ][8120] File changed:
New file
2018-10-06 14:42:01,008 [salt.state       :1941][INFO    ][8120] Completed state [/etc/keepalived/keepalived.conf] at time 14:42:01.008707 duration_in_ms=89.161
2018-10-06 14:42:01,009 [salt.state       :1770][INFO    ][8120] Running state [keepalived] at time 14:42:01.009512
2018-10-06 14:42:01,009 [salt.state       :1803][INFO    ][8120] Executing state service.running for [keepalived]
2018-10-06 14:42:01,010 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8120] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2018-10-06 14:42:01,025 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8120] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2018-10-06 14:42:01,037 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8120] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2018-10-06 14:42:01,050 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8120] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2018-10-06 14:42:01,099 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8120] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2018-10-06 14:42:01,114 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8120] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2018-10-06 14:42:01,128 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8120] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2018-10-06 14:42:01,141 [salt.state       :290 ][INFO    ][8120] {'keepalived': True}
2018-10-06 14:42:01,142 [salt.state       :1941][INFO    ][8120] Completed state [keepalived] at time 14:42:01.142088 duration_in_ms=132.576
2018-10-06 14:42:01,143 [salt.minion      :1708][INFO    ][8120] Returning information for job: 20181006144150639439
2018-10-06 14:44:35,793 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command pillar.get with jid 20181006144435781965
2018-10-06 14:44:35,821 [salt.minion      :1431][INFO    ][9580] Starting a new job with PID 9580
2018-10-06 14:44:35,831 [salt.minion      :1708][INFO    ][9580] Returning information for job: 20181006144435781965
2018-10-06 14:47:14,299 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command state.sls with jid 20181006144714287427
2018-10-06 14:47:14,318 [salt.minion      :1431][INFO    ][9585] Starting a new job with PID 9585
2018-10-06 14:47:17,389 [salt.state       :905 ][INFO    ][9585] Loading fresh modules for state activity
2018-10-06 14:47:17,485 [salt.fileclient  :1215][INFO    ][9585] Fetching file from saltenv 'base', ** done ** 'rabbitmq/init.sls'
2018-10-06 14:47:17,543 [salt.fileclient  :1215][INFO    ][9585] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/init.sls'
2018-10-06 14:47:17,575 [salt.fileclient  :1215][INFO    ][9585] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/service.sls'
2018-10-06 14:47:17,624 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command 'cat /var/lib/rabbitmq/.erlang.cookie' in directory '/root'
2018-10-06 14:47:17,642 [salt.loaded.int.module.cmdmod:722 ][ERROR   ][9585] Command 'cat /var/lib/rabbitmq/.erlang.cookie' failed with return code: 1
2018-10-06 14:47:17,643 [salt.loaded.int.module.cmdmod:724 ][ERROR   ][9585] stdout: cat: /var/lib/rabbitmq/.erlang.cookie: No such file or directory
2018-10-06 14:47:17,643 [salt.loaded.int.module.cmdmod:728 ][ERROR   ][9585] retcode: 1
2018-10-06 14:47:17,687 [salt.fileclient  :1215][INFO    ][9585] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/ssl.sls'
2018-10-06 14:47:17,753 [salt.fileclient  :1215][INFO    ][9585] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/plugin.sls'
2018-10-06 14:47:17,832 [salt.fileclient  :1215][INFO    ][9585] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/vhost.sls'
2018-10-06 14:47:17,904 [salt.fileclient  :1215][INFO    ][9585] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/user.sls'
2018-10-06 14:47:17,959 [salt.fileclient  :1215][INFO    ][9585] Fetching file from saltenv 'base', ** done ** 'rabbitmq/cluster.sls'
2018-10-06 14:47:18,604 [salt.state       :1770][INFO    ][9585] Running state [rabbitmq-server] at time 14:47:18.603983
2018-10-06 14:47:18,604 [salt.state       :1803][INFO    ][9585] Executing state pkg.installed for [rabbitmq-server]
2018-10-06 14:47:18,605 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-10-06 14:47:18,976 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['apt-cache', '-q', 'policy', 'rabbitmq-server'] in directory '/root'
2018-10-06 14:47:19,073 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-10-06 14:47:21,077 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-10-06 14:47:21,107 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] 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'
2018-10-06 14:47:24,400 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006144724379967
2018-10-06 14:47:24,415 [salt.minion      :1431][INFO    ][10215] Starting a new job with PID 10215
2018-10-06 14:47:24,435 [salt.minion      :1708][INFO    ][10215] Returning information for job: 20181006144724379967
2018-10-06 14:47:34,629 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006144734607877
2018-10-06 14:47:34,645 [salt.minion      :1431][INFO    ][10945] Starting a new job with PID 10945
2018-10-06 14:47:34,672 [salt.minion      :1708][INFO    ][10945] Returning information for job: 20181006144734607877
2018-10-06 14:47:44,662 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006144744648911
2018-10-06 14:47:44,677 [salt.minion      :1431][INFO    ][10950] Starting a new job with PID 10950
2018-10-06 14:47:44,699 [salt.minion      :1708][INFO    ][10950] Returning information for job: 20181006144744648911
2018-10-06 14:47:54,861 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006144754840747
2018-10-06 14:47:54,873 [salt.minion      :1431][INFO    ][10955] Starting a new job with PID 10955
2018-10-06 14:47:54,888 [salt.minion      :1708][INFO    ][10955] Returning information for job: 20181006144754840747
2018-10-06 14:48:03,675 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-10-06 14:48:03,713 [salt.state       :290 ][INFO    ][9585] Made the following changes:
'libltdl7' changed from 'absent' to '2.4.6-0.1'
'erlang-runtime-tools' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-eunit' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-asn1' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-inets' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-snmp' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-odbc' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-diameter' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-parsetools' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-public-key' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'socat' changed from 'absent' to '1.7.3.1-1'
'erlang-abi-17.0' changed from 'absent' to '1'
'erlang-corba' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-xmerl' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-ic' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-os-mon' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'libodbc1' changed from 'absent' to '2.3.1-4.1'
'erlang-eldap' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-syntax-tools' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-edoc' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-ssl' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-tools' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-mnesia' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-ssh' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'libsctp1' changed from 'absent' to '1.0.16+dfsg-3'
'rabbitmq-server' changed from 'absent' to '3.6.15-3~u16.04+mcp1'
'erlang-base-hipe' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-crypto' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-erl-docgen' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'
'erlang-abi-15.b' changed from 'absent' to '1'
'erlang-nox' changed from 'absent' to '1:20.2.2+dfsg-1.1~u16.04+mcp1'

2018-10-06 14:48:03,732 [salt.state       :905 ][INFO    ][9585] Loading fresh modules for state activity
2018-10-06 14:48:03,766 [salt.state       :1941][INFO    ][9585] Completed state [rabbitmq-server] at time 14:48:03.766243 duration_in_ms=45162.26
2018-10-06 14:48:03,770 [salt.state       :1770][INFO    ][9585] Running state [/etc/rabbitmq/rabbitmq.config] at time 14:48:03.770906
2018-10-06 14:48:03,771 [salt.state       :1803][INFO    ][9585] Executing state file.managed for [/etc/rabbitmq/rabbitmq.config]
2018-10-06 14:48:03,801 [salt.fileclient  :1215][INFO    ][9585] Fetching file from saltenv 'base', ** done ** 'rabbitmq/files/rabbitmq.config'
2018-10-06 14:48:03,861 [salt.state       :290 ][INFO    ][9585] File changed:
New file
2018-10-06 14:48:03,861 [salt.state       :1941][INFO    ][9585] Completed state [/etc/rabbitmq/rabbitmq.config] at time 14:48:03.861193 duration_in_ms=90.291
2018-10-06 14:48:03,861 [salt.state       :1770][INFO    ][9585] Running state [/etc/default/rabbitmq-server] at time 14:48:03.861519
2018-10-06 14:48:03,861 [salt.state       :1803][INFO    ][9585] Executing state file.managed for [/etc/default/rabbitmq-server]
2018-10-06 14:48:03,882 [salt.fileclient  :1215][INFO    ][9585] Fetching file from saltenv 'base', ** done ** 'rabbitmq/files/default'
2018-10-06 14:48:03,916 [salt.state       :290 ][INFO    ][9585] 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.
 #
-#ulimit -n 1024
-# 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 -n 8192
2018-10-06 14:48:03,916 [salt.state       :1941][INFO    ][9585] Completed state [/etc/default/rabbitmq-server] at time 14:48:03.916702 duration_in_ms=55.183
2018-10-06 14:48:03,917 [salt.state       :1770][INFO    ][9585] Running state [/etc/systemd/system/rabbitmq-server.service.d/limits.conf] at time 14:48:03.917059
2018-10-06 14:48:03,917 [salt.state       :1803][INFO    ][9585] Executing state file.managed for [/etc/systemd/system/rabbitmq-server.service.d/limits.conf]
2018-10-06 14:48:03,936 [salt.fileclient  :1215][INFO    ][9585] Fetching file from saltenv 'base', ** done ** 'rabbitmq/files/limits.conf'
2018-10-06 14:48:03,981 [salt.state       :290 ][INFO    ][9585] File changed:
New file
2018-10-06 14:48:03,981 [salt.state       :1941][INFO    ][9585] Completed state [/etc/systemd/system/rabbitmq-server.service.d/limits.conf] at time 14:48:03.981501 duration_in_ms=64.442
2018-10-06 14:48:03,982 [salt.state       :1770][INFO    ][9585] Running state [sleep 30] at time 14:48:03.982708
2018-10-06 14:48:03,982 [salt.state       :1803][INFO    ][9585] Executing state cmd.run for [sleep 30]
2018-10-06 14:48:03,983 [py.warnings      :2526][WARNING ][9585] /usr/lib/python2.7/dist-packages/salt/states/cmd.py:824: DeprecationWarning: The legacy user/group arguments are deprecated. Replace them with runas. These arguments will be removed in Salt Oxygen.

2018-10-06 14:48:03,986 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command 'sleep 30' as user 'root' in directory '/root'
2018-10-06 14:48:05,086 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006144805070736
2018-10-06 14:48:05,105 [salt.minion      :1431][INFO    ][11138] Starting a new job with PID 11138
2018-10-06 14:48:05,119 [salt.minion      :1708][INFO    ][11138] Returning information for job: 20181006144805070736
2018-10-06 14:48:15,312 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006144815296858
2018-10-06 14:48:15,335 [salt.minion      :1431][INFO    ][11143] Starting a new job with PID 11143
2018-10-06 14:48:15,355 [salt.minion      :1708][INFO    ][11143] Returning information for job: 20181006144815296858
2018-10-06 14:48:25,378 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006144825356313
2018-10-06 14:48:25,399 [salt.minion      :1431][INFO    ][11148] Starting a new job with PID 11148
2018-10-06 14:48:25,414 [salt.minion      :1708][INFO    ][11148] Returning information for job: 20181006144825356313
2018-10-06 14:48:34,078 [salt.state       :290 ][INFO    ][9585] {'pid': 11106, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-10-06 14:48:34,080 [salt.state       :1941][INFO    ][9585] Completed state [sleep 30] at time 14:48:34.080034 duration_in_ms=30097.323
2018-10-06 14:48:34,083 [salt.state       :1770][INFO    ][9585] Running state [service rabbitmq-server stop] at time 14:48:34.083128
2018-10-06 14:48:34,084 [salt.state       :1803][INFO    ][9585] Executing state cmd.run for [service rabbitmq-server stop]
2018-10-06 14:48:34,086 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command 'service rabbitmq-server stop' in directory '/root'
2018-10-06 14:48:34,436 [salt.state       :290 ][INFO    ][9585] {'pid': 11151, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-10-06 14:48:34,437 [salt.state       :1941][INFO    ][9585] Completed state [service rabbitmq-server stop] at time 14:48:34.437486 duration_in_ms=354.356
2018-10-06 14:48:34,438 [salt.state       :1770][INFO    ][9585] Running state [/var/lib/rabbitmq] at time 14:48:34.438149
2018-10-06 14:48:34,438 [salt.state       :1803][INFO    ][9585] Executing state file.directory for [/var/lib/rabbitmq]
2018-10-06 14:48:34,440 [salt.state       :290 ][INFO    ][9585] Directory /var/lib/rabbitmq is in the correct state
Directory /var/lib/rabbitmq updated
2018-10-06 14:48:34,440 [salt.state       :1941][INFO    ][9585] Completed state [/var/lib/rabbitmq] at time 14:48:34.440796 duration_in_ms=2.647
2018-10-06 14:48:34,442 [salt.state       :1770][INFO    ][9585] Running state [/var/lib/rabbitmq/.erlang.cookie] at time 14:48:34.442466
2018-10-06 14:48:34,442 [salt.state       :1803][INFO    ][9585] Executing state file.managed for [/var/lib/rabbitmq/.erlang.cookie]
2018-10-06 14:48:34,446 [salt.state       :290 ][INFO    ][9585] File changed:
--- 
+++ 
@@ -1 +1 @@
-VNQDOSSNZXARPXUDHJNV+opnfv_secret

2018-10-06 14:48:34,446 [salt.state       :1941][INFO    ][9585] Completed state [/var/lib/rabbitmq/.erlang.cookie] at time 14:48:34.446594 duration_in_ms=4.128
2018-10-06 14:48:34,447 [salt.state       :1770][INFO    ][9585] Running state [sleep 30] at time 14:48:34.447151
2018-10-06 14:48:34,447 [salt.state       :1803][INFO    ][9585] Executing state cmd.run for [sleep 30]
2018-10-06 14:48:34,449 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command 'sleep 30' as user 'root' in directory '/root'
2018-10-06 14:48:35,398 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006144835383491
2018-10-06 14:48:35,414 [salt.minion      :1431][INFO    ][11236] Starting a new job with PID 11236
2018-10-06 14:48:35,430 [salt.minion      :1708][INFO    ][11236] Returning information for job: 20181006144835383491
2018-10-06 14:48:45,629 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006144845611421
2018-10-06 14:48:45,651 [salt.minion      :1431][INFO    ][11241] Starting a new job with PID 11241
2018-10-06 14:48:45,671 [salt.minion      :1708][INFO    ][11241] Returning information for job: 20181006144845611421
2018-10-06 14:48:55,670 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006144855649391
2018-10-06 14:48:55,691 [salt.minion      :1431][INFO    ][11246] Starting a new job with PID 11246
2018-10-06 14:48:55,709 [salt.minion      :1708][INFO    ][11246] Returning information for job: 20181006144855649391
2018-10-06 14:49:04,531 [salt.state       :290 ][INFO    ][9585] {'pid': 11211, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-10-06 14:49:04,534 [salt.state       :1941][INFO    ][9585] Completed state [sleep 30] at time 14:49:04.534741 duration_in_ms=30087.587
2018-10-06 14:49:04,961 [salt.state       :1770][INFO    ][9585] Running state [rabbitmq-server] at time 14:49:04.961301
2018-10-06 14:49:04,962 [salt.state       :1803][INFO    ][9585] Executing state service.running for [rabbitmq-server]
2018-10-06 14:49:04,962 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['systemctl', 'status', 'rabbitmq-server.service', '-n', '0'] in directory '/root'
2018-10-06 14:49:04,979 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['systemctl', 'is-active', 'rabbitmq-server.service'] in directory '/root'
2018-10-06 14:49:04,998 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['systemctl', 'is-enabled', 'rabbitmq-server.service'] in directory '/root'
2018-10-06 14:49:05,026 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'rabbitmq-server.service'] in directory '/root'
2018-10-06 14:49:05,722 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006144905702860
2018-10-06 14:49:05,733 [salt.minion      :1431][INFO    ][11525] Starting a new job with PID 11525
2018-10-06 14:49:05,755 [salt.minion      :1708][INFO    ][11525] Returning information for job: 20181006144905702860
2018-10-06 14:49:07,822 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['systemctl', 'is-active', 'rabbitmq-server.service'] in directory '/root'
2018-10-06 14:49:07,844 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['systemctl', 'is-enabled', 'rabbitmq-server.service'] in directory '/root'
2018-10-06 14:49:07,867 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['systemctl', 'is-enabled', 'rabbitmq-server.service'] in directory '/root'
2018-10-06 14:49:07,895 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'rabbitmq-server.service'] in directory '/root'
2018-10-06 14:49:08,257 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['systemctl', 'is-enabled', 'rabbitmq-server.service'] in directory '/root'
2018-10-06 14:49:08,274 [salt.state       :290 ][INFO    ][9585] {'rabbitmq-server': True}
2018-10-06 14:49:08,275 [salt.state       :1941][INFO    ][9585] Completed state [rabbitmq-server] at time 14:49:08.275660 duration_in_ms=3314.359
2018-10-06 14:49:08,280 [salt.state       :1770][INFO    ][9585] Running state [amqp_client] at time 14:49:08.280584
2018-10-06 14:49:08,281 [salt.state       :1803][INFO    ][9585] Executing state rabbitmq_plugin.enabled for [amqp_client]
2018-10-06 14:49:08,283 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmq-plugins', 'list', '-m', '-e'] as user 'root' in directory '/root'
2018-10-06 14:49:09,247 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmq-plugins', 'enable', 'amqp_client'] as user 'root' in directory '/root'
2018-10-06 14:49:10,417 [salt.state       :290 ][INFO    ][9585] {'new': 'amqp_client', 'old': ''}
2018-10-06 14:49:10,417 [salt.state       :1941][INFO    ][9585] Completed state [amqp_client] at time 14:49:10.417911 duration_in_ms=2137.326
2018-10-06 14:49:10,419 [salt.state       :1770][INFO    ][9585] Running state [rabbitmq_management] at time 14:49:10.419046
2018-10-06 14:49:10,419 [salt.state       :1803][INFO    ][9585] Executing state rabbitmq_plugin.enabled for [rabbitmq_management]
2018-10-06 14:49:10,420 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmq-plugins', 'list', '-m', '-e'] as user 'root' in directory '/root'
2018-10-06 14:49:11,372 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmq-plugins', 'enable', 'rabbitmq_management'] as user 'root' in directory '/root'
2018-10-06 14:49:13,186 [salt.state       :290 ][INFO    ][9585] {'new': 'rabbitmq_management', 'old': ''}
2018-10-06 14:49:13,187 [salt.state       :1941][INFO    ][9585] Completed state [rabbitmq_management] at time 14:49:13.187427 duration_in_ms=2768.38
2018-10-06 14:49:13,191 [salt.state       :1770][INFO    ][9585] Running state [/openstack] at time 14:49:13.191405
2018-10-06 14:49:13,192 [salt.state       :1803][INFO    ][9585] Executing state rabbitmq_vhost.present for [/openstack]
2018-10-06 14:49:13,193 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'list_vhosts', '-q'] as user 'root' in directory '/root'
2018-10-06 14:49:14,200 [salt.state       :290 ][INFO    ][9585] Virtual Host '/openstack' already exists.
2018-10-06 14:49:14,200 [salt.state       :1941][INFO    ][9585] Completed state [/openstack] at time 14:49:14.200784 duration_in_ms=1009.379
2018-10-06 14:49:14,205 [salt.state       :1770][INFO    ][9585] Running state [openstack] at time 14:49:14.205160
2018-10-06 14:49:14,205 [salt.state       :1803][INFO    ][9585] Executing state rabbitmq_user.present for [openstack]
2018-10-06 14:49:14,206 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'list_users', '-q'] as user 'root' in directory '/root'
2018-10-06 14:49:15,234 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'status'] as user 'root' in directory '/root'
2018-10-06 14:49:15,754 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006144915738384
2018-10-06 14:49:15,772 [salt.minion      :1431][INFO    ][13197] Starting a new job with PID 13197
2018-10-06 14:49:15,792 [salt.minion      :1708][INFO    ][13197] Returning information for job: 20181006144915738384
2018-10-06 14:49:18,331 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'list_user_permissions', 'openstack', '-q'] as user 'root' in directory '/root'
2018-10-06 14:49:19,338 [salt.state       :290 ][INFO    ][9585] Made the following changes:
'password' changed from 'absent' to 'Set password.'

2018-10-06 14:49:19,340 [salt.state       :1941][INFO    ][9585] Completed state [openstack] at time 14:49:19.339889 duration_in_ms=5134.727
2018-10-06 14:49:19,343 [salt.state       :1770][INFO    ][9585] Running state [Ceilometer] at time 14:49:19.343359
2018-10-06 14:49:19,343 [salt.state       :1803][INFO    ][9585] Executing state rabbitmq_policy.present for [Ceilometer]
2018-10-06 14:49:19,344 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'list_policies', '-q', '-p', '/openstack'] as user 'root' in directory '/root'
2018-10-06 14:49:20,373 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'set_policy', '-p', '/openstack', '--priority', 10, 'Ceilometer', '^metering.sample$', '{"ha-mode": "all", "message-ttl": 10800000}'] as user 'root' in directory '/root'
2018-10-06 14:49:21,458 [salt.state       :290 ][INFO    ][9585] {'new': ['Definition'], 'old': {'apply_to': 'all', 'priority': '10', 'definition': '{"ha-mode":"all","message-ttl":10800000}', 'pattern': '^metering.sample$'}}
2018-10-06 14:49:21,459 [salt.state       :1941][INFO    ][9585] Completed state [Ceilometer] at time 14:49:21.459910 duration_in_ms=2116.551
2018-10-06 14:49:21,461 [salt.state       :1770][INFO    ][9585] Running state [HA] at time 14:49:21.461455
2018-10-06 14:49:21,465 [salt.state       :1803][INFO    ][9585] Executing state rabbitmq_policy.present for [HA]
2018-10-06 14:49:21,466 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'list_policies', '-q', '-p', '/openstack'] as user 'root' in directory '/root'
2018-10-06 14:49:22,481 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'set_policy', '-p', '/openstack', 'HA', '^(?!amq\\.).*', '{"ha-mode": "all", "message-ttl": 120000}'] as user 'root' in directory '/root'
2018-10-06 14:49:23,503 [salt.state       :290 ][INFO    ][9585] {'new': ['Pattern', 'Definition'], 'old': {'apply_to': 'all', 'priority': '0', 'definition': '{"ha-mode":"all","message-ttl":120000}', 'pattern': '^(?!amq\\\\.).*'}}
2018-10-06 14:49:23,504 [salt.state       :1941][INFO    ][9585] Completed state [HA] at time 14:49:23.503869 duration_in_ms=2042.413
2018-10-06 14:49:23,505 [salt.state       :1770][INFO    ][9585] Running state [guest] at time 14:49:23.505455
2018-10-06 14:49:23,505 [salt.state       :1803][INFO    ][9585] Executing state rabbitmq_user.present for [guest]
2018-10-06 14:49:23,506 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'list_users', '-q'] as user 'root' in directory '/root'
2018-10-06 14:49:24,530 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'status'] as user 'root' in directory '/root'
2018-10-06 14:49:25,786 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006144925766869
2018-10-06 14:49:25,799 [salt.minion      :1431][INFO    ][15627] Starting a new job with PID 15627
2018-10-06 14:49:25,816 [salt.minion      :1708][INFO    ][15627] Returning information for job: 20181006144925766869
2018-10-06 14:49:27,636 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'list_user_permissions', 'guest', '-q'] as user 'root' in directory '/root'
2018-10-06 14:49:28,661 [salt.state       :290 ][INFO    ][9585] Made the following changes:
'password' changed from 'absent' to 'Set password.'

2018-10-06 14:49:28,661 [salt.state       :1941][INFO    ][9585] Completed state [guest] at time 14:49:28.661794 duration_in_ms=5156.338
2018-10-06 14:49:28,662 [salt.state       :1770][INFO    ][9585] Running state [HA] at time 14:49:28.662596
2018-10-06 14:49:28,662 [salt.state       :1803][INFO    ][9585] Executing state rabbitmq_policy.present for [HA]
2018-10-06 14:49:28,663 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'list_policies', '-q', '-p', '/'] as user 'root' in directory '/root'
2018-10-06 14:49:29,694 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'set_policy', '-p', '/', 'HA', '^(?!amq\\.).*', '{"ha-mode": "all", "message-ttl": 120000}'] as user 'root' in directory '/root'
2018-10-06 14:49:30,722 [salt.state       :290 ][INFO    ][9585] {'new': ['Pattern', 'Definition'], 'old': {'apply_to': 'all', 'priority': '0', 'definition': '{"ha-mode":"all","message-ttl":120000}', 'pattern': '^(?!amq\\\\.).*'}}
2018-10-06 14:49:30,722 [salt.state       :1941][INFO    ][9585] Completed state [HA] at time 14:49:30.722606 duration_in_ms=2060.008
2018-10-06 14:49:30,724 [salt.state       :1770][INFO    ][9585] Running state [admin] at time 14:49:30.724644
2018-10-06 14:49:30,725 [salt.state       :1803][INFO    ][9585] Executing state rabbitmq_user.present for [admin]
2018-10-06 14:49:30,727 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'list_users', '-q'] as user 'root' in directory '/root'
2018-10-06 14:49:31,678 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'status'] as user 'root' in directory '/root'
2018-10-06 14:49:34,758 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'list_users', '-q'] as user 'root' in directory '/root'
2018-10-06 14:49:35,754 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'list_user_permissions', 'admin', '-q'] as user 'root' in directory '/root'
2018-10-06 14:49:35,814 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command saltutil.find_job with jid 20181006144935801581
2018-10-06 14:49:35,827 [salt.minion      :1431][INFO    ][17910] Starting a new job with PID 17910
2018-10-06 14:49:35,846 [salt.minion      :1708][INFO    ][17910] Returning information for job: 20181006144935801581
2018-10-06 14:49:36,729 [salt.state       :290 ][INFO    ][9585] Made the following changes:
'password' changed from 'absent' to 'Set password.'

2018-10-06 14:49:36,730 [salt.state       :1941][INFO    ][9585] Completed state [admin] at time 14:49:36.729906 duration_in_ms=6005.26
2018-10-06 14:49:36,734 [salt.state       :1770][INFO    ][9585] Running state [rabbit@master] at time 14:49:36.734029
2018-10-06 14:49:36,734 [salt.state       :1803][INFO    ][9585] Executing state rabbitmq_cluster.join for [rabbit@master]
2018-10-06 14:49:36,735 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9585] Executing command ['/usr/sbin/rabbitmqctl', 'cluster_status'] as user 'root' in directory '/root'
2018-10-06 14:49:37,815 [salt.state       :290 ][INFO    ][9585] Already in cluster
2018-10-06 14:49:37,815 [salt.state       :1941][INFO    ][9585] Completed state [rabbit@master] at time 14:49:37.815811 duration_in_ms=1081.781
2018-10-06 14:49:37,824 [salt.minion      :1708][INFO    ][9585] Returning information for job: 20181006144714287427
2018-10-06 14:49:47,992 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command cmd.run with jid 20181006144947982320
2018-10-06 14:49:48,012 [salt.minion      :1431][INFO    ][18375] Starting a new job with PID 18375
2018-10-06 14:49:48,022 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][18375] Executing command 'rabbitmqctl cluster_status' in directory '/root'
2018-10-06 14:49:48,994 [salt.minion      :1708][INFO    ][18375] Returning information for job: 20181006144947982320
2018-10-06 14:56:26,089 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command state.sls with jid 20181006145626080306
2018-10-06 14:56:26,106 [salt.minion      :1431][INFO    ][18658] Starting a new job with PID 18658
2018-10-06 14:56:27,609 [salt.state       :905 ][INFO    ][18658] Loading fresh modules for state activity
2018-10-06 14:56:27,660 [salt.fileclient  :1215][INFO    ][18658] Fetching file from saltenv 'base', ** done ** 'haproxy/init.sls'
2018-10-06 14:56:27,689 [salt.fileclient  :1215][INFO    ][18658] Fetching file from saltenv 'base', ** done ** 'haproxy/proxy.sls'
2018-10-06 14:56:28,698 [salt.state       :1770][INFO    ][18658] Running state [haproxy] at time 14:56:28.698156
2018-10-06 14:56:28,698 [salt.state       :1803][INFO    ][18658] Executing state pkg.installed for [haproxy]
2018-10-06 14:56:28,699 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18658] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-10-06 14:56:29,068 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18658] Executing command ['apt-cache', '-q', 'policy', 'haproxy'] in directory '/root'
2018-10-06 14:56:29,166 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18658] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-10-06 14:56:30,998 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18658] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-10-06 14:56:31,026 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18658] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'haproxy'] in directory '/root'
2018-10-06 14:56:34,594 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18658] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-10-06 14:56:34,636 [salt.state       :290 ][INFO    ][18658] Made the following changes:
'haproxy' changed from 'absent' to '1.6.3-1ubuntu0.1'
'liblua5.3-0' changed from 'absent' to '5.3.1-1ubuntu2'

2018-10-06 14:56:34,660 [salt.state       :905 ][INFO    ][18658] Loading fresh modules for state activity
2018-10-06 14:56:34,701 [salt.state       :1941][INFO    ][18658] Completed state [haproxy] at time 14:56:34.701625 duration_in_ms=6003.47
2018-10-06 14:56:34,708 [salt.state       :1770][INFO    ][18658] Running state [/etc/default/haproxy] at time 14:56:34.708118
2018-10-06 14:56:34,708 [salt.state       :1803][INFO    ][18658] Executing state file.managed for [/etc/default/haproxy]
2018-10-06 14:56:34,746 [salt.fileclient  :1215][INFO    ][18658] Fetching file from saltenv 'base', ** done ** 'haproxy/files/haproxy.default'
2018-10-06 14:56:34,752 [salt.state       :290 ][INFO    ][18658] 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"

2018-10-06 14:56:34,757 [salt.state       :1941][INFO    ][18658] Completed state [/etc/default/haproxy] at time 14:56:34.756973 duration_in_ms=48.855
2018-10-06 14:56:34,758 [salt.state       :1770][INFO    ][18658] Running state [/etc/haproxy/haproxy.cfg] at time 14:56:34.757931
2018-10-06 14:56:34,758 [salt.state       :1803][INFO    ][18658] Executing state file.managed for [/etc/haproxy/haproxy.cfg]
2018-10-06 14:56:34,786 [salt.fileclient  :1215][INFO    ][18658] Fetching file from saltenv 'base', ** done ** 'haproxy/files/haproxy.cfg'
2018-10-06 14:56:34,972 [salt.state       :290 ][INFO    ][18658] 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 192.168.10.27:5672 
+  balance roundrobin
+  mode tcp
+  option tcpka
+  timeout client 300s
+  timeout server 300s
+  server msg01 192.168.10.28:5672 check inter 5000 rise 2 fall 3
+  server msg02 192.168.10.29:5672 backup check inter 5000 rise 2 fall 3
+  server msg03 192.168.10.30:5672 backup check inter 5000 rise 2 fall 3

2018-10-06 14:56:34,976 [salt.state       :1941][INFO    ][18658] Completed state [/etc/haproxy/haproxy.cfg] at time 14:56:34.976787 duration_in_ms=218.856
2018-10-06 14:56:34,977 [salt.state       :1770][INFO    ][18658] Running state [/etc/haproxy/ssl] at time 14:56:34.977268
2018-10-06 14:56:34,977 [salt.state       :1803][INFO    ][18658] Executing state file.directory for [/etc/haproxy/ssl]
2018-10-06 14:56:34,979 [salt.state       :290 ][INFO    ][18658] {'/etc/haproxy/ssl': 'New Dir'}
2018-10-06 14:56:34,979 [salt.state       :1941][INFO    ][18658] Completed state [/etc/haproxy/ssl] at time 14:56:34.979507 duration_in_ms=2.238
2018-10-06 14:56:34,980 [salt.state       :1770][INFO    ][18658] Running state [/etc/haproxy/errors/429.http11] at time 14:56:34.980112
2018-10-06 14:56:34,980 [salt.state       :1803][INFO    ][18658] Executing state file.managed for [/etc/haproxy/errors/429.http11]
2018-10-06 14:56:35,017 [salt.fileclient  :1215][INFO    ][18658] Fetching file from saltenv 'base', ** done ** 'haproxy/files/errors/429.http11'
2018-10-06 14:56:35,027 [salt.state       :290 ][INFO    ][18658] File changed:
New file
2018-10-06 14:56:35,028 [salt.state       :1941][INFO    ][18658] Completed state [/etc/haproxy/errors/429.http11] at time 14:56:35.028160 duration_in_ms=48.048
2018-10-06 14:56:35,029 [salt.state       :1770][INFO    ][18658] Running state [haproxy_status_packages] at time 14:56:35.029236
2018-10-06 14:56:35,029 [salt.state       :1803][INFO    ][18658] Executing state pkg.installed for [haproxy_status_packages]
2018-10-06 14:56:35,695 [salt.state       :290 ][INFO    ][18658] All specified packages are already installed
2018-10-06 14:56:35,696 [salt.state       :1941][INFO    ][18658] Completed state [haproxy_status_packages] at time 14:56:35.695924 duration_in_ms=666.686
2018-10-06 14:56:35,696 [salt.state       :1770][INFO    ][18658] Running state [/usr/bin/haproxy-status.sh] at time 14:56:35.696741
2018-10-06 14:56:35,697 [salt.state       :1803][INFO    ][18658] Executing state file.managed for [/usr/bin/haproxy-status.sh]
2018-10-06 14:56:35,721 [salt.fileclient  :1215][INFO    ][18658] Fetching file from saltenv 'base', ** done ** 'haproxy/files/haproxy-status.sh'
2018-10-06 14:56:35,797 [salt.state       :290 ][INFO    ][18658] File changed:
New file
2018-10-06 14:56:35,798 [salt.state       :1941][INFO    ][18658] Completed state [/usr/bin/haproxy-status.sh] at time 14:56:35.797959 duration_in_ms=101.217
2018-10-06 14:56:35,798 [salt.state       :1770][INFO    ][18658] Running state [net.ipv4.ip_nonlocal_bind] at time 14:56:35.798499
2018-10-06 14:56:35,798 [salt.state       :1803][INFO    ][18658] Executing state sysctl.present for [net.ipv4.ip_nonlocal_bind]
2018-10-06 14:56:35,799 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18658] Executing command 'sysctl -w net.ipv4.ip_nonlocal_bind="1"' in directory '/root'
2018-10-06 14:56:35,813 [salt.state       :290 ][INFO    ][18658] {'net.ipv4.ip_nonlocal_bind': 1}
2018-10-06 14:56:35,814 [salt.state       :1941][INFO    ][18658] Completed state [net.ipv4.ip_nonlocal_bind] at time 14:56:35.813929 duration_in_ms=15.43
2018-10-06 14:56:35,816 [salt.state       :1770][INFO    ][18658] Running state [haproxy] at time 14:56:35.816787
2018-10-06 14:56:35,817 [salt.state       :1803][INFO    ][18658] Executing state service.running for [haproxy]
2018-10-06 14:56:35,817 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18658] Executing command ['systemctl', 'status', 'haproxy.service', '-n', '0'] in directory '/root'
2018-10-06 14:56:35,834 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18658] Executing command ['systemctl', 'is-active', 'haproxy.service'] in directory '/root'
2018-10-06 14:56:35,848 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18658] Executing command ['systemctl', 'is-enabled', 'haproxy.service'] in directory '/root'
2018-10-06 14:56:35,865 [salt.state       :290 ][INFO    ][18658] The service haproxy is already running
2018-10-06 14:56:35,866 [salt.state       :1941][INFO    ][18658] Completed state [haproxy] at time 14:56:35.866104 duration_in_ms=49.316
2018-10-06 14:56:35,866 [salt.state       :1770][INFO    ][18658] Running state [haproxy] at time 14:56:35.866410
2018-10-06 14:56:35,866 [salt.state       :1803][INFO    ][18658] Executing state service.mod_watch for [haproxy]
2018-10-06 14:56:35,868 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18658] Executing command ['systemctl', 'is-active', 'haproxy.service'] in directory '/root'
2018-10-06 14:56:35,885 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18658] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'haproxy.service'] in directory '/root'
2018-10-06 14:56:35,944 [salt.state       :290 ][INFO    ][18658] {'haproxy': True}
2018-10-06 14:56:35,944 [salt.state       :1941][INFO    ][18658] Completed state [haproxy] at time 14:56:35.944736 duration_in_ms=78.324
2018-10-06 14:56:35,946 [salt.minion      :1708][INFO    ][18658] Returning information for job: 20181006145626080306
2018-10-06 14:56:42,531 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command service.status with jid 20181006145642522472
2018-10-06 14:56:42,549 [salt.minion      :1431][INFO    ][19658] Starting a new job with PID 19658
2018-10-06 14:56:42,953 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][19658] Executing command ['systemctl', 'status', 'haproxy.service', '-n', '0'] in directory '/root'
2018-10-06 14:56:42,968 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][19658] Executing command ['systemctl', 'is-active', 'haproxy.service'] in directory '/root'
2018-10-06 14:56:42,983 [salt.minion      :1708][INFO    ][19658] Returning information for job: 20181006145642522472
2018-10-06 14:56:44,241 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command service.restart with jid 20181006145644229764
2018-10-06 14:56:44,257 [salt.minion      :1431][INFO    ][19666] Starting a new job with PID 19666
2018-10-06 14:56:44,655 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][19666] Executing command ['systemctl', 'status', 'rsyslog.service', '-n', '0'] in directory '/root'
2018-10-06 14:56:44,679 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][19666] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'rsyslog.service'] in directory '/root'
2018-10-06 14:56:44,705 [salt.minion      :1708][INFO    ][19666] Returning information for job: 20181006145644229764
2018-10-06 15:29:54,969 [salt.utils.schedule:1375][INFO    ][2947] Running scheduled job: __mine_interval
2018-10-06 15:32:01,660 [salt.minion      :1307][INFO    ][2947] User sudo_ubuntu Executing command cp.push_dir with jid 20181006153201649240
2018-10-06 15:32:01,682 [salt.minion      :1431][INFO    ][20230] Starting a new job with PID 20230
