2018-09-29 14:01:56,391 [salt.loaded.int.module.cmdmod:722 ][ERROR   ][1793] Command 'rabbitmqctl cluster_status' failed with return code: 127
2018-09-29 14:01:56,392 [salt.loaded.int.module.cmdmod:724 ][ERROR   ][1793] stdout: /bin/sh: 1: rabbitmqctl: not found
2018-09-29 14:01:56,392 [salt.loaded.int.module.cmdmod:728 ][ERROR   ][1793] retcode: 127
2018-09-29 14:01:56,416 [salt.loaded.int.module.cmdmod:722 ][ERROR   ][1793] Command 'rabbitmqctl cluster_status' failed with return code: 127
2018-09-29 14:01:56,417 [salt.loaded.int.module.cmdmod:724 ][ERROR   ][1793] stdout: /bin/sh: 1: rabbitmqctl: not found
2018-09-29 14:01:56,417 [salt.loaded.int.module.cmdmod:728 ][ERROR   ][1793] retcode: 127
2018-09-29 14:01:57,885 [salt.loaded.int.module.cmdmod:722 ][ERROR   ][1793] Command 'rabbitmqctl cluster_status' failed with return code: 127
2018-09-29 14:01:57,885 [salt.loaded.int.module.cmdmod:724 ][ERROR   ][1793] stdout: /bin/sh: 1: rabbitmqctl: not found
2018-09-29 14:01:57,886 [salt.loaded.int.module.cmdmod:728 ][ERROR   ][1793] retcode: 127
2018-09-29 14:01:57,908 [salt.loaded.int.module.cmdmod:722 ][ERROR   ][1793] Command 'rabbitmqctl cluster_status' failed with return code: 127
2018-09-29 14:01:57,909 [salt.loaded.int.module.cmdmod:724 ][ERROR   ][1793] stdout: /bin/sh: 1: rabbitmqctl: not found
2018-09-29 14:01:57,909 [salt.loaded.int.module.cmdmod:728 ][ERROR   ][1793] retcode: 127
2018-09-29 14:02:14,555 [salt.utils.decorators:613 ][WARNING ][1793] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-29 14:02:17,878 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2852] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2018-09-29 14:02:17,912 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2852] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2018-09-29 14:02:17,938 [salt.utils.parsers:1051][WARNING ][1459] Minion received a SIGTERM. Exiting.
2018-09-29 14:02:18,782 [salt.cli.daemons :293 ][INFO    ][2903] Setting up the Salt Minion "msg02.mcp-pike-odl-ha.local"
2018-09-29 14:02:18,901 [salt.cli.daemons :82  ][INFO    ][2903] Starting up the Salt Minion
2018-09-29 14:02:18,902 [salt.utils.event :1017][INFO    ][2903] Starting pull socket on /var/run/salt/minion/minion_event_dda4868e5c_pull.ipc
2018-09-29 14:02:19,517 [salt.minion      :976 ][INFO    ][2903] Creating minion process manager
2018-09-29 14:02:20,595 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][2903] Executing command ['date', '+%z'] in directory '/root'
2018-09-29 14:02:20,616 [salt.utils.schedule:568 ][INFO    ][2903] Updating job settings for scheduled job: __mine_interval
2018-09-29 14:02:20,618 [salt.minion      :1107][INFO    ][2903] Added mine.update to scheduler
2018-09-29 14:02:20,623 [salt.minion      :1965][INFO    ][2903] Minion is starting as user 'root'
2018-09-29 14:02:20,637 [salt.minion      :2324][INFO    ][2903] Minion is ready to receive requests!
2018-09-29 14:02:44,479 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command state.apply with jid 20180929140244470320
2018-09-29 14:02:44,503 [salt.minion      :1431][INFO    ][2993] Starting a new job with PID 2993
2018-09-29 14:02:49,475 [salt.state       :905 ][INFO    ][2993] Loading fresh modules for state activity
2018-09-29 14:02:49,922 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/init.sls'
2018-09-29 14:02:49,962 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/init.sls'
2018-09-29 14:02:50,105 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/env.sls'
2018-09-29 14:02:50,226 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/profile.sls'
2018-09-29 14:02:50,342 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/at.sls'
2018-09-29 14:02:50,482 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/cron.sls'
2018-09-29 14:02:50,607 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/repo.sls'
2018-09-29 14:02:50,832 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/package.sls'
2018-09-29 14:02:51,107 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/timezone.sls'
2018-09-29 14:02:51,234 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/kernel.sls'
2018-09-29 14:02:51,391 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/grub.sls'
2018-09-29 14:02:51,427 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/cpu.sls'
2018-09-29 14:02:51,551 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/sysfs.sls'
2018-09-29 14:02:52,462 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/locale.sls'
2018-09-29 14:02:52,579 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/user.sls'
2018-09-29 14:02:52,716 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/group.sls'
2018-09-29 14:02:52,836 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/limit.sls'
2018-09-29 14:02:52,943 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/systemd.sls'
2018-09-29 14:02:53,062 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/apt.sls'
2018-09-29 14:02:53,188 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/system/banner.sls'
2018-09-29 14:02:53,347 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/network/init.sls'
2018-09-29 14:02:53,474 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/network/hostname.sls'
2018-09-29 14:02:53,600 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/network/host.sls'
2018-09-29 14:02:53,838 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/network/interface.sls'
2018-09-29 14:02:54,074 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/network/proxy.sls'
2018-09-29 14:02:54,193 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/storage/init.sls'
2018-09-29 14:02:54,326 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'ntp/init.sls'
2018-09-29 14:02:54,350 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'ntp/client.sls'
2018-09-29 14:02:54,394 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'ntp/server.sls'
2018-09-29 14:02:54,434 [salt.state       :1770][INFO    ][2993] Running state [/etc/environment] at time 14:02:54.434081
2018-09-29 14:02:54,434 [salt.state       :1803][INFO    ][2993] Executing state file.blockreplace for [/etc/environment]
2018-09-29 14:02:54,441 [salt.state       :290 ][INFO    ][2993] 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-09-29 14:02:54,441 [salt.state       :1941][INFO    ][2993] Completed state [/etc/environment] at time 14:02:54.441749 duration_in_ms=7.668
2018-09-29 14:02:54,442 [salt.state       :1770][INFO    ][2993] Running state [/etc/profile.d] at time 14:02:54.441992
2018-09-29 14:02:54,442 [salt.state       :1803][INFO    ][2993] Executing state file.directory for [/etc/profile.d]
2018-09-29 14:02:54,443 [salt.state       :290 ][INFO    ][2993] Directory /etc/profile.d is in the correct state
Directory /etc/profile.d updated
2018-09-29 14:02:54,444 [salt.state       :1941][INFO    ][2993] Completed state [/etc/profile.d] at time 14:02:54.444153 duration_in_ms=2.161
2018-09-29 14:02:54,445 [salt.state       :1770][INFO    ][2993] Running state [fake_linux_system_at] at time 14:02:54.445140
2018-09-29 14:02:54,445 [salt.state       :1803][INFO    ][2993] Executing state test.nop for [fake_linux_system_at]
2018-09-29 14:02:54,445 [salt.state       :290 ][INFO    ][2993] Success!
2018-09-29 14:02:54,446 [salt.state       :1941][INFO    ][2993] Completed state [fake_linux_system_at] at time 14:02:54.446091 duration_in_ms=0.951
2018-09-29 14:02:54,446 [salt.state       :1770][INFO    ][2993] Running state [fake_linux_system_cron] at time 14:02:54.446282
2018-09-29 14:02:54,446 [salt.state       :1803][INFO    ][2993] Executing state test.nop for [fake_linux_system_cron]
2018-09-29 14:02:54,446 [salt.state       :290 ][INFO    ][2993] Success!
2018-09-29 14:02:54,446 [salt.state       :1941][INFO    ][2993] Completed state [fake_linux_system_cron] at time 14:02:54.446832 duration_in_ms=0.551
2018-09-29 14:02:54,583 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929140254573840
2018-09-29 14:02:54,602 [salt.minion      :1431][INFO    ][3059] Starting a new job with PID 3059
2018-09-29 14:02:54,625 [salt.minion      :1708][INFO    ][3059] Returning information for job: 20180929140254573840
2018-09-29 14:02:55,047 [salt.state       :1770][INFO    ][2993] Running state [/etc/apt/apt.conf.d/99prefer_ipv4-salt] at time 14:02:55.047526
2018-09-29 14:02:55,048 [salt.state       :1803][INFO    ][2993] Executing state file.managed for [/etc/apt/apt.conf.d/99prefer_ipv4-salt]
2018-09-29 14:02:55,735 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/files/apt.conf'
2018-09-29 14:02:55,749 [salt.state       :290 ][INFO    ][2993] File changed:
New file
2018-09-29 14:02:55,751 [salt.state       :1941][INFO    ][2993] Completed state [/etc/apt/apt.conf.d/99prefer_ipv4-salt] at time 14:02:55.751039 duration_in_ms=703.513
2018-09-29 14:02:55,751 [salt.state       :1770][INFO    ][2993] Running state [/etc/apt/apt.conf.d/99allow_downgrades-salt] at time 14:02:55.751324
2018-09-29 14:02:55,751 [salt.state       :1803][INFO    ][2993] Executing state file.managed for [/etc/apt/apt.conf.d/99allow_downgrades-salt]
2018-09-29 14:02:55,774 [salt.state       :290 ][INFO    ][2993] File changed:
New file
2018-09-29 14:02:55,774 [salt.state       :1941][INFO    ][2993] Completed state [/etc/apt/apt.conf.d/99allow_downgrades-salt] at time 14:02:55.774947 duration_in_ms=23.623
2018-09-29 14:02:55,776 [salt.state       :1770][INFO    ][2993] Running state [linux_repo_prereq_pkgs] at time 14:02:55.776215
2018-09-29 14:02:55,776 [salt.state       :1803][INFO    ][2993] Executing state pkg.installed for [linux_repo_prereq_pkgs]
2018-09-29 14:02:55,777 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-09-29 14:02:56,143 [salt.state       :290 ][INFO    ][2993] All specified packages are already installed
2018-09-29 14:02:56,143 [salt.state       :1941][INFO    ][2993] Completed state [linux_repo_prereq_pkgs] at time 14:02:56.143699 duration_in_ms=367.483
2018-09-29 14:02:56,144 [salt.state       :1770][INFO    ][2993] Running state [/etc/apt/apt.conf.d/99proxies-salt] at time 14:02:56.143984
2018-09-29 14:02:56,144 [salt.state       :1803][INFO    ][2993] Executing state file.managed for [/etc/apt/apt.conf.d/99proxies-salt]
2018-09-29 14:02:56,162 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/files/apt.conf.d_proxies'
2018-09-29 14:02:56,177 [salt.state       :290 ][INFO    ][2993] File changed:
New file
2018-09-29 14:02:56,177 [salt.state       :1941][INFO    ][2993] Completed state [/etc/apt/apt.conf.d/99proxies-salt] at time 14:02:56.177267 duration_in_ms=33.283
2018-09-29 14:02:56,177 [salt.state       :1770][INFO    ][2993] Running state [/etc/apt/apt.conf.d/99proxies-salt-mk_openstack] at time 14:02:56.177513
2018-09-29 14:02:56,178 [salt.state       :1803][INFO    ][2993] Executing state file.absent for [/etc/apt/apt.conf.d/99proxies-salt-mk_openstack]
2018-09-29 14:02:56,178 [salt.state       :290 ][INFO    ][2993] File /etc/apt/apt.conf.d/99proxies-salt-mk_openstack is not present
2018-09-29 14:02:56,178 [salt.state       :1941][INFO    ][2993] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mk_openstack] at time 14:02:56.178841 duration_in_ms=1.327
2018-09-29 14:02:56,179 [salt.state       :1770][INFO    ][2993] Running state [/etc/apt/preferences.d/mk_openstack] at time 14:02:56.179269
2018-09-29 14:02:56,179 [salt.state       :1803][INFO    ][2993] Executing state file.managed for [/etc/apt/preferences.d/mk_openstack]
2018-09-29 14:02:56,202 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/files/preferences_repo'
2018-09-29 14:02:56,314 [salt.state       :290 ][INFO    ][2993] File changed:
New file
2018-09-29 14:02:56,314 [salt.state       :1941][INFO    ][2993] Completed state [/etc/apt/preferences.d/mk_openstack] at time 14:02:56.314898 duration_in_ms=135.629
2018-09-29 14:02:56,319 [salt.state       :1770][INFO    ][2993] Running state [deb [arch=amd64] http://apt.mirantis.com/xenial/ nightly pike] at time 14:02:56.319321
2018-09-29 14:02:56,319 [salt.state       :1803][INFO    ][2993] Executing state pkgrepo.managed for [deb [arch=amd64] http://apt.mirantis.com/xenial/ nightly pike]
2018-09-29 14:02:56,675 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['apt-key', 'add', '/var/cache/salt/minion/extrn_files/base/apt.mirantis.com/public.gpg'] in directory '/root'
2018-09-29 14:02:56,931 [salt.state       :290 ][INFO    ][2993] {'repo': 'deb [arch=amd64] http://apt.mirantis.com/xenial nightly pike'}
2018-09-29 14:02:56,932 [salt.state       :1941][INFO    ][2993] Completed state [deb [arch=amd64] http://apt.mirantis.com/xenial/ nightly pike] at time 14:02:56.932010 duration_in_ms=612.687
2018-09-29 14:02:56,932 [salt.state       :1770][INFO    ][2993] Running state [/etc/apt/apt.conf.d/99proxies-salt-uca-staging] at time 14:02:56.932373
2018-09-29 14:02:56,932 [salt.state       :1803][INFO    ][2993] Executing state file.absent for [/etc/apt/apt.conf.d/99proxies-salt-uca-staging]
2018-09-29 14:02:56,933 [salt.state       :290 ][INFO    ][2993] File /etc/apt/apt.conf.d/99proxies-salt-uca-staging is not present
2018-09-29 14:02:56,933 [salt.state       :1941][INFO    ][2993] Completed state [/etc/apt/apt.conf.d/99proxies-salt-uca-staging] at time 14:02:56.933488 duration_in_ms=1.115
2018-09-29 14:02:56,933 [salt.state       :1770][INFO    ][2993] Running state [/etc/apt/preferences.d/uca-staging] at time 14:02:56.933721
2018-09-29 14:02:56,933 [salt.state       :1803][INFO    ][2993] Executing state file.absent for [/etc/apt/preferences.d/uca-staging]
2018-09-29 14:02:56,934 [salt.state       :290 ][INFO    ][2993] File /etc/apt/preferences.d/uca-staging is not present
2018-09-29 14:02:56,934 [salt.state       :1941][INFO    ][2993] Completed state [/etc/apt/preferences.d/uca-staging] at time 14:02:56.934378 duration_in_ms=0.657
2018-09-29 14:02:56,939 [salt.state       :1770][INFO    ][2993] Running state [apt-key adv --keyserver keyserver.ubuntu.com --recv 9F68104E] at time 14:02:56.939861
2018-09-29 14:02:56,940 [salt.state       :1803][INFO    ][2993] Executing state cmd.run for [apt-key adv --keyserver keyserver.ubuntu.com --recv 9F68104E]
2018-09-29 14:02:56,940 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'test -e /etc/apt/sources.list.d/uca-staging.list' in directory '/root'
2018-09-29 14:02:56,952 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'apt-key adv --keyserver keyserver.ubuntu.com --recv 9F68104E' in directory '/root'
2018-09-29 14:02:57,168 [salt.state       :290 ][INFO    ][2993] {'pid': 3206, '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.1mKAP9oJU8/gpg.1.sh --keyserver\nkeyserver.ubuntu.com\n--recv\n9F68104E'}
2018-09-29 14:02:57,169 [salt.state       :1941][INFO    ][2993] Completed state [apt-key adv --keyserver keyserver.ubuntu.com --recv 9F68104E] at time 14:02:57.169381 duration_in_ms=229.519
2018-09-29 14:02:57,174 [salt.state       :1770][INFO    ][2993] Running state [deb http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial main] at time 14:02:57.174094
2018-09-29 14:02:57,174 [salt.state       :1803][INFO    ][2993] Executing state pkgrepo.managed for [deb http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial main]
2018-09-29 14:02:57,261 [salt.state       :290 ][INFO    ][2993] {'repo': 'deb http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial main'}
2018-09-29 14:02:57,261 [salt.state       :1941][INFO    ][2993] Completed state [deb http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial main] at time 14:02:57.261292 duration_in_ms=87.199
2018-09-29 14:02:57,261 [salt.state       :1770][INFO    ][2993] Running state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack] at time 14:02:57.261510
2018-09-29 14:02:57,262 [salt.state       :1803][INFO    ][2993] Executing state file.absent for [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack]
2018-09-29 14:02:57,262 [salt.state       :290 ][INFO    ][2993] File /etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack is not present
2018-09-29 14:02:57,262 [salt.state       :1941][INFO    ][2993] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack] at time 14:02:57.262906 duration_in_ms=1.396
2018-09-29 14:02:57,263 [salt.state       :1770][INFO    ][2993] Running state [/etc/apt/preferences.d/mirantis_openstack] at time 14:02:57.263111
2018-09-29 14:02:57,263 [salt.state       :1803][INFO    ][2993] Executing state file.managed for [/etc/apt/preferences.d/mirantis_openstack]
2018-09-29 14:02:57,378 [salt.state       :290 ][INFO    ][2993] File changed:
New file
2018-09-29 14:02:57,378 [salt.state       :1941][INFO    ][2993] Completed state [/etc/apt/preferences.d/mirantis_openstack] at time 14:02:57.378815 duration_in_ms=115.703
2018-09-29 14:02:57,380 [salt.state       :1770][INFO    ][2993] Running state [deb http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike main] at time 14:02:57.380524
2018-09-29 14:02:57,380 [salt.state       :1803][INFO    ][2993] Executing state pkgrepo.managed for [deb http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike main]
2018-09-29 14:02:57,771 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] 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-09-29 14:02:57,992 [salt.state       :290 ][INFO    ][2993] {'repo': 'deb http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike main'}
2018-09-29 14:02:57,993 [salt.state       :1941][INFO    ][2993] Completed state [deb http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike main] at time 14:02:57.993442 duration_in_ms=612.918
2018-09-29 14:02:57,994 [salt.state       :1770][INFO    ][2993] Running state [/etc/apt/apt.conf.d/99proxies-salt-uca] at time 14:02:57.994920
2018-09-29 14:02:57,995 [salt.state       :1803][INFO    ][2993] Executing state file.absent for [/etc/apt/apt.conf.d/99proxies-salt-uca]
2018-09-29 14:02:57,996 [salt.state       :290 ][INFO    ][2993] File /etc/apt/apt.conf.d/99proxies-salt-uca is not present
2018-09-29 14:02:57,996 [salt.state       :1941][INFO    ][2993] Completed state [/etc/apt/apt.conf.d/99proxies-salt-uca] at time 14:02:57.996528 duration_in_ms=1.608
2018-09-29 14:02:57,996 [salt.state       :1770][INFO    ][2993] Running state [/etc/apt/preferences.d/uca] at time 14:02:57.996848
2018-09-29 14:02:57,997 [salt.state       :1803][INFO    ][2993] Executing state file.managed for [/etc/apt/preferences.d/uca]
2018-09-29 14:02:58,140 [salt.state       :290 ][INFO    ][2993] File changed:
New file
2018-09-29 14:02:58,140 [salt.state       :1941][INFO    ][2993] Completed state [/etc/apt/preferences.d/uca] at time 14:02:58.140543 duration_in_ms=143.695
2018-09-29 14:02:58,145 [salt.state       :1770][INFO    ][2993] Running state [apt-key adv --keyserver keyserver.ubuntu.com --recv EC4926EA] at time 14:02:58.145265
2018-09-29 14:02:58,145 [salt.state       :1803][INFO    ][2993] Executing state cmd.run for [apt-key adv --keyserver keyserver.ubuntu.com --recv EC4926EA]
2018-09-29 14:02:58,146 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'test -e /etc/apt/sources.list.d/uca.list' in directory '/root'
2018-09-29 14:02:58,165 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'apt-key adv --keyserver keyserver.ubuntu.com --recv EC4926EA' in directory '/root'
2018-09-29 14:02:58,392 [salt.state       :290 ][INFO    ][2993] {'pid': 3487, '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.LXGgieANGj/gpg.1.sh --keyserver\nkeyserver.ubuntu.com\n--recv\nEC4926EA'}
2018-09-29 14:02:58,392 [salt.state       :1941][INFO    ][2993] Completed state [apt-key adv --keyserver keyserver.ubuntu.com --recv EC4926EA] at time 14:02:58.392524 duration_in_ms=247.258
2018-09-29 14:02:58,395 [salt.state       :1770][INFO    ][2993] Running state [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main] at time 14:02:58.395722
2018-09-29 14:02:58,396 [salt.state       :1803][INFO    ][2993] Executing state pkgrepo.managed for [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main]
2018-09-29 14:02:58,563 [salt.state       :290 ][INFO    ][2993] {'repo': 'deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main'}
2018-09-29 14:02:58,563 [salt.state       :1941][INFO    ][2993] Completed state [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main] at time 14:02:58.563501 duration_in_ms=167.778
2018-09-29 14:02:58,564 [salt.state       :1770][INFO    ][2993] Running state [pkg.refresh_db] at time 14:02:58.564366
2018-09-29 14:02:58,564 [salt.state       :1803][INFO    ][2993] Executing state module.run for [pkg.refresh_db]
2018-09-29 14:02:58,564 [salt.utils.decorators:613 ][WARNING ][2993] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-29 14:02:58,565 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-09-29 14:03:03,970 [salt.state       :290 ][INFO    ][2993] {'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-09-29 14:03:03,971 [salt.state       :1941][INFO    ][2993] Completed state [pkg.refresh_db] at time 14:03:03.971403 duration_in_ms=5407.036
2018-09-29 14:03:03,973 [salt.state       :1770][INFO    ][2993] Running state [UTC] at time 14:03:03.973092
2018-09-29 14:03:03,973 [salt.state       :1803][INFO    ][2993] Executing state timezone.system for [UTC]
2018-09-29 14:03:03,974 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['timedatectl'] in directory '/root'
2018-09-29 14:03:04,015 [salt.state       :290 ][INFO    ][2993] Timezone UTC already set, UTC already set to UTC
2018-09-29 14:03:04,016 [salt.state       :1941][INFO    ][2993] Completed state [UTC] at time 14:03:04.016390 duration_in_ms=43.298
2018-09-29 14:03:04,017 [salt.state       :1770][INFO    ][2993] Running state [/etc/default/grub.d] at time 14:03:04.017141
2018-09-29 14:03:04,017 [salt.state       :1803][INFO    ][2993] Executing state file.directory for [/etc/default/grub.d]
2018-09-29 14:03:04,019 [salt.state       :290 ][INFO    ][2993] Directory /etc/default/grub.d is in the correct state
Directory /etc/default/grub.d updated
2018-09-29 14:03:04,020 [salt.state       :1941][INFO    ][2993] Completed state [/etc/default/grub.d] at time 14:03:04.020555 duration_in_ms=3.414
2018-09-29 14:03:04,021 [salt.state       :1770][INFO    ][2993] Running state [update-grub] at time 14:03:04.021032
2018-09-29 14:03:04,021 [salt.state       :1803][INFO    ][2993] Executing state cmd.wait for [update-grub]
2018-09-29 14:03:04,022 [salt.state       :290 ][INFO    ][2993] No changes made for update-grub
2018-09-29 14:03:04,022 [salt.state       :1941][INFO    ][2993] Completed state [update-grub] at time 14:03:04.022405 duration_in_ms=1.373
2018-09-29 14:03:04,024 [salt.state       :1770][INFO    ][2993] Running state [/boot/grub/grub.cfg] at time 14:03:04.024350
2018-09-29 14:03:04,024 [salt.state       :1803][INFO    ][2993] Executing state file.managed for [/boot/grub/grub.cfg]
2018-09-29 14:03:04,214 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['git', '--version'] in directory '/root'
2018-09-29 14:03:04,509 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'test -f /boot/grub/grub.cfg' in directory '/root'
2018-09-29 14:03:04,526 [salt.loaded.int.states.file:2150][WARNING ][2993] 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-09-29 14:03:04,528 [salt.state       :290 ][INFO    ][2993] {'mode': '0400'}
2018-09-29 14:03:04,529 [salt.state       :1941][INFO    ][2993] Completed state [/boot/grub/grub.cfg] at time 14:03:04.528904 duration_in_ms=504.553
2018-09-29 14:03:04,529 [salt.state       :1770][INFO    ][2993] Running state [nf_conntrack] at time 14:03:04.529626
2018-09-29 14:03:04,530 [salt.state       :1803][INFO    ][2993] Executing state kmod.present for [nf_conntrack]
2018-09-29 14:03:04,531 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'lsmod' in directory '/root'
2018-09-29 14:03:04,669 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929140304659578
2018-09-29 14:03:04,690 [salt.minion      :1431][INFO    ][4085] Starting a new job with PID 4085
2018-09-29 14:03:04,710 [salt.minion      :1708][INFO    ][4085] Returning information for job: 20180929140304659578
2018-09-29 14:03:04,948 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'lsmod' in directory '/root'
2018-09-29 14:03:04,965 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'modprobe nf_conntrack' in directory '/root'
2018-09-29 14:03:04,983 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'lsmod' in directory '/root'
2018-09-29 14:03:05,096 [salt.state       :290 ][INFO    ][2993] {'nf_conntrack': 'loaded'}
2018-09-29 14:03:05,096 [salt.state       :1941][INFO    ][2993] Completed state [nf_conntrack] at time 14:03:05.096811 duration_in_ms=567.185
2018-09-29 14:03:05,097 [salt.state       :1770][INFO    ][2993] Running state [kernel.panic] at time 14:03:05.097391
2018-09-29 14:03:05,097 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [kernel.panic]
2018-09-29 14:03:05,116 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w kernel.panic="60"' in directory '/root'
2018-09-29 14:03:05,132 [salt.state       :290 ][INFO    ][2993] {'kernel.panic': 60}
2018-09-29 14:03:05,133 [salt.state       :1941][INFO    ][2993] Completed state [kernel.panic] at time 14:03:05.133379 duration_in_ms=35.986
2018-09-29 14:03:05,134 [salt.state       :1770][INFO    ][2993] Running state [net.ipv4.tcp_keepalive_probes] at time 14:03:05.133980
2018-09-29 14:03:05,135 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [net.ipv4.tcp_keepalive_probes]
2018-09-29 14:03:05,137 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w net.ipv4.tcp_keepalive_probes="8"' in directory '/root'
2018-09-29 14:03:05,152 [salt.state       :290 ][INFO    ][2993] {'net.ipv4.tcp_keepalive_probes': 8}
2018-09-29 14:03:05,153 [salt.state       :1941][INFO    ][2993] Completed state [net.ipv4.tcp_keepalive_probes] at time 14:03:05.153063 duration_in_ms=19.082
2018-09-29 14:03:05,153 [salt.state       :1770][INFO    ][2993] Running state [fs.file-max] at time 14:03:05.153829
2018-09-29 14:03:05,154 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [fs.file-max]
2018-09-29 14:03:05,156 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w fs.file-max="124165"' in directory '/root'
2018-09-29 14:03:05,173 [salt.state       :290 ][INFO    ][2993] {'fs.file-max': 124165}
2018-09-29 14:03:05,173 [salt.state       :1941][INFO    ][2993] Completed state [fs.file-max] at time 14:03:05.173797 duration_in_ms=19.968
2018-09-29 14:03:05,174 [salt.state       :1770][INFO    ][2993] Running state [net.core.somaxconn] at time 14:03:05.174376
2018-09-29 14:03:05,175 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [net.core.somaxconn]
2018-09-29 14:03:05,176 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w net.core.somaxconn="4096"' in directory '/root'
2018-09-29 14:03:05,191 [salt.state       :290 ][INFO    ][2993] {'net.core.somaxconn': 4096}
2018-09-29 14:03:05,192 [salt.state       :1941][INFO    ][2993] Completed state [net.core.somaxconn] at time 14:03:05.192734 duration_in_ms=18.357
2018-09-29 14:03:05,193 [salt.state       :1770][INFO    ][2993] Running state [net.ipv4.tcp_max_syn_backlog] at time 14:03:05.193530
2018-09-29 14:03:05,194 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [net.ipv4.tcp_max_syn_backlog]
2018-09-29 14:03:05,196 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w net.ipv4.tcp_max_syn_backlog="8192"' in directory '/root'
2018-09-29 14:03:05,211 [salt.state       :290 ][INFO    ][2993] {'net.ipv4.tcp_max_syn_backlog': 8192}
2018-09-29 14:03:05,212 [salt.state       :1941][INFO    ][2993] Completed state [net.ipv4.tcp_max_syn_backlog] at time 14:03:05.212348 duration_in_ms=18.818
2018-09-29 14:03:05,212 [salt.state       :1770][INFO    ][2993] Running state [net.ipv4.tcp_tw_reuse] at time 14:03:05.212785
2018-09-29 14:03:05,213 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [net.ipv4.tcp_tw_reuse]
2018-09-29 14:03:05,214 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w net.ipv4.tcp_tw_reuse="1"' in directory '/root'
2018-09-29 14:03:05,233 [salt.state       :290 ][INFO    ][2993] {'net.ipv4.tcp_tw_reuse': 1}
2018-09-29 14:03:05,233 [salt.state       :1941][INFO    ][2993] Completed state [net.ipv4.tcp_tw_reuse] at time 14:03:05.233440 duration_in_ms=20.654
2018-09-29 14:03:05,233 [salt.state       :1770][INFO    ][2993] Running state [net.ipv4.tcp_congestion_control] at time 14:03:05.233822
2018-09-29 14:03:05,234 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [net.ipv4.tcp_congestion_control]
2018-09-29 14:03:05,235 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w net.ipv4.tcp_congestion_control="yeah"' in directory '/root'
2018-09-29 14:03:05,257 [salt.state       :290 ][INFO    ][2993] {'net.ipv4.tcp_congestion_control': 'yeah'}
2018-09-29 14:03:05,257 [salt.state       :1941][INFO    ][2993] Completed state [net.ipv4.tcp_congestion_control] at time 14:03:05.257844 duration_in_ms=24.022
2018-09-29 14:03:05,258 [salt.state       :1770][INFO    ][2993] Running state [net.ipv4.tcp_retries2] at time 14:03:05.258245
2018-09-29 14:03:05,259 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [net.ipv4.tcp_retries2]
2018-09-29 14:03:05,260 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w net.ipv4.tcp_retries2="5"' in directory '/root'
2018-09-29 14:03:05,273 [salt.state       :290 ][INFO    ][2993] {'net.ipv4.tcp_retries2': 5}
2018-09-29 14:03:05,274 [salt.state       :1941][INFO    ][2993] Completed state [net.ipv4.tcp_retries2] at time 14:03:05.273975 duration_in_ms=15.73
2018-09-29 14:03:05,274 [salt.state       :1770][INFO    ][2993] Running state [net.core.netdev_max_backlog] at time 14:03:05.274317
2018-09-29 14:03:05,275 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [net.core.netdev_max_backlog]
2018-09-29 14:03:05,276 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w net.core.netdev_max_backlog="261144"' in directory '/root'
2018-09-29 14:03:05,293 [salt.state       :290 ][INFO    ][2993] {'net.core.netdev_max_backlog': 261144}
2018-09-29 14:03:05,293 [salt.state       :1941][INFO    ][2993] Completed state [net.core.netdev_max_backlog] at time 14:03:05.293708 duration_in_ms=19.391
2018-09-29 14:03:05,294 [salt.state       :1770][INFO    ][2993] Running state [net.ipv4.tcp_slow_start_after_idle] at time 14:03:05.294071
2018-09-29 14:03:05,295 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [net.ipv4.tcp_slow_start_after_idle]
2018-09-29 14:03:05,296 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w net.ipv4.tcp_slow_start_after_idle="0"' in directory '/root'
2018-09-29 14:03:05,312 [salt.state       :290 ][INFO    ][2993] {'net.ipv4.tcp_slow_start_after_idle': 0}
2018-09-29 14:03:05,312 [salt.state       :1941][INFO    ][2993] Completed state [net.ipv4.tcp_slow_start_after_idle] at time 14:03:05.312376 duration_in_ms=18.305
2018-09-29 14:03:05,312 [salt.state       :1770][INFO    ][2993] Running state [vm.swappiness] at time 14:03:05.312756
2018-09-29 14:03:05,313 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [vm.swappiness]
2018-09-29 14:03:05,314 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w vm.swappiness="10"' in directory '/root'
2018-09-29 14:03:05,331 [salt.state       :290 ][INFO    ][2993] {'vm.swappiness': 10}
2018-09-29 14:03:05,331 [salt.state       :1941][INFO    ][2993] Completed state [vm.swappiness] at time 14:03:05.331411 duration_in_ms=18.655
2018-09-29 14:03:05,331 [salt.state       :1770][INFO    ][2993] Running state [net.ipv4.tcp_keepalive_intvl] at time 14:03:05.331795
2018-09-29 14:03:05,332 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [net.ipv4.tcp_keepalive_intvl]
2018-09-29 14:03:05,333 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w net.ipv4.tcp_keepalive_intvl="3"' in directory '/root'
2018-09-29 14:03:05,347 [salt.state       :290 ][INFO    ][2993] {'net.ipv4.tcp_keepalive_intvl': 3}
2018-09-29 14:03:05,347 [salt.state       :1941][INFO    ][2993] Completed state [net.ipv4.tcp_keepalive_intvl] at time 14:03:05.347833 duration_in_ms=16.038
2018-09-29 14:03:05,348 [salt.state       :1770][INFO    ][2993] Running state [net.ipv4.neigh.default.gc_thresh1] at time 14:03:05.348266
2018-09-29 14:03:05,348 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [net.ipv4.neigh.default.gc_thresh1]
2018-09-29 14:03:05,349 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w net.ipv4.neigh.default.gc_thresh1="4096"' in directory '/root'
2018-09-29 14:03:05,366 [salt.state       :290 ][INFO    ][2993] {'net.ipv4.neigh.default.gc_thresh1': 4096}
2018-09-29 14:03:05,367 [salt.state       :1941][INFO    ][2993] Completed state [net.ipv4.neigh.default.gc_thresh1] at time 14:03:05.367539 duration_in_ms=19.272
2018-09-29 14:03:05,367 [salt.state       :1770][INFO    ][2993] Running state [net.ipv4.neigh.default.gc_thresh2] at time 14:03:05.367919
2018-09-29 14:03:05,368 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [net.ipv4.neigh.default.gc_thresh2]
2018-09-29 14:03:05,369 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w net.ipv4.neigh.default.gc_thresh2="8192"' in directory '/root'
2018-09-29 14:03:05,384 [salt.state       :290 ][INFO    ][2993] {'net.ipv4.neigh.default.gc_thresh2': 8192}
2018-09-29 14:03:05,384 [salt.state       :1941][INFO    ][2993] Completed state [net.ipv4.neigh.default.gc_thresh2] at time 14:03:05.384855 duration_in_ms=16.935
2018-09-29 14:03:05,385 [salt.state       :1770][INFO    ][2993] Running state [net.ipv4.neigh.default.gc_thresh3] at time 14:03:05.385220
2018-09-29 14:03:05,385 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [net.ipv4.neigh.default.gc_thresh3]
2018-09-29 14:03:05,387 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w net.ipv4.neigh.default.gc_thresh3="16384"' in directory '/root'
2018-09-29 14:03:05,402 [salt.state       :290 ][INFO    ][2993] {'net.ipv4.neigh.default.gc_thresh3': 16384}
2018-09-29 14:03:05,403 [salt.state       :1941][INFO    ][2993] Completed state [net.ipv4.neigh.default.gc_thresh3] at time 14:03:05.403113 duration_in_ms=17.892
2018-09-29 14:03:05,403 [salt.state       :1770][INFO    ][2993] Running state [net.ipv4.tcp_fin_timeout] at time 14:03:05.403480
2018-09-29 14:03:05,403 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [net.ipv4.tcp_fin_timeout]
2018-09-29 14:03:05,405 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w net.ipv4.tcp_fin_timeout="30"' in directory '/root'
2018-09-29 14:03:05,420 [salt.state       :290 ][INFO    ][2993] {'net.ipv4.tcp_fin_timeout': 30}
2018-09-29 14:03:05,421 [salt.state       :1941][INFO    ][2993] Completed state [net.ipv4.tcp_fin_timeout] at time 14:03:05.421139 duration_in_ms=17.658
2018-09-29 14:03:05,421 [salt.state       :1770][INFO    ][2993] Running state [net.ipv4.tcp_keepalive_time] at time 14:03:05.421546
2018-09-29 14:03:05,421 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [net.ipv4.tcp_keepalive_time]
2018-09-29 14:03:05,423 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w net.ipv4.tcp_keepalive_time="30"' in directory '/root'
2018-09-29 14:03:05,436 [salt.state       :290 ][INFO    ][2993] {'net.ipv4.tcp_keepalive_time': 30}
2018-09-29 14:03:05,437 [salt.state       :1941][INFO    ][2993] Completed state [net.ipv4.tcp_keepalive_time] at time 14:03:05.436921 duration_in_ms=15.375
2018-09-29 14:03:05,437 [salt.state       :1770][INFO    ][2993] Running state [net.nf_conntrack_max] at time 14:03:05.437288
2018-09-29 14:03:05,437 [salt.state       :1803][INFO    ][2993] Executing state sysctl.present for [net.nf_conntrack_max]
2018-09-29 14:03:05,439 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'sysctl -w net.nf_conntrack_max="1048576"' in directory '/root'
2018-09-29 14:03:05,453 [salt.state       :290 ][INFO    ][2993] {'net.nf_conntrack_max': 1048576}
2018-09-29 14:03:05,453 [salt.state       :1941][INFO    ][2993] Completed state [net.nf_conntrack_max] at time 14:03:05.453517 duration_in_ms=16.229
2018-09-29 14:03:05,453 [salt.state       :1770][INFO    ][2993] Running state [linux_sysfs_package] at time 14:03:05.453917
2018-09-29 14:03:05,454 [salt.state       :1803][INFO    ][2993] Executing state pkg.installed for [linux_sysfs_package]
2018-09-29 14:03:05,481 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['apt-cache', '-q', 'policy', 'sysfsutils'] in directory '/root'
2018-09-29 14:03:05,581 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-09-29 14:03:07,604 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-09-29 14:03:07,629 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] 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-09-29 14:03:10,847 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-09-29 14:03:10,882 [salt.state       :290 ][INFO    ][2993] 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-09-29 14:03:10,908 [salt.state       :905 ][INFO    ][2993] Loading fresh modules for state activity
2018-09-29 14:03:10,948 [salt.state       :1941][INFO    ][2993] Completed state [linux_sysfs_package] at time 14:03:10.948493 duration_in_ms=5494.574
2018-09-29 14:03:10,956 [salt.state       :1770][INFO    ][2993] Running state [/etc/sysfs.d] at time 14:03:10.955994
2018-09-29 14:03:10,956 [salt.state       :1803][INFO    ][2993] Executing state file.directory for [/etc/sysfs.d]
2018-09-29 14:03:10,959 [salt.state       :290 ][INFO    ][2993] Directory /etc/sysfs.d is in the correct state
Directory /etc/sysfs.d updated
2018-09-29 14:03:10,960 [salt.state       :1941][INFO    ][2993] Completed state [/etc/sysfs.d] at time 14:03:10.960135 duration_in_ms=4.141
2018-09-29 14:03:11,497 [salt.state       :1770][INFO    ][2993] Running state [ondemand] at time 14:03:11.497569
2018-09-29 14:03:11,498 [salt.state       :1803][INFO    ][2993] Executing state service.dead for [ondemand]
2018-09-29 14:03:11,498 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['systemctl', 'status', 'ondemand.service', '-n', '0'] in directory '/root'
2018-09-29 14:03:11,521 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['systemctl', 'is-active', 'ondemand.service'] in directory '/root'
2018-09-29 14:03:11,541 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-09-29 14:03:11,566 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['systemd-run', '--scope', 'systemctl', 'stop', 'ondemand.service'] in directory '/root'
2018-09-29 14:03:11,630 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['systemctl', 'is-active', 'ondemand.service'] in directory '/root'
2018-09-29 14:03:11,648 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-09-29 14:03:11,669 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-09-29 14:03:11,693 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['systemd-run', '--scope', '/usr/sbin/update-rc.d', '-f', 'ondemand', 'remove'] in directory '/root'
2018-09-29 14:03:11,861 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-09-29 14:03:11,883 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'runlevel' in directory '/root'
2018-09-29 14:03:11,896 [salt.state       :290 ][INFO    ][2993] {'ondemand': True}
2018-09-29 14:03:11,896 [salt.state       :1941][INFO    ][2993] Completed state [ondemand] at time 14:03:11.896387 duration_in_ms=398.818
2018-09-29 14:03:11,897 [salt.state       :1770][INFO    ][2993] Running state [cs_CZ.UTF-8] at time 14:03:11.897541
2018-09-29 14:03:11,897 [salt.state       :1803][INFO    ][2993] Executing state locale.present for [cs_CZ.UTF-8]
2018-09-29 14:03:11,898 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'locale -a' in directory '/root'
2018-09-29 14:03:11,915 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['locale-gen', 'cs_CZ.utf8'] in directory '/root'
2018-09-29 14:03:12,640 [salt.state       :290 ][INFO    ][2993] {'locale': 'cs_CZ.UTF-8'}
2018-09-29 14:03:12,641 [salt.state       :1941][INFO    ][2993] Completed state [cs_CZ.UTF-8] at time 14:03:12.641325 duration_in_ms=743.784
2018-09-29 14:03:12,641 [salt.state       :1770][INFO    ][2993] Running state [en_US.UTF-8] at time 14:03:12.641734
2018-09-29 14:03:12,642 [salt.state       :1803][INFO    ][2993] Executing state locale.present for [en_US.UTF-8]
2018-09-29 14:03:12,642 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'locale -a' in directory '/root'
2018-09-29 14:03:12,655 [salt.state       :290 ][INFO    ][2993] Locale en_US.UTF-8 is already present
2018-09-29 14:03:12,655 [salt.state       :1941][INFO    ][2993] Completed state [en_US.UTF-8] at time 14:03:12.655880 duration_in_ms=14.146
2018-09-29 14:03:12,657 [salt.state       :1770][INFO    ][2993] Running state [en_US.UTF-8] at time 14:03:12.657922
2018-09-29 14:03:12,658 [salt.state       :1803][INFO    ][2993] Executing state locale.system for [en_US.UTF-8]
2018-09-29 14:03:12,659 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'localectl' in directory '/root'
2018-09-29 14:03:12,701 [salt.state       :290 ][INFO    ][2993] System locale en_US.UTF-8 already set
2018-09-29 14:03:12,701 [salt.state       :1941][INFO    ][2993] Completed state [en_US.UTF-8] at time 14:03:12.701805 duration_in_ms=43.882
2018-09-29 14:03:12,703 [salt.state       :1770][INFO    ][2993] Running state [root] at time 14:03:12.703711
2018-09-29 14:03:12,704 [salt.state       :1803][INFO    ][2993] Executing state group.present for [root]
2018-09-29 14:03:12,704 [salt.state       :290 ][INFO    ][2993] Group root is present and up to date
2018-09-29 14:03:12,704 [salt.state       :1941][INFO    ][2993] Completed state [root] at time 14:03:12.704843 duration_in_ms=1.131
2018-09-29 14:03:12,707 [salt.state       :1770][INFO    ][2993] Running state [root] at time 14:03:12.707857
2018-09-29 14:03:12,708 [salt.state       :1803][INFO    ][2993] Executing state user.present for [root]
2018-09-29 14:03:12,713 [salt.state       :290 ][INFO    ][2993] User root is present and up to date
2018-09-29 14:03:12,713 [salt.state       :1941][INFO    ][2993] Completed state [root] at time 14:03:12.713583 duration_in_ms=5.726
2018-09-29 14:03:12,714 [salt.state       :1770][INFO    ][2993] Running state [/root] at time 14:03:12.714817
2018-09-29 14:03:12,715 [salt.state       :1803][INFO    ][2993] Executing state file.directory for [/root]
2018-09-29 14:03:12,715 [salt.state       :290 ][INFO    ][2993] Directory /root is in the correct state
Directory /root updated
2018-09-29 14:03:12,716 [salt.state       :1941][INFO    ][2993] Completed state [/root] at time 14:03:12.716166 duration_in_ms=1.349
2018-09-29 14:03:12,716 [salt.state       :1770][INFO    ][2993] Running state [/etc/sudoers.d/90-salt-user-root] at time 14:03:12.716376
2018-09-29 14:03:12,716 [salt.state       :1803][INFO    ][2993] Executing state file.absent for [/etc/sudoers.d/90-salt-user-root]
2018-09-29 14:03:12,716 [salt.state       :290 ][INFO    ][2993] File /etc/sudoers.d/90-salt-user-root is not present
2018-09-29 14:03:12,717 [salt.state       :1941][INFO    ][2993] Completed state [/etc/sudoers.d/90-salt-user-root] at time 14:03:12.717176 duration_in_ms=0.799
2018-09-29 14:03:12,717 [salt.state       :1770][INFO    ][2993] Running state [ubuntu] at time 14:03:12.717385
2018-09-29 14:03:12,717 [salt.state       :1803][INFO    ][2993] Executing state group.present for [ubuntu]
2018-09-29 14:03:12,717 [salt.state       :290 ][INFO    ][2993] Group ubuntu is present and up to date
2018-09-29 14:03:12,718 [salt.state       :1941][INFO    ][2993] Completed state [ubuntu] at time 14:03:12.718129 duration_in_ms=0.744
2018-09-29 14:03:12,719 [salt.state       :1770][INFO    ][2993] Running state [ubuntu] at time 14:03:12.719438
2018-09-29 14:03:12,719 [salt.state       :1803][INFO    ][2993] Executing state user.present for [ubuntu]
2018-09-29 14:03:12,723 [salt.state       :290 ][INFO    ][2993] {'passwd': 'XXX-REDACTED-XXX'}
2018-09-29 14:03:12,723 [salt.state       :1941][INFO    ][2993] Completed state [ubuntu] at time 14:03:12.723654 duration_in_ms=4.216
2018-09-29 14:03:12,724 [salt.state       :1770][INFO    ][2993] Running state [/home/ubuntu] at time 14:03:12.724891
2018-09-29 14:03:12,725 [salt.state       :1803][INFO    ][2993] Executing state file.directory for [/home/ubuntu]
2018-09-29 14:03:12,726 [salt.state       :290 ][INFO    ][2993] {'mode': '0700'}
2018-09-29 14:03:12,726 [salt.state       :1941][INFO    ][2993] Completed state [/home/ubuntu] at time 14:03:12.726388 duration_in_ms=1.497
2018-09-29 14:03:12,728 [salt.state       :1770][INFO    ][2993] Running state [/etc/sudoers.d/90-salt-user-ubuntu] at time 14:03:12.728210
2018-09-29 14:03:12,728 [salt.state       :1803][INFO    ][2993] Executing state file.managed for [/etc/sudoers.d/90-salt-user-ubuntu]
2018-09-29 14:03:12,753 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/files/sudoer'
2018-09-29 14:03:12,769 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command '/usr/sbin/visudo -c -f /tmp/__salt.tmp.0uBZHm' in directory '/root'
2018-09-29 14:03:12,792 [salt.state       :290 ][INFO    ][2993] File changed:
New file
2018-09-29 14:03:12,792 [salt.state       :1941][INFO    ][2993] Completed state [/etc/sudoers.d/90-salt-user-ubuntu] at time 14:03:12.792913 duration_in_ms=64.702
2018-09-29 14:03:12,793 [salt.state       :1770][INFO    ][2993] Running state [/etc/security/limits.d/90-salt-default.conf] at time 14:03:12.793238
2018-09-29 14:03:12,793 [salt.state       :1803][INFO    ][2993] Executing state file.managed for [/etc/security/limits.d/90-salt-default.conf]
2018-09-29 14:03:12,815 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/files/limits.conf'
2018-09-29 14:03:12,932 [salt.state       :290 ][INFO    ][2993] File changed:
New file
2018-09-29 14:03:12,932 [salt.state       :1941][INFO    ][2993] Completed state [/etc/security/limits.d/90-salt-default.conf] at time 14:03:12.932380 duration_in_ms=139.141
2018-09-29 14:03:12,932 [salt.state       :1770][INFO    ][2993] Running state [/etc/systemd/system.conf.d/90-salt.conf] at time 14:03:12.932648
2018-09-29 14:03:12,932 [salt.state       :1803][INFO    ][2993] Executing state file.managed for [/etc/systemd/system.conf.d/90-salt.conf]
2018-09-29 14:03:12,949 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/files/systemd.conf'
2018-09-29 14:03:13,052 [salt.state       :290 ][INFO    ][2993] File changed:
New file
2018-09-29 14:03:13,053 [salt.state       :1941][INFO    ][2993] Completed state [/etc/systemd/system.conf.d/90-salt.conf] at time 14:03:13.053213 duration_in_ms=120.564
2018-09-29 14:03:13,055 [salt.state       :1770][INFO    ][2993] Running state [service.systemctl_reload] at time 14:03:13.055845
2018-09-29 14:03:13,056 [salt.state       :1803][INFO    ][2993] Executing state module.wait for [service.systemctl_reload]
2018-09-29 14:03:13,056 [salt.state       :290 ][INFO    ][2993] No changes made for service.systemctl_reload
2018-09-29 14:03:13,056 [salt.state       :1941][INFO    ][2993] Completed state [service.systemctl_reload] at time 14:03:13.056612 duration_in_ms=0.767
2018-09-29 14:03:13,056 [salt.state       :1770][INFO    ][2993] Running state [service.systemctl_reload] at time 14:03:13.056801
2018-09-29 14:03:13,057 [salt.state       :1803][INFO    ][2993] Executing state module.mod_watch for [service.systemctl_reload]
2018-09-29 14:03:13,057 [salt.utils.decorators:613 ][WARNING ][2993] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-29 14:03:13,057 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2018-09-29 14:03:13,177 [salt.state       :290 ][INFO    ][2993] {'ret': True}
2018-09-29 14:03:13,178 [salt.state       :1941][INFO    ][2993] Completed state [service.systemctl_reload] at time 14:03:13.178197 duration_in_ms=121.396
2018-09-29 14:03:13,178 [salt.state       :1770][INFO    ][2993] Running state [/etc/issue] at time 14:03:13.178630
2018-09-29 14:03:13,179 [salt.state       :1803][INFO    ][2993] Executing state file.managed for [/etc/issue]
2018-09-29 14:03:13,181 [salt.state       :290 ][INFO    ][2993] 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-09-29 14:03:13,182 [salt.state       :1941][INFO    ][2993] Completed state [/etc/issue] at time 14:03:13.182094 duration_in_ms=3.464
2018-09-29 14:03:13,182 [salt.state       :1770][INFO    ][2993] Running state [/etc/hostname] at time 14:03:13.182345
2018-09-29 14:03:13,182 [salt.state       :1803][INFO    ][2993] Executing state file.managed for [/etc/hostname]
2018-09-29 14:03:13,599 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'linux/files/hostname'
2018-09-29 14:03:13,613 [salt.state       :290 ][INFO    ][2993] File changed:
--- 
+++ 
@@ -1 +1 @@
-ubuntu
+msg02

2018-09-29 14:03:13,613 [salt.state       :1941][INFO    ][2993] Completed state [/etc/hostname] at time 14:03:13.613498 duration_in_ms=431.152
2018-09-29 14:03:13,615 [salt.state       :1770][INFO    ][2993] Running state [hostname msg02] at time 14:03:13.615677
2018-09-29 14:03:13,615 [salt.state       :1803][INFO    ][2993] Executing state cmd.run for [hostname msg02]
2018-09-29 14:03:13,616 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'test "$(hostname)" = "msg02"' in directory '/root'
2018-09-29 14:03:13,630 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command 'hostname msg02' in directory '/root'
2018-09-29 14:03:13,643 [salt.state       :290 ][INFO    ][2993] {'pid': 5148, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-29 14:03:13,644 [salt.state       :1941][INFO    ][2993] Completed state [hostname msg02] at time 14:03:13.643981 duration_in_ms=28.304
2018-09-29 14:03:13,645 [salt.state       :1770][INFO    ][2993] Running state [mdb02] at time 14:03:13.645418
2018-09-29 14:03:13,645 [salt.state       :1803][INFO    ][2993] Executing state host.present for [mdb02]
2018-09-29 14:03:13,646 [salt.state       :290 ][INFO    ][2993] {'host': 'mdb02'}
2018-09-29 14:03:13,647 [salt.state       :1941][INFO    ][2993] Completed state [mdb02] at time 14:03:13.647507 duration_in_ms=2.089
2018-09-29 14:03:13,647 [salt.state       :1770][INFO    ][2993] Running state [mdb02.mcp-pike-odl-ha.local] at time 14:03:13.647827
2018-09-29 14:03:13,648 [salt.state       :1803][INFO    ][2993] Executing state host.present for [mdb02.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,648 [salt.state       :290 ][INFO    ][2993] {'host': 'mdb02.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,649 [salt.state       :1941][INFO    ][2993] Completed state [mdb02.mcp-pike-odl-ha.local] at time 14:03:13.649156 duration_in_ms=1.329
2018-09-29 14:03:13,649 [salt.state       :1770][INFO    ][2993] Running state [mdb03] at time 14:03:13.649451
2018-09-29 14:03:13,649 [salt.state       :1803][INFO    ][2993] Executing state host.present for [mdb03]
2018-09-29 14:03:13,650 [salt.state       :290 ][INFO    ][2993] {'host': 'mdb03'}
2018-09-29 14:03:13,650 [salt.state       :1941][INFO    ][2993] Completed state [mdb03] at time 14:03:13.650760 duration_in_ms=1.308
2018-09-29 14:03:13,651 [salt.state       :1770][INFO    ][2993] Running state [mdb03.mcp-pike-odl-ha.local] at time 14:03:13.651392
2018-09-29 14:03:13,651 [salt.state       :1803][INFO    ][2993] Executing state host.present for [mdb03.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,652 [salt.state       :290 ][INFO    ][2993] {'host': 'mdb03.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,652 [salt.state       :1941][INFO    ][2993] Completed state [mdb03.mcp-pike-odl-ha.local] at time 14:03:13.652769 duration_in_ms=1.377
2018-09-29 14:03:13,653 [salt.state       :1770][INFO    ][2993] Running state [mdb01] at time 14:03:13.653056
2018-09-29 14:03:13,653 [salt.state       :1803][INFO    ][2993] Executing state host.present for [mdb01]
2018-09-29 14:03:13,654 [salt.state       :290 ][INFO    ][2993] {'host': 'mdb01'}
2018-09-29 14:03:13,654 [salt.state       :1941][INFO    ][2993] Completed state [mdb01] at time 14:03:13.654400 duration_in_ms=1.344
2018-09-29 14:03:13,654 [salt.state       :1770][INFO    ][2993] Running state [mdb01.mcp-pike-odl-ha.local] at time 14:03:13.654681
2018-09-29 14:03:13,654 [salt.state       :1803][INFO    ][2993] Executing state host.present for [mdb01.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,656 [salt.state       :290 ][INFO    ][2993] {'host': 'mdb01.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,656 [salt.state       :1941][INFO    ][2993] Completed state [mdb01.mcp-pike-odl-ha.local] at time 14:03:13.656698 duration_in_ms=2.016
2018-09-29 14:03:13,657 [salt.state       :1770][INFO    ][2993] Running state [mdb] at time 14:03:13.656997
2018-09-29 14:03:13,657 [salt.state       :1803][INFO    ][2993] Executing state host.present for [mdb]
2018-09-29 14:03:13,658 [salt.state       :290 ][INFO    ][2993] {'host': 'mdb'}
2018-09-29 14:03:13,658 [salt.state       :1941][INFO    ][2993] Completed state [mdb] at time 14:03:13.658302 duration_in_ms=1.305
2018-09-29 14:03:13,658 [salt.state       :1770][INFO    ][2993] Running state [mdb.mcp-pike-odl-ha.local] at time 14:03:13.658595
2018-09-29 14:03:13,658 [salt.state       :1803][INFO    ][2993] Executing state host.present for [mdb.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,661 [salt.state       :290 ][INFO    ][2993] {'host': 'mdb.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,661 [salt.state       :1941][INFO    ][2993] Completed state [mdb.mcp-pike-odl-ha.local] at time 14:03:13.661534 duration_in_ms=2.939
2018-09-29 14:03:13,661 [salt.state       :1770][INFO    ][2993] Running state [cfg01] at time 14:03:13.661832
2018-09-29 14:03:13,662 [salt.state       :1803][INFO    ][2993] Executing state host.present for [cfg01]
2018-09-29 14:03:13,662 [salt.state       :290 ][INFO    ][2993] {'host': 'cfg01'}
2018-09-29 14:03:13,663 [salt.state       :1941][INFO    ][2993] Completed state [cfg01] at time 14:03:13.663453 duration_in_ms=1.621
2018-09-29 14:03:13,663 [salt.state       :1770][INFO    ][2993] Running state [cfg01.mcp-pike-odl-ha.local] at time 14:03:13.663770
2018-09-29 14:03:13,664 [salt.state       :1803][INFO    ][2993] Executing state host.present for [cfg01.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,664 [salt.state       :290 ][INFO    ][2993] {'host': 'cfg01.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,665 [salt.state       :1941][INFO    ][2993] Completed state [cfg01.mcp-pike-odl-ha.local] at time 14:03:13.665163 duration_in_ms=1.393
2018-09-29 14:03:13,665 [salt.state       :1770][INFO    ][2993] Running state [prx01] at time 14:03:13.665459
2018-09-29 14:03:13,665 [salt.state       :1803][INFO    ][2993] Executing state host.present for [prx01]
2018-09-29 14:03:13,666 [salt.state       :290 ][INFO    ][2993] {'host': 'prx01'}
2018-09-29 14:03:13,666 [salt.state       :1941][INFO    ][2993] Completed state [prx01] at time 14:03:13.666744 duration_in_ms=1.285
2018-09-29 14:03:13,667 [salt.state       :1770][INFO    ][2993] Running state [prx01.mcp-pike-odl-ha.local] at time 14:03:13.667659
2018-09-29 14:03:13,667 [salt.state       :1803][INFO    ][2993] Executing state host.present for [prx01.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,668 [salt.state       :290 ][INFO    ][2993] {'host': 'prx01.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,669 [salt.state       :1941][INFO    ][2993] Completed state [prx01.mcp-pike-odl-ha.local] at time 14:03:13.669055 duration_in_ms=1.396
2018-09-29 14:03:13,669 [salt.state       :1770][INFO    ][2993] Running state [kvm01] at time 14:03:13.669344
2018-09-29 14:03:13,669 [salt.state       :1803][INFO    ][2993] Executing state host.present for [kvm01]
2018-09-29 14:03:13,670 [salt.state       :290 ][INFO    ][2993] {'host': 'kvm01'}
2018-09-29 14:03:13,670 [salt.state       :1941][INFO    ][2993] Completed state [kvm01] at time 14:03:13.670654 duration_in_ms=1.31
2018-09-29 14:03:13,670 [salt.state       :1770][INFO    ][2993] Running state [kvm01.mcp-pike-odl-ha.local] at time 14:03:13.670950
2018-09-29 14:03:13,671 [salt.state       :1803][INFO    ][2993] Executing state host.present for [kvm01.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,672 [salt.state       :290 ][INFO    ][2993] {'host': 'kvm01.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,672 [salt.state       :1941][INFO    ][2993] Completed state [kvm01.mcp-pike-odl-ha.local] at time 14:03:13.672309 duration_in_ms=1.359
2018-09-29 14:03:13,672 [salt.state       :1770][INFO    ][2993] Running state [kvm03] at time 14:03:13.672622
2018-09-29 14:03:13,672 [salt.state       :1803][INFO    ][2993] Executing state host.present for [kvm03]
2018-09-29 14:03:13,673 [salt.state       :290 ][INFO    ][2993] {'host': 'kvm03'}
2018-09-29 14:03:13,674 [salt.state       :1941][INFO    ][2993] Completed state [kvm03] at time 14:03:13.674022 duration_in_ms=1.4
2018-09-29 14:03:13,674 [salt.state       :1770][INFO    ][2993] Running state [kvm03.mcp-pike-odl-ha.local] at time 14:03:13.674331
2018-09-29 14:03:13,674 [salt.state       :1803][INFO    ][2993] Executing state host.present for [kvm03.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,675 [salt.state       :290 ][INFO    ][2993] {'host': 'kvm03.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,675 [salt.state       :1941][INFO    ][2993] Completed state [kvm03.mcp-pike-odl-ha.local] at time 14:03:13.675871 duration_in_ms=1.541
2018-09-29 14:03:13,676 [salt.state       :1770][INFO    ][2993] Running state [kvm02] at time 14:03:13.676266
2018-09-29 14:03:13,676 [salt.state       :1803][INFO    ][2993] Executing state host.present for [kvm02]
2018-09-29 14:03:13,677 [salt.state       :290 ][INFO    ][2993] {'host': 'kvm02'}
2018-09-29 14:03:13,678 [salt.state       :1941][INFO    ][2993] Completed state [kvm02] at time 14:03:13.677979 duration_in_ms=1.713
2018-09-29 14:03:13,678 [salt.state       :1770][INFO    ][2993] Running state [kvm02.mcp-pike-odl-ha.local] at time 14:03:13.678430
2018-09-29 14:03:13,678 [salt.state       :1803][INFO    ][2993] Executing state host.present for [kvm02.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,680 [salt.state       :290 ][INFO    ][2993] {'host': 'kvm02.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,680 [salt.state       :1941][INFO    ][2993] Completed state [kvm02.mcp-pike-odl-ha.local] at time 14:03:13.680860 duration_in_ms=2.431
2018-09-29 14:03:13,681 [salt.state       :1770][INFO    ][2993] Running state [dbs] at time 14:03:13.681152
2018-09-29 14:03:13,681 [salt.state       :1803][INFO    ][2993] Executing state host.present for [dbs]
2018-09-29 14:03:13,682 [salt.state       :290 ][INFO    ][2993] {'host': 'dbs'}
2018-09-29 14:03:13,682 [salt.state       :1941][INFO    ][2993] Completed state [dbs] at time 14:03:13.682654 duration_in_ms=1.502
2018-09-29 14:03:13,683 [salt.state       :1770][INFO    ][2993] Running state [dbs.mcp-pike-odl-ha.local] at time 14:03:13.683077
2018-09-29 14:03:13,683 [salt.state       :1803][INFO    ][2993] Executing state host.present for [dbs.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,684 [salt.state       :290 ][INFO    ][2993] {'host': 'dbs.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,684 [salt.state       :1941][INFO    ][2993] Completed state [dbs.mcp-pike-odl-ha.local] at time 14:03:13.684532 duration_in_ms=1.455
2018-09-29 14:03:13,684 [salt.state       :1770][INFO    ][2993] Running state [prx] at time 14:03:13.684894
2018-09-29 14:03:13,685 [salt.state       :1803][INFO    ][2993] Executing state host.present for [prx]
2018-09-29 14:03:13,686 [salt.state       :290 ][INFO    ][2993] {'host': 'prx'}
2018-09-29 14:03:13,686 [salt.state       :1941][INFO    ][2993] Completed state [prx] at time 14:03:13.686300 duration_in_ms=1.406
2018-09-29 14:03:13,686 [salt.state       :1770][INFO    ][2993] Running state [prx.mcp-pike-odl-ha.local] at time 14:03:13.686599
2018-09-29 14:03:13,686 [salt.state       :1803][INFO    ][2993] Executing state host.present for [prx.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,688 [salt.state       :290 ][INFO    ][2993] {'host': 'prx.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,688 [salt.state       :1941][INFO    ][2993] Completed state [prx.mcp-pike-odl-ha.local] at time 14:03:13.688380 duration_in_ms=1.782
2018-09-29 14:03:13,688 [salt.state       :1770][INFO    ][2993] Running state [prx02] at time 14:03:13.688870
2018-09-29 14:03:13,689 [salt.state       :1803][INFO    ][2993] Executing state host.present for [prx02]
2018-09-29 14:03:13,690 [salt.state       :290 ][INFO    ][2993] {'host': 'prx02'}
2018-09-29 14:03:13,690 [salt.state       :1941][INFO    ][2993] Completed state [prx02] at time 14:03:13.690463 duration_in_ms=1.594
2018-09-29 14:03:13,690 [salt.state       :1770][INFO    ][2993] Running state [prx02.mcp-pike-odl-ha.local] at time 14:03:13.690775
2018-09-29 14:03:13,691 [salt.state       :1803][INFO    ][2993] Executing state host.present for [prx02.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,692 [salt.state       :290 ][INFO    ][2993] {'host': 'prx02.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,692 [salt.state       :1941][INFO    ][2993] Completed state [prx02.mcp-pike-odl-ha.local] at time 14:03:13.692892 duration_in_ms=2.117
2018-09-29 14:03:13,693 [salt.state       :1770][INFO    ][2993] Running state [msg02] at time 14:03:13.693188
2018-09-29 14:03:13,693 [salt.state       :1803][INFO    ][2993] Executing state host.present for [msg02]
2018-09-29 14:03:13,694 [salt.state       :290 ][INFO    ][2993] {'host': 'msg02'}
2018-09-29 14:03:13,694 [salt.state       :1941][INFO    ][2993] Completed state [msg02] at time 14:03:13.694561 duration_in_ms=1.373
2018-09-29 14:03:13,694 [salt.state       :1770][INFO    ][2993] Running state [msg02.mcp-pike-odl-ha.local] at time 14:03:13.694872
2018-09-29 14:03:13,695 [salt.state       :1803][INFO    ][2993] Executing state host.present for [msg02.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,696 [salt.state       :290 ][INFO    ][2993] {'host': 'msg02.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,696 [salt.state       :1941][INFO    ][2993] Completed state [msg02.mcp-pike-odl-ha.local] at time 14:03:13.696803 duration_in_ms=1.93
2018-09-29 14:03:13,697 [salt.state       :1770][INFO    ][2993] Running state [msg03] at time 14:03:13.697106
2018-09-29 14:03:13,697 [salt.state       :1803][INFO    ][2993] Executing state host.present for [msg03]
2018-09-29 14:03:13,698 [salt.state       :290 ][INFO    ][2993] {'host': 'msg03'}
2018-09-29 14:03:13,698 [salt.state       :1941][INFO    ][2993] Completed state [msg03] at time 14:03:13.698471 duration_in_ms=1.365
2018-09-29 14:03:13,698 [salt.state       :1770][INFO    ][2993] Running state [msg03.mcp-pike-odl-ha.local] at time 14:03:13.698766
2018-09-29 14:03:13,699 [salt.state       :1803][INFO    ][2993] Executing state host.present for [msg03.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,699 [salt.state       :290 ][INFO    ][2993] {'host': 'msg03.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,700 [salt.state       :1941][INFO    ][2993] Completed state [msg03.mcp-pike-odl-ha.local] at time 14:03:13.700176 duration_in_ms=1.41
2018-09-29 14:03:13,700 [salt.state       :1770][INFO    ][2993] Running state [msg01] at time 14:03:13.700530
2018-09-29 14:03:13,700 [salt.state       :1803][INFO    ][2993] Executing state host.present for [msg01]
2018-09-29 14:03:13,701 [salt.state       :290 ][INFO    ][2993] {'host': 'msg01'}
2018-09-29 14:03:13,701 [salt.state       :1941][INFO    ][2993] Completed state [msg01] at time 14:03:13.701928 duration_in_ms=1.397
2018-09-29 14:03:13,702 [salt.state       :1770][INFO    ][2993] Running state [msg01.mcp-pike-odl-ha.local] at time 14:03:13.702249
2018-09-29 14:03:13,702 [salt.state       :1803][INFO    ][2993] Executing state host.present for [msg01.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,703 [salt.state       :290 ][INFO    ][2993] {'host': 'msg01.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,703 [salt.state       :1941][INFO    ][2993] Completed state [msg01.mcp-pike-odl-ha.local] at time 14:03:13.703755 duration_in_ms=1.505
2018-09-29 14:03:13,704 [salt.state       :1770][INFO    ][2993] Running state [msg] at time 14:03:13.704069
2018-09-29 14:03:13,704 [salt.state       :1803][INFO    ][2993] Executing state host.present for [msg]
2018-09-29 14:03:13,705 [salt.state       :290 ][INFO    ][2993] {'host': 'msg'}
2018-09-29 14:03:13,705 [salt.state       :1941][INFO    ][2993] Completed state [msg] at time 14:03:13.705619 duration_in_ms=1.55
2018-09-29 14:03:13,705 [salt.state       :1770][INFO    ][2993] Running state [msg.mcp-pike-odl-ha.local] at time 14:03:13.705957
2018-09-29 14:03:13,706 [salt.state       :1803][INFO    ][2993] Executing state host.present for [msg.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,707 [salt.state       :290 ][INFO    ][2993] {'host': 'msg.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,707 [salt.state       :1941][INFO    ][2993] Completed state [msg.mcp-pike-odl-ha.local] at time 14:03:13.707756 duration_in_ms=1.8
2018-09-29 14:03:13,708 [salt.state       :1770][INFO    ][2993] Running state [cfg01] at time 14:03:13.708073
2018-09-29 14:03:13,708 [salt.state       :1803][INFO    ][2993] Executing state host.present for [cfg01]
2018-09-29 14:03:13,708 [salt.state       :290 ][INFO    ][2993] Host cfg01 (192.168.10.11) already present
2018-09-29 14:03:13,709 [salt.state       :1941][INFO    ][2993] Completed state [cfg01] at time 14:03:13.708975 duration_in_ms=0.901
2018-09-29 14:03:13,709 [salt.state       :1770][INFO    ][2993] Running state [cfg01.mcp-pike-odl-ha.local] at time 14:03:13.709273
2018-09-29 14:03:13,709 [salt.state       :1803][INFO    ][2993] Executing state host.present for [cfg01.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,709 [salt.state       :290 ][INFO    ][2993] Host cfg01.mcp-pike-odl-ha.local (192.168.10.11) already present
2018-09-29 14:03:13,710 [salt.state       :1941][INFO    ][2993] Completed state [cfg01.mcp-pike-odl-ha.local] at time 14:03:13.710093 duration_in_ms=0.821
2018-09-29 14:03:13,710 [salt.state       :1770][INFO    ][2993] Running state [cmp002] at time 14:03:13.710389
2018-09-29 14:03:13,710 [salt.state       :1803][INFO    ][2993] Executing state host.present for [cmp002]
2018-09-29 14:03:13,711 [salt.state       :290 ][INFO    ][2993] {'host': 'cmp002'}
2018-09-29 14:03:13,712 [salt.state       :1941][INFO    ][2993] Completed state [cmp002] at time 14:03:13.712058 duration_in_ms=1.669
2018-09-29 14:03:13,712 [salt.state       :1770][INFO    ][2993] Running state [cmp002.mcp-pike-odl-ha.local] at time 14:03:13.712409
2018-09-29 14:03:13,712 [salt.state       :1803][INFO    ][2993] Executing state host.present for [cmp002.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,713 [salt.state       :290 ][INFO    ][2993] {'host': 'cmp002.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,713 [salt.state       :1941][INFO    ][2993] Completed state [cmp002.mcp-pike-odl-ha.local] at time 14:03:13.713923 duration_in_ms=1.514
2018-09-29 14:03:13,714 [salt.state       :1770][INFO    ][2993] Running state [cmp001] at time 14:03:13.714263
2018-09-29 14:03:13,714 [salt.state       :1803][INFO    ][2993] Executing state host.present for [cmp001]
2018-09-29 14:03:13,716 [salt.state       :290 ][INFO    ][2993] {'host': 'cmp001'}
2018-09-29 14:03:13,716 [salt.state       :1941][INFO    ][2993] Completed state [cmp001] at time 14:03:13.716363 duration_in_ms=2.1
2018-09-29 14:03:13,716 [salt.state       :1770][INFO    ][2993] Running state [cmp001.mcp-pike-odl-ha.local] at time 14:03:13.716724
2018-09-29 14:03:13,716 [salt.state       :1803][INFO    ][2993] Executing state host.present for [cmp001.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,718 [salt.state       :290 ][INFO    ][2993] {'host': 'cmp001.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,718 [salt.state       :1941][INFO    ][2993] Completed state [cmp001.mcp-pike-odl-ha.local] at time 14:03:13.718248 duration_in_ms=1.524
2018-09-29 14:03:13,718 [salt.state       :1770][INFO    ][2993] Running state [dbs01] at time 14:03:13.718579
2018-09-29 14:03:13,718 [salt.state       :1803][INFO    ][2993] Executing state host.present for [dbs01]
2018-09-29 14:03:13,719 [salt.state       :290 ][INFO    ][2993] {'host': 'dbs01'}
2018-09-29 14:03:13,720 [salt.state       :1941][INFO    ][2993] Completed state [dbs01] at time 14:03:13.720037 duration_in_ms=1.458
2018-09-29 14:03:13,720 [salt.state       :1770][INFO    ][2993] Running state [dbs01.mcp-pike-odl-ha.local] at time 14:03:13.720366
2018-09-29 14:03:13,720 [salt.state       :1803][INFO    ][2993] Executing state host.present for [dbs01.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,721 [salt.state       :290 ][INFO    ][2993] {'host': 'dbs01.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,721 [salt.state       :1941][INFO    ][2993] Completed state [dbs01.mcp-pike-odl-ha.local] at time 14:03:13.721887 duration_in_ms=1.521
2018-09-29 14:03:13,722 [salt.state       :1770][INFO    ][2993] Running state [dbs02] at time 14:03:13.722219
2018-09-29 14:03:13,722 [salt.state       :1803][INFO    ][2993] Executing state host.present for [dbs02]
2018-09-29 14:03:13,723 [salt.state       :290 ][INFO    ][2993] {'host': 'dbs02'}
2018-09-29 14:03:13,723 [salt.state       :1941][INFO    ][2993] Completed state [dbs02] at time 14:03:13.723736 duration_in_ms=1.517
2018-09-29 14:03:13,724 [salt.state       :1770][INFO    ][2993] Running state [dbs02.mcp-pike-odl-ha.local] at time 14:03:13.724046
2018-09-29 14:03:13,724 [salt.state       :1803][INFO    ][2993] Executing state host.present for [dbs02.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,725 [salt.state       :290 ][INFO    ][2993] {'host': 'dbs02.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,725 [salt.state       :1941][INFO    ][2993] Completed state [dbs02.mcp-pike-odl-ha.local] at time 14:03:13.725663 duration_in_ms=1.617
2018-09-29 14:03:13,726 [salt.state       :1770][INFO    ][2993] Running state [dbs03] at time 14:03:13.725991
2018-09-29 14:03:13,726 [salt.state       :1803][INFO    ][2993] Executing state host.present for [dbs03]
2018-09-29 14:03:13,728 [salt.state       :290 ][INFO    ][2993] {'host': 'dbs03'}
2018-09-29 14:03:13,728 [salt.state       :1941][INFO    ][2993] Completed state [dbs03] at time 14:03:13.728381 duration_in_ms=2.389
2018-09-29 14:03:13,728 [salt.state       :1770][INFO    ][2993] Running state [dbs03.mcp-pike-odl-ha.local] at time 14:03:13.728723
2018-09-29 14:03:13,728 [salt.state       :1803][INFO    ][2993] Executing state host.present for [dbs03.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,730 [salt.state       :290 ][INFO    ][2993] {'host': 'dbs03.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,730 [salt.state       :1941][INFO    ][2993] Completed state [dbs03.mcp-pike-odl-ha.local] at time 14:03:13.730314 duration_in_ms=1.591
2018-09-29 14:03:13,730 [salt.state       :1770][INFO    ][2993] Running state [odl01] at time 14:03:13.730631
2018-09-29 14:03:13,730 [salt.state       :1803][INFO    ][2993] Executing state host.present for [odl01]
2018-09-29 14:03:13,732 [salt.state       :290 ][INFO    ][2993] {'host': 'odl01'}
2018-09-29 14:03:13,732 [salt.state       :1941][INFO    ][2993] Completed state [odl01] at time 14:03:13.732495 duration_in_ms=1.864
2018-09-29 14:03:13,733 [salt.state       :1770][INFO    ][2993] Running state [odl01.mcp-pike-odl-ha.local] at time 14:03:13.732812
2018-09-29 14:03:13,733 [salt.state       :1803][INFO    ][2993] Executing state host.present for [odl01.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,734 [salt.state       :290 ][INFO    ][2993] {'host': 'odl01.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,734 [salt.state       :1941][INFO    ][2993] Completed state [odl01.mcp-pike-odl-ha.local] at time 14:03:13.734653 duration_in_ms=1.841
2018-09-29 14:03:13,735 [salt.state       :1770][INFO    ][2993] Running state [mas01] at time 14:03:13.734988
2018-09-29 14:03:13,735 [salt.state       :1803][INFO    ][2993] Executing state host.present for [mas01]
2018-09-29 14:03:13,736 [salt.state       :290 ][INFO    ][2993] {'host': 'mas01'}
2018-09-29 14:03:13,736 [salt.state       :1941][INFO    ][2993] Completed state [mas01] at time 14:03:13.736522 duration_in_ms=1.534
2018-09-29 14:03:13,736 [salt.state       :1770][INFO    ][2993] Running state [mas01.mcp-pike-odl-ha.local] at time 14:03:13.736846
2018-09-29 14:03:13,737 [salt.state       :1803][INFO    ][2993] Executing state host.present for [mas01.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,738 [salt.state       :290 ][INFO    ][2993] {'host': 'mas01.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,738 [salt.state       :1941][INFO    ][2993] Completed state [mas01.mcp-pike-odl-ha.local] at time 14:03:13.738477 duration_in_ms=1.63
2018-09-29 14:03:13,738 [salt.state       :1770][INFO    ][2993] Running state [ctl02] at time 14:03:13.738829
2018-09-29 14:03:13,739 [salt.state       :1803][INFO    ][2993] Executing state host.present for [ctl02]
2018-09-29 14:03:13,740 [salt.state       :290 ][INFO    ][2993] {'host': 'ctl02'}
2018-09-29 14:03:13,741 [salt.state       :1941][INFO    ][2993] Completed state [ctl02] at time 14:03:13.741078 duration_in_ms=2.249
2018-09-29 14:03:13,741 [salt.state       :1770][INFO    ][2993] Running state [ctl02.mcp-pike-odl-ha.local] at time 14:03:13.741429
2018-09-29 14:03:13,741 [salt.state       :1803][INFO    ][2993] Executing state host.present for [ctl02.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,742 [salt.state       :290 ][INFO    ][2993] {'host': 'ctl02.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,743 [salt.state       :1941][INFO    ][2993] Completed state [ctl02.mcp-pike-odl-ha.local] at time 14:03:13.743078 duration_in_ms=1.649
2018-09-29 14:03:13,743 [salt.state       :1770][INFO    ][2993] Running state [ctl03] at time 14:03:13.743409
2018-09-29 14:03:13,743 [salt.state       :1803][INFO    ][2993] Executing state host.present for [ctl03]
2018-09-29 14:03:13,744 [salt.state       :290 ][INFO    ][2993] {'host': 'ctl03'}
2018-09-29 14:03:13,745 [salt.state       :1941][INFO    ][2993] Completed state [ctl03] at time 14:03:13.745002 duration_in_ms=1.593
2018-09-29 14:03:13,745 [salt.state       :1770][INFO    ][2993] Running state [ctl03.mcp-pike-odl-ha.local] at time 14:03:13.745337
2018-09-29 14:03:13,745 [salt.state       :1803][INFO    ][2993] Executing state host.present for [ctl03.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,748 [salt.state       :290 ][INFO    ][2993] {'host': 'ctl03.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,748 [salt.state       :1941][INFO    ][2993] Completed state [ctl03.mcp-pike-odl-ha.local] at time 14:03:13.748307 duration_in_ms=2.97
2018-09-29 14:03:13,748 [salt.state       :1770][INFO    ][2993] Running state [ctl01] at time 14:03:13.748683
2018-09-29 14:03:13,748 [salt.state       :1803][INFO    ][2993] Executing state host.present for [ctl01]
2018-09-29 14:03:13,750 [salt.state       :290 ][INFO    ][2993] {'host': 'ctl01'}
2018-09-29 14:03:13,750 [salt.state       :1941][INFO    ][2993] Completed state [ctl01] at time 14:03:13.750298 duration_in_ms=1.616
2018-09-29 14:03:13,750 [salt.state       :1770][INFO    ][2993] Running state [ctl01.mcp-pike-odl-ha.local] at time 14:03:13.750620
2018-09-29 14:03:13,750 [salt.state       :1803][INFO    ][2993] Executing state host.present for [ctl01.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,752 [salt.state       :290 ][INFO    ][2993] {'host': 'ctl01.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,753 [salt.state       :1941][INFO    ][2993] Completed state [ctl01.mcp-pike-odl-ha.local] at time 14:03:13.753153 duration_in_ms=2.533
2018-09-29 14:03:13,753 [salt.state       :1770][INFO    ][2993] Running state [ctl] at time 14:03:13.753475
2018-09-29 14:03:13,753 [salt.state       :1803][INFO    ][2993] Executing state host.present for [ctl]
2018-09-29 14:03:13,754 [salt.state       :290 ][INFO    ][2993] {'host': 'ctl'}
2018-09-29 14:03:13,755 [salt.state       :1941][INFO    ][2993] Completed state [ctl] at time 14:03:13.755014 duration_in_ms=1.539
2018-09-29 14:03:13,755 [salt.state       :1770][INFO    ][2993] Running state [ctl.mcp-pike-odl-ha.local] at time 14:03:13.755363
2018-09-29 14:03:13,755 [salt.state       :1803][INFO    ][2993] Executing state host.present for [ctl.mcp-pike-odl-ha.local]
2018-09-29 14:03:13,756 [salt.state       :290 ][INFO    ][2993] {'host': 'ctl.mcp-pike-odl-ha.local'}
2018-09-29 14:03:13,757 [salt.state       :1941][INFO    ][2993] Completed state [ctl.mcp-pike-odl-ha.local] at time 14:03:13.757003 duration_in_ms=1.64
2018-09-29 14:03:13,757 [salt.state       :1770][INFO    ][2993] Running state [/etc/network/interfaces.d/50-cloud-init.cfg] at time 14:03:13.757229
2018-09-29 14:03:13,757 [salt.state       :1803][INFO    ][2993] Executing state file.absent for [/etc/network/interfaces.d/50-cloud-init.cfg]
2018-09-29 14:03:13,757 [salt.state       :290 ][INFO    ][2993] {'removed': '/etc/network/interfaces.d/50-cloud-init.cfg'}
2018-09-29 14:03:13,758 [salt.state       :1941][INFO    ][2993] Completed state [/etc/network/interfaces.d/50-cloud-init.cfg] at time 14:03:13.758044 duration_in_ms=0.816
2018-09-29 14:03:13,758 [salt.state       :1770][INFO    ][2993] Running state [ens2] at time 14:03:13.758756
2018-09-29 14:03:13,759 [salt.state       :1803][INFO    ][2993] Executing state network.managed for [ens2]
2018-09-29 14:03:13,889 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['ifdown', 'ens2'] in directory '/root'
2018-09-29 14:03:14,717 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929140314703074
2018-09-29 14:03:14,731 [salt.minion      :1431][INFO    ][5170] Starting a new job with PID 5170
2018-09-29 14:03:14,751 [salt.minion      :1708][INFO    ][5170] Returning information for job: 20180929140314703074
2018-09-29 14:03:15,059 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['ifup', 'ens2'] in directory '/root'
2018-09-29 14:03:16,294 [salt.state       :290 ][INFO    ][2993] {'interface': 'Added network interface.', 'status': 'Interface ens2 restart to validate'}
2018-09-29 14:03:16,296 [salt.state       :1941][INFO    ][2993] Completed state [ens2] at time 14:03:16.295107 duration_in_ms=2536.35
2018-09-29 14:03:16,296 [salt.state       :1770][INFO    ][2993] Running state [ens3] at time 14:03:16.296872
2018-09-29 14:03:16,297 [salt.state       :1803][INFO    ][2993] Executing state network.managed for [ens3]
2018-09-29 14:03:16,329 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['ifup', 'ens3'] in directory '/root'
2018-09-29 14:03:17,045 [salt.state       :290 ][INFO    ][2993] {'interface': 'Added network interface.', 'status': 'Interface ens3 is up'}
2018-09-29 14:03:17,045 [salt.state       :1941][INFO    ][2993] Completed state [ens3] at time 14:03:17.045891 duration_in_ms=749.017
2018-09-29 14:03:17,046 [salt.state       :1770][INFO    ][2993] Running state [/etc/profile.d/proxy.sh] at time 14:03:17.046216
2018-09-29 14:03:17,046 [salt.state       :1803][INFO    ][2993] Executing state file.absent for [/etc/profile.d/proxy.sh]
2018-09-29 14:03:17,047 [salt.state       :290 ][INFO    ][2993] File /etc/profile.d/proxy.sh is not present
2018-09-29 14:03:17,047 [salt.state       :1941][INFO    ][2993] Completed state [/etc/profile.d/proxy.sh] at time 14:03:17.047208 duration_in_ms=0.992
2018-09-29 14:03:17,048 [salt.state       :1770][INFO    ][2993] Running state [/etc/apt/apt.conf.d/95proxies] at time 14:03:17.048048
2018-09-29 14:03:17,048 [salt.state       :1803][INFO    ][2993] Executing state file.absent for [/etc/apt/apt.conf.d/95proxies]
2018-09-29 14:03:17,048 [salt.state       :290 ][INFO    ][2993] File /etc/apt/apt.conf.d/95proxies is not present
2018-09-29 14:03:17,048 [salt.state       :1941][INFO    ][2993] Completed state [/etc/apt/apt.conf.d/95proxies] at time 14:03:17.048762 duration_in_ms=0.714
2018-09-29 14:03:17,050 [salt.state       :1770][INFO    ][2993] Running state [ntp] at time 14:03:17.050057
2018-09-29 14:03:17,050 [salt.state       :1803][INFO    ][2993] Executing state pkg.installed for [ntp]
2018-09-29 14:03:17,286 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-09-29 14:03:17,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] 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-09-29 14:03:20,743 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-09-29 14:03:20,782 [salt.state       :290 ][INFO    ][2993] 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-09-29 14:03:20,805 [salt.state       :905 ][INFO    ][2993] Loading fresh modules for state activity
2018-09-29 14:03:20,848 [salt.state       :1941][INFO    ][2993] Completed state [ntp] at time 14:03:20.848018 duration_in_ms=3797.96
2018-09-29 14:03:20,857 [salt.state       :1770][INFO    ][2993] Running state [/etc/ntp.conf] at time 14:03:20.857543
2018-09-29 14:03:20,858 [salt.state       :1803][INFO    ][2993] Executing state file.managed for [/etc/ntp.conf]
2018-09-29 14:03:20,888 [salt.fileclient  :1215][INFO    ][2993] Fetching file from saltenv 'base', ** done ** 'ntp/files/ntp.conf'
2018-09-29 14:03:20,975 [salt.state       :290 ][INFO    ][2993] 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-09-29 14:03:20,976 [salt.state       :1941][INFO    ][2993] Completed state [/etc/ntp.conf] at time 14:03:20.976524 duration_in_ms=118.981
2018-09-29 14:03:21,496 [salt.state       :1770][INFO    ][2993] Running state [ntp] at time 14:03:21.496296
2018-09-29 14:03:21,496 [salt.state       :1803][INFO    ][2993] Executing state service.running for [ntp]
2018-09-29 14:03:21,497 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['systemctl', 'status', 'ntp.service', '-n', '0'] in directory '/root'
2018-09-29 14:03:21,520 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['systemctl', 'is-active', 'ntp.service'] in directory '/root'
2018-09-29 14:03:21,533 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['systemctl', 'is-enabled', 'ntp.service'] in directory '/root'
2018-09-29 14:03:21,548 [salt.state       :290 ][INFO    ][2993] The service ntp is already running
2018-09-29 14:03:21,549 [salt.state       :1941][INFO    ][2993] Completed state [ntp] at time 14:03:21.548963 duration_in_ms=52.668
2018-09-29 14:03:21,549 [salt.state       :1770][INFO    ][2993] Running state [ntp] at time 14:03:21.549261
2018-09-29 14:03:21,549 [salt.state       :1803][INFO    ][2993] Executing state service.mod_watch for [ntp]
2018-09-29 14:03:21,550 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['systemctl', 'is-active', 'ntp.service'] in directory '/root'
2018-09-29 14:03:21,563 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2993] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'ntp.service'] in directory '/root'
2018-09-29 14:03:21,637 [salt.state       :290 ][INFO    ][2993] {'ntp': True}
2018-09-29 14:03:21,637 [salt.state       :1941][INFO    ][2993] Completed state [ntp] at time 14:03:21.637727 duration_in_ms=88.465
2018-09-29 14:03:21,643 [salt.minion      :1708][INFO    ][2993] Returning information for job: 20180929140244470320
2018-09-29 14:03:42,506 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command state.apply with jid 20180929140342487606
2018-09-29 14:03:42,534 [salt.minion      :1431][INFO    ][6387] Starting a new job with PID 6387
2018-09-29 14:03:47,138 [salt.state       :905 ][INFO    ][6387] Loading fresh modules for state activity
2018-09-29 14:03:51,522 [salt.state       :1770][INFO    ][6387] Running state [/etc/environment] at time 14:03:51.522788
2018-09-29 14:03:51,523 [salt.state       :1803][INFO    ][6387] Executing state file.blockreplace for [/etc/environment]
2018-09-29 14:03:51,528 [salt.state       :290 ][INFO    ][6387] No changes needed to be made
2018-09-29 14:03:51,529 [salt.state       :1941][INFO    ][6387] Completed state [/etc/environment] at time 14:03:51.529024 duration_in_ms=6.235
2018-09-29 14:03:51,529 [salt.state       :1770][INFO    ][6387] Running state [/etc/profile.d] at time 14:03:51.529270
2018-09-29 14:03:51,529 [salt.state       :1803][INFO    ][6387] Executing state file.directory for [/etc/profile.d]
2018-09-29 14:03:51,531 [salt.state       :290 ][INFO    ][6387] Directory /etc/profile.d is in the correct state
Directory /etc/profile.d updated
2018-09-29 14:03:51,531 [salt.state       :1941][INFO    ][6387] Completed state [/etc/profile.d] at time 14:03:51.531195 duration_in_ms=1.926
2018-09-29 14:03:51,532 [salt.state       :1770][INFO    ][6387] Running state [fake_linux_system_at] at time 14:03:51.532183
2018-09-29 14:03:51,532 [salt.state       :1803][INFO    ][6387] Executing state test.nop for [fake_linux_system_at]
2018-09-29 14:03:51,532 [salt.state       :290 ][INFO    ][6387] Success!
2018-09-29 14:03:51,532 [salt.state       :1941][INFO    ][6387] Completed state [fake_linux_system_at] at time 14:03:51.532866 duration_in_ms=0.682
2018-09-29 14:03:51,533 [salt.state       :1770][INFO    ][6387] Running state [fake_linux_system_cron] at time 14:03:51.533081
2018-09-29 14:03:51,533 [salt.state       :1803][INFO    ][6387] Executing state test.nop for [fake_linux_system_cron]
2018-09-29 14:03:51,533 [salt.state       :290 ][INFO    ][6387] Success!
2018-09-29 14:03:51,533 [salt.state       :1941][INFO    ][6387] Completed state [fake_linux_system_cron] at time 14:03:51.533703 duration_in_ms=0.623
2018-09-29 14:03:52,164 [salt.state       :1770][INFO    ][6387] Running state [/etc/apt/apt.conf.d/99prefer_ipv4-salt] at time 14:03:52.164203
2018-09-29 14:03:52,164 [salt.state       :1803][INFO    ][6387] Executing state file.managed for [/etc/apt/apt.conf.d/99prefer_ipv4-salt]
2018-09-29 14:03:52,199 [salt.state       :290 ][INFO    ][6387] File /etc/apt/apt.conf.d/99prefer_ipv4-salt is in the correct state
2018-09-29 14:03:52,199 [salt.state       :1941][INFO    ][6387] Completed state [/etc/apt/apt.conf.d/99prefer_ipv4-salt] at time 14:03:52.199392 duration_in_ms=35.19
2018-09-29 14:03:52,199 [salt.state       :1770][INFO    ][6387] Running state [/etc/apt/apt.conf.d/99allow_downgrades-salt] at time 14:03:52.199661
2018-09-29 14:03:52,199 [salt.state       :1803][INFO    ][6387] Executing state file.managed for [/etc/apt/apt.conf.d/99allow_downgrades-salt]
2018-09-29 14:03:52,232 [salt.state       :290 ][INFO    ][6387] File /etc/apt/apt.conf.d/99allow_downgrades-salt is in the correct state
2018-09-29 14:03:52,232 [salt.state       :1941][INFO    ][6387] Completed state [/etc/apt/apt.conf.d/99allow_downgrades-salt] at time 14:03:52.232358 duration_in_ms=32.697
2018-09-29 14:03:52,233 [salt.state       :1770][INFO    ][6387] Running state [linux_repo_prereq_pkgs] at time 14:03:52.233575
2018-09-29 14:03:52,233 [salt.state       :1803][INFO    ][6387] Executing state pkg.installed for [linux_repo_prereq_pkgs]
2018-09-29 14:03:52,234 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-09-29 14:03:52,631 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929140352611518
2018-09-29 14:03:52,633 [salt.state       :290 ][INFO    ][6387] All specified packages are already installed
2018-09-29 14:03:52,634 [salt.state       :1941][INFO    ][6387] Completed state [linux_repo_prereq_pkgs] at time 14:03:52.634290 duration_in_ms=400.714
2018-09-29 14:03:52,634 [salt.state       :1770][INFO    ][6387] Running state [/etc/apt/apt.conf.d/99proxies-salt] at time 14:03:52.634669
2018-09-29 14:03:52,635 [salt.state       :1803][INFO    ][6387] Executing state file.managed for [/etc/apt/apt.conf.d/99proxies-salt]
2018-09-29 14:03:52,653 [salt.minion      :1431][INFO    ][6472] Starting a new job with PID 6472
2018-09-29 14:03:52,670 [salt.state       :290 ][INFO    ][6387] File /etc/apt/apt.conf.d/99proxies-salt is in the correct state
2018-09-29 14:03:52,670 [salt.state       :1941][INFO    ][6387] Completed state [/etc/apt/apt.conf.d/99proxies-salt] at time 14:03:52.670647 duration_in_ms=35.978
2018-09-29 14:03:52,670 [salt.state       :1770][INFO    ][6387] Running state [/etc/apt/apt.conf.d/99proxies-salt-mk_openstack] at time 14:03:52.670911
2018-09-29 14:03:52,671 [salt.state       :1803][INFO    ][6387] Executing state file.absent for [/etc/apt/apt.conf.d/99proxies-salt-mk_openstack]
2018-09-29 14:03:52,671 [salt.state       :290 ][INFO    ][6387] File /etc/apt/apt.conf.d/99proxies-salt-mk_openstack is not present
2018-09-29 14:03:52,671 [salt.state       :1941][INFO    ][6387] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mk_openstack] at time 14:03:52.671703 duration_in_ms=0.792
2018-09-29 14:03:52,671 [salt.state       :1770][INFO    ][6387] Running state [/etc/apt/preferences.d/mk_openstack] at time 14:03:52.671919
2018-09-29 14:03:52,672 [salt.state       :1803][INFO    ][6387] Executing state file.managed for [/etc/apt/preferences.d/mk_openstack]
2018-09-29 14:03:52,673 [salt.minion      :1708][INFO    ][6472] Returning information for job: 20180929140352611518
2018-09-29 14:03:52,835 [salt.state       :290 ][INFO    ][6387] File /etc/apt/preferences.d/mk_openstack is in the correct state
2018-09-29 14:03:52,835 [salt.state       :1941][INFO    ][6387] Completed state [/etc/apt/preferences.d/mk_openstack] at time 14:03:52.835362 duration_in_ms=163.442
2018-09-29 14:03:52,841 [salt.state       :1770][INFO    ][6387] Running state [deb [arch=amd64] http://apt.mirantis.com/xenial/ nightly pike] at time 14:03:52.841294
2018-09-29 14:03:52,841 [salt.state       :1803][INFO    ][6387] Executing state pkgrepo.managed for [deb [arch=amd64] http://apt.mirantis.com/xenial/ nightly pike]
2018-09-29 14:03:52,893 [salt.state       :290 ][INFO    ][6387] Package repo 'deb [arch=amd64] http://apt.mirantis.com/xenial/ nightly pike' already configured
2018-09-29 14:03:52,894 [salt.state       :1941][INFO    ][6387] Completed state [deb [arch=amd64] http://apt.mirantis.com/xenial/ nightly pike] at time 14:03:52.894006 duration_in_ms=52.713
2018-09-29 14:03:52,894 [salt.state       :1770][INFO    ][6387] Running state [/etc/apt/apt.conf.d/99proxies-salt-uca-staging] at time 14:03:52.894238
2018-09-29 14:03:52,894 [salt.state       :1803][INFO    ][6387] Executing state file.absent for [/etc/apt/apt.conf.d/99proxies-salt-uca-staging]
2018-09-29 14:03:52,894 [salt.state       :290 ][INFO    ][6387] File /etc/apt/apt.conf.d/99proxies-salt-uca-staging is not present
2018-09-29 14:03:52,894 [salt.state       :1941][INFO    ][6387] Completed state [/etc/apt/apt.conf.d/99proxies-salt-uca-staging] at time 14:03:52.894931 duration_in_ms=0.694
2018-09-29 14:03:52,895 [salt.state       :1770][INFO    ][6387] Running state [/etc/apt/preferences.d/uca-staging] at time 14:03:52.895135
2018-09-29 14:03:52,895 [salt.state       :1803][INFO    ][6387] Executing state file.absent for [/etc/apt/preferences.d/uca-staging]
2018-09-29 14:03:52,895 [salt.state       :290 ][INFO    ][6387] File /etc/apt/preferences.d/uca-staging is not present
2018-09-29 14:03:52,895 [salt.state       :1941][INFO    ][6387] Completed state [/etc/apt/preferences.d/uca-staging] at time 14:03:52.895794 duration_in_ms=0.658
2018-09-29 14:03:52,903 [salt.state       :1770][INFO    ][6387] Running state [apt-key adv --keyserver keyserver.ubuntu.com --recv 9F68104E] at time 14:03:52.903405
2018-09-29 14:03:52,903 [salt.state       :1803][INFO    ][6387] Executing state cmd.run for [apt-key adv --keyserver keyserver.ubuntu.com --recv 9F68104E]
2018-09-29 14:03:52,904 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'test -e /etc/apt/sources.list.d/uca-staging.list' in directory '/root'
2018-09-29 14:03:52,920 [salt.state       :290 ][INFO    ][6387] unless execution succeeded
2018-09-29 14:03:52,921 [salt.state       :1941][INFO    ][6387] Completed state [apt-key adv --keyserver keyserver.ubuntu.com --recv 9F68104E] at time 14:03:52.921053 duration_in_ms=17.648
2018-09-29 14:03:52,925 [salt.state       :1770][INFO    ][6387] Running state [deb http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial main] at time 14:03:52.925574
2018-09-29 14:03:52,925 [salt.state       :1803][INFO    ][6387] Executing state pkgrepo.managed for [deb http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial main]
2018-09-29 14:03:52,968 [salt.state       :290 ][INFO    ][6387] Package repo 'deb http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial main' already configured
2018-09-29 14:03:52,968 [salt.state       :1941][INFO    ][6387] Completed state [deb http://ppa.launchpad.net/ubuntu-cloud-archive/pike-staging/ubuntu xenial main] at time 14:03:52.968769 duration_in_ms=43.194
2018-09-29 14:03:52,969 [salt.state       :1770][INFO    ][6387] Running state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack] at time 14:03:52.969042
2018-09-29 14:03:52,969 [salt.state       :1803][INFO    ][6387] Executing state file.absent for [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack]
2018-09-29 14:03:52,969 [salt.state       :290 ][INFO    ][6387] File /etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack is not present
2018-09-29 14:03:52,969 [salt.state       :1941][INFO    ][6387] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack] at time 14:03:52.969904 duration_in_ms=0.862
2018-09-29 14:03:52,970 [salt.state       :1770][INFO    ][6387] Running state [/etc/apt/preferences.d/mirantis_openstack] at time 14:03:52.970113
2018-09-29 14:03:52,970 [salt.state       :1803][INFO    ][6387] Executing state file.managed for [/etc/apt/preferences.d/mirantis_openstack]
2018-09-29 14:03:53,123 [salt.state       :290 ][INFO    ][6387] File /etc/apt/preferences.d/mirantis_openstack is in the correct state
2018-09-29 14:03:53,123 [salt.state       :1941][INFO    ][6387] Completed state [/etc/apt/preferences.d/mirantis_openstack] at time 14:03:53.123423 duration_in_ms=153.31
2018-09-29 14:03:53,125 [salt.state       :1770][INFO    ][6387] Running state [deb http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike main] at time 14:03:53.125585
2018-09-29 14:03:53,125 [salt.state       :1803][INFO    ][6387] Executing state pkgrepo.managed for [deb http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike main]
2018-09-29 14:03:53,281 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] 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-09-29 14:03:53,460 [salt.state       :290 ][INFO    ][6387] Configured package repo 'deb http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike main'
2018-09-29 14:03:53,461 [salt.state       :1941][INFO    ][6387] Completed state [deb http://mirror.fuel-infra.org/mcp-repos/pike/xenial pike main] at time 14:03:53.460965 duration_in_ms=335.379
2018-09-29 14:03:53,461 [salt.state       :1770][INFO    ][6387] Running state [/etc/apt/apt.conf.d/99proxies-salt-uca] at time 14:03:53.461417
2018-09-29 14:03:53,461 [salt.state       :1803][INFO    ][6387] Executing state file.absent for [/etc/apt/apt.conf.d/99proxies-salt-uca]
2018-09-29 14:03:53,462 [salt.state       :290 ][INFO    ][6387] File /etc/apt/apt.conf.d/99proxies-salt-uca is not present
2018-09-29 14:03:53,462 [salt.state       :1941][INFO    ][6387] Completed state [/etc/apt/apt.conf.d/99proxies-salt-uca] at time 14:03:53.462877 duration_in_ms=1.46
2018-09-29 14:03:53,463 [salt.state       :1770][INFO    ][6387] Running state [/etc/apt/preferences.d/uca] at time 14:03:53.463098
2018-09-29 14:03:53,463 [salt.state       :1803][INFO    ][6387] Executing state file.managed for [/etc/apt/preferences.d/uca]
2018-09-29 14:03:53,589 [salt.state       :290 ][INFO    ][6387] File /etc/apt/preferences.d/uca is in the correct state
2018-09-29 14:03:53,590 [salt.state       :1941][INFO    ][6387] Completed state [/etc/apt/preferences.d/uca] at time 14:03:53.590107 duration_in_ms=127.008
2018-09-29 14:03:53,594 [salt.state       :1770][INFO    ][6387] Running state [apt-key adv --keyserver keyserver.ubuntu.com --recv EC4926EA] at time 14:03:53.594805
2018-09-29 14:03:53,595 [salt.state       :1803][INFO    ][6387] Executing state cmd.run for [apt-key adv --keyserver keyserver.ubuntu.com --recv EC4926EA]
2018-09-29 14:03:53,595 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'test -e /etc/apt/sources.list.d/uca.list' in directory '/root'
2018-09-29 14:03:53,611 [salt.state       :290 ][INFO    ][6387] unless execution succeeded
2018-09-29 14:03:53,611 [salt.state       :1941][INFO    ][6387] Completed state [apt-key adv --keyserver keyserver.ubuntu.com --recv EC4926EA] at time 14:03:53.611493 duration_in_ms=16.688
2018-09-29 14:03:53,614 [salt.state       :1770][INFO    ][6387] Running state [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main] at time 14:03:53.614749
2018-09-29 14:03:53,615 [salt.state       :1803][INFO    ][6387] Executing state pkgrepo.managed for [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main]
2018-09-29 14:03:53,716 [salt.state       :290 ][INFO    ][6387] Package repo 'deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main' already configured
2018-09-29 14:03:53,717 [salt.state       :1941][INFO    ][6387] Completed state [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main] at time 14:03:53.717061 duration_in_ms=102.311
2018-09-29 14:03:53,718 [salt.state       :1770][INFO    ][6387] Running state [pkg.refresh_db] at time 14:03:53.718027
2018-09-29 14:03:53,718 [salt.state       :1803][INFO    ][6387] Executing state module.run for [pkg.refresh_db]
2018-09-29 14:03:53,718 [salt.utils.decorators:613 ][WARNING ][6387] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-29 14:03:53,719 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-09-29 14:03:57,288 [salt.state       :290 ][INFO    ][6387] {'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-09-29 14:03:57,288 [salt.state       :1941][INFO    ][6387] Completed state [pkg.refresh_db] at time 14:03:57.288812 duration_in_ms=3570.785
2018-09-29 14:03:57,289 [salt.state       :1770][INFO    ][6387] Running state [UTC] at time 14:03:57.289768
2018-09-29 14:03:57,290 [salt.state       :1803][INFO    ][6387] Executing state timezone.system for [UTC]
2018-09-29 14:03:57,291 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command ['timedatectl'] in directory '/root'
2018-09-29 14:03:57,325 [salt.state       :290 ][INFO    ][6387] Timezone UTC already set, UTC already set to UTC
2018-09-29 14:03:57,326 [salt.state       :1941][INFO    ][6387] Completed state [UTC] at time 14:03:57.326251 duration_in_ms=36.482
2018-09-29 14:03:57,327 [salt.state       :1770][INFO    ][6387] Running state [/etc/default/grub.d] at time 14:03:57.327283
2018-09-29 14:03:57,328 [salt.state       :1803][INFO    ][6387] Executing state file.directory for [/etc/default/grub.d]
2018-09-29 14:03:57,330 [salt.state       :290 ][INFO    ][6387] Directory /etc/default/grub.d is in the correct state
Directory /etc/default/grub.d updated
2018-09-29 14:03:57,331 [salt.state       :1941][INFO    ][6387] Completed state [/etc/default/grub.d] at time 14:03:57.331151 duration_in_ms=3.867
2018-09-29 14:03:57,331 [salt.state       :1770][INFO    ][6387] Running state [update-grub] at time 14:03:57.331773
2018-09-29 14:03:57,332 [salt.state       :1803][INFO    ][6387] Executing state cmd.wait for [update-grub]
2018-09-29 14:03:57,332 [salt.state       :290 ][INFO    ][6387] No changes made for update-grub
2018-09-29 14:03:57,333 [salt.state       :1941][INFO    ][6387] Completed state [update-grub] at time 14:03:57.333475 duration_in_ms=1.702
2018-09-29 14:03:57,336 [salt.state       :1770][INFO    ][6387] Running state [/boot/grub/grub.cfg] at time 14:03:57.335963
2018-09-29 14:03:57,336 [salt.state       :1803][INFO    ][6387] Executing state file.managed for [/boot/grub/grub.cfg]
2018-09-29 14:03:57,425 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command ['git', '--version'] in directory '/root'
2018-09-29 14:03:57,585 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'test -f /boot/grub/grub.cfg' in directory '/root'
2018-09-29 14:03:57,600 [salt.loaded.int.states.file:2150][WARNING ][6387] 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-09-29 14:03:57,601 [salt.state       :290 ][INFO    ][6387] File /boot/grub/grub.cfg exists with proper permissions. No changes made.
2018-09-29 14:03:57,602 [salt.state       :1941][INFO    ][6387] Completed state [/boot/grub/grub.cfg] at time 14:03:57.602434 duration_in_ms=266.466
2018-09-29 14:03:57,603 [salt.state       :1770][INFO    ][6387] Running state [nf_conntrack] at time 14:03:57.603360
2018-09-29 14:03:57,605 [salt.state       :1803][INFO    ][6387] Executing state kmod.present for [nf_conntrack]
2018-09-29 14:03:57,606 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'lsmod' in directory '/root'
2018-09-29 14:03:57,622 [salt.state       :290 ][INFO    ][6387] Kernel module nf_conntrack is already present
2018-09-29 14:03:57,622 [salt.state       :1941][INFO    ][6387] Completed state [nf_conntrack] at time 14:03:57.622509 duration_in_ms=19.15
2018-09-29 14:03:57,623 [salt.state       :1770][INFO    ][6387] Running state [kernel.panic] at time 14:03:57.622997
2018-09-29 14:03:57,623 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [kernel.panic]
2018-09-29 14:03:57,639 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n kernel.panic' in directory '/root'
2018-09-29 14:03:57,653 [salt.state       :290 ][INFO    ][6387] Sysctl value kernel.panic = 60 is already set
2018-09-29 14:03:57,653 [salt.state       :1941][INFO    ][6387] Completed state [kernel.panic] at time 14:03:57.653832 duration_in_ms=30.835
2018-09-29 14:03:57,654 [salt.state       :1770][INFO    ][6387] Running state [net.ipv4.tcp_keepalive_probes] at time 14:03:57.654538
2018-09-29 14:03:57,655 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [net.ipv4.tcp_keepalive_probes]
2018-09-29 14:03:57,656 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n net.ipv4.tcp_keepalive_probes' in directory '/root'
2018-09-29 14:03:57,670 [salt.state       :290 ][INFO    ][6387] Sysctl value net.ipv4.tcp_keepalive_probes = 8 is already set
2018-09-29 14:03:57,671 [salt.state       :1941][INFO    ][6387] Completed state [net.ipv4.tcp_keepalive_probes] at time 14:03:57.671031 duration_in_ms=16.493
2018-09-29 14:03:57,671 [salt.state       :1770][INFO    ][6387] Running state [fs.file-max] at time 14:03:57.671732
2018-09-29 14:03:57,673 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [fs.file-max]
2018-09-29 14:03:57,674 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n fs.file-max' in directory '/root'
2018-09-29 14:03:57,689 [salt.state       :290 ][INFO    ][6387] Sysctl value fs.file-max = 124165 is already set
2018-09-29 14:03:57,690 [salt.state       :1941][INFO    ][6387] Completed state [fs.file-max] at time 14:03:57.690227 duration_in_ms=18.495
2018-09-29 14:03:57,691 [salt.state       :1770][INFO    ][6387] Running state [net.core.somaxconn] at time 14:03:57.691246
2018-09-29 14:03:57,692 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [net.core.somaxconn]
2018-09-29 14:03:57,694 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n net.core.somaxconn' in directory '/root'
2018-09-29 14:03:57,711 [salt.state       :290 ][INFO    ][6387] Sysctl value net.core.somaxconn = 4096 is already set
2018-09-29 14:03:57,712 [salt.state       :1941][INFO    ][6387] Completed state [net.core.somaxconn] at time 14:03:57.712567 duration_in_ms=21.322
2018-09-29 14:03:57,713 [salt.state       :1770][INFO    ][6387] Running state [net.ipv4.tcp_max_syn_backlog] at time 14:03:57.713492
2018-09-29 14:03:57,714 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [net.ipv4.tcp_max_syn_backlog]
2018-09-29 14:03:57,716 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n net.ipv4.tcp_max_syn_backlog' in directory '/root'
2018-09-29 14:03:57,730 [salt.state       :290 ][INFO    ][6387] Sysctl value net.ipv4.tcp_max_syn_backlog = 8192 is already set
2018-09-29 14:03:57,731 [salt.state       :1941][INFO    ][6387] Completed state [net.ipv4.tcp_max_syn_backlog] at time 14:03:57.731424 duration_in_ms=17.933
2018-09-29 14:03:57,732 [salt.state       :1770][INFO    ][6387] Running state [net.ipv4.tcp_tw_reuse] at time 14:03:57.732578
2018-09-29 14:03:57,733 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [net.ipv4.tcp_tw_reuse]
2018-09-29 14:03:57,734 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n net.ipv4.tcp_tw_reuse' in directory '/root'
2018-09-29 14:03:57,749 [salt.state       :290 ][INFO    ][6387] Sysctl value net.ipv4.tcp_tw_reuse = 1 is already set
2018-09-29 14:03:57,750 [salt.state       :1941][INFO    ][6387] Completed state [net.ipv4.tcp_tw_reuse] at time 14:03:57.750496 duration_in_ms=17.918
2018-09-29 14:03:57,751 [salt.state       :1770][INFO    ][6387] Running state [net.ipv4.tcp_congestion_control] at time 14:03:57.751509
2018-09-29 14:03:57,752 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [net.ipv4.tcp_congestion_control]
2018-09-29 14:03:57,754 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n net.ipv4.tcp_congestion_control' in directory '/root'
2018-09-29 14:03:57,771 [salt.state       :290 ][INFO    ][6387] Sysctl value net.ipv4.tcp_congestion_control = yeah is already set
2018-09-29 14:03:57,773 [salt.state       :1941][INFO    ][6387] Completed state [net.ipv4.tcp_congestion_control] at time 14:03:57.773061 duration_in_ms=21.552
2018-09-29 14:03:57,774 [salt.state       :1770][INFO    ][6387] Running state [net.ipv4.tcp_retries2] at time 14:03:57.774202
2018-09-29 14:03:57,775 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [net.ipv4.tcp_retries2]
2018-09-29 14:03:57,777 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n net.ipv4.tcp_retries2' in directory '/root'
2018-09-29 14:03:57,796 [salt.state       :290 ][INFO    ][6387] Sysctl value net.ipv4.tcp_retries2 = 5 is already set
2018-09-29 14:03:57,797 [salt.state       :1941][INFO    ][6387] Completed state [net.ipv4.tcp_retries2] at time 14:03:57.797773 duration_in_ms=23.57
2018-09-29 14:03:57,798 [salt.state       :1770][INFO    ][6387] Running state [net.core.netdev_max_backlog] at time 14:03:57.798673
2018-09-29 14:03:57,799 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [net.core.netdev_max_backlog]
2018-09-29 14:03:57,801 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n net.core.netdev_max_backlog' in directory '/root'
2018-09-29 14:03:57,815 [salt.state       :290 ][INFO    ][6387] Sysctl value net.core.netdev_max_backlog = 261144 is already set
2018-09-29 14:03:57,815 [salt.state       :1941][INFO    ][6387] Completed state [net.core.netdev_max_backlog] at time 14:03:57.815780 duration_in_ms=17.107
2018-09-29 14:03:57,816 [salt.state       :1770][INFO    ][6387] Running state [net.ipv4.tcp_slow_start_after_idle] at time 14:03:57.816815
2018-09-29 14:03:57,817 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [net.ipv4.tcp_slow_start_after_idle]
2018-09-29 14:03:57,818 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n net.ipv4.tcp_slow_start_after_idle' in directory '/root'
2018-09-29 14:03:57,833 [salt.state       :290 ][INFO    ][6387] Sysctl value net.ipv4.tcp_slow_start_after_idle = 0 is already set
2018-09-29 14:03:57,834 [salt.state       :1941][INFO    ][6387] Completed state [net.ipv4.tcp_slow_start_after_idle] at time 14:03:57.833980 duration_in_ms=17.164
2018-09-29 14:03:57,835 [salt.state       :1770][INFO    ][6387] Running state [vm.swappiness] at time 14:03:57.834954
2018-09-29 14:03:57,835 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [vm.swappiness]
2018-09-29 14:03:57,837 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n vm.swappiness' in directory '/root'
2018-09-29 14:03:57,854 [salt.state       :290 ][INFO    ][6387] Sysctl value vm.swappiness = 10 is already set
2018-09-29 14:03:57,855 [salt.state       :1941][INFO    ][6387] Completed state [vm.swappiness] at time 14:03:57.855281 duration_in_ms=20.327
2018-09-29 14:03:57,856 [salt.state       :1770][INFO    ][6387] Running state [net.ipv4.tcp_keepalive_intvl] at time 14:03:57.856856
2018-09-29 14:03:57,857 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [net.ipv4.tcp_keepalive_intvl]
2018-09-29 14:03:57,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n net.ipv4.tcp_keepalive_intvl' in directory '/root'
2018-09-29 14:03:57,874 [salt.state       :290 ][INFO    ][6387] Sysctl value net.ipv4.tcp_keepalive_intvl = 3 is already set
2018-09-29 14:03:57,875 [salt.state       :1941][INFO    ][6387] Completed state [net.ipv4.tcp_keepalive_intvl] at time 14:03:57.875138 duration_in_ms=18.282
2018-09-29 14:03:57,875 [salt.state       :1770][INFO    ][6387] Running state [net.ipv4.neigh.default.gc_thresh1] at time 14:03:57.875926
2018-09-29 14:03:57,877 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [net.ipv4.neigh.default.gc_thresh1]
2018-09-29 14:03:57,878 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n net.ipv4.neigh.default.gc_thresh1' in directory '/root'
2018-09-29 14:03:57,893 [salt.state       :290 ][INFO    ][6387] Sysctl value net.ipv4.neigh.default.gc_thresh1 = 4096 is already set
2018-09-29 14:03:57,894 [salt.state       :1941][INFO    ][6387] Completed state [net.ipv4.neigh.default.gc_thresh1] at time 14:03:57.894020 duration_in_ms=18.093
2018-09-29 14:03:57,894 [salt.state       :1770][INFO    ][6387] Running state [net.ipv4.neigh.default.gc_thresh2] at time 14:03:57.894585
2018-09-29 14:03:57,895 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [net.ipv4.neigh.default.gc_thresh2]
2018-09-29 14:03:57,896 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n net.ipv4.neigh.default.gc_thresh2' in directory '/root'
2018-09-29 14:03:57,907 [salt.state       :290 ][INFO    ][6387] Sysctl value net.ipv4.neigh.default.gc_thresh2 = 8192 is already set
2018-09-29 14:03:57,908 [salt.state       :1941][INFO    ][6387] Completed state [net.ipv4.neigh.default.gc_thresh2] at time 14:03:57.907975 duration_in_ms=13.39
2018-09-29 14:03:57,909 [salt.state       :1770][INFO    ][6387] Running state [net.ipv4.neigh.default.gc_thresh3] at time 14:03:57.909465
2018-09-29 14:03:57,910 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [net.ipv4.neigh.default.gc_thresh3]
2018-09-29 14:03:57,911 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n net.ipv4.neigh.default.gc_thresh3' in directory '/root'
2018-09-29 14:03:57,923 [salt.state       :290 ][INFO    ][6387] Sysctl value net.ipv4.neigh.default.gc_thresh3 = 16384 is already set
2018-09-29 14:03:57,925 [salt.state       :1941][INFO    ][6387] Completed state [net.ipv4.neigh.default.gc_thresh3] at time 14:03:57.925156 duration_in_ms=15.691
2018-09-29 14:03:57,925 [salt.state       :1770][INFO    ][6387] Running state [net.ipv4.tcp_fin_timeout] at time 14:03:57.925889
2018-09-29 14:03:57,926 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [net.ipv4.tcp_fin_timeout]
2018-09-29 14:03:57,927 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n net.ipv4.tcp_fin_timeout' in directory '/root'
2018-09-29 14:03:57,942 [salt.state       :290 ][INFO    ][6387] Sysctl value net.ipv4.tcp_fin_timeout = 30 is already set
2018-09-29 14:03:57,943 [salt.state       :1941][INFO    ][6387] Completed state [net.ipv4.tcp_fin_timeout] at time 14:03:57.942959 duration_in_ms=17.07
2018-09-29 14:03:57,943 [salt.state       :1770][INFO    ][6387] Running state [net.ipv4.tcp_keepalive_time] at time 14:03:57.943612
2018-09-29 14:03:57,944 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [net.ipv4.tcp_keepalive_time]
2018-09-29 14:03:57,945 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n net.ipv4.tcp_keepalive_time' in directory '/root'
2018-09-29 14:03:57,957 [salt.state       :290 ][INFO    ][6387] Sysctl value net.ipv4.tcp_keepalive_time = 30 is already set
2018-09-29 14:03:57,958 [salt.state       :1941][INFO    ][6387] Completed state [net.ipv4.tcp_keepalive_time] at time 14:03:57.958116 duration_in_ms=14.504
2018-09-29 14:03:57,958 [salt.state       :1770][INFO    ][6387] Running state [net.nf_conntrack_max] at time 14:03:57.958667
2018-09-29 14:03:57,959 [salt.state       :1803][INFO    ][6387] Executing state sysctl.present for [net.nf_conntrack_max]
2018-09-29 14:03:57,960 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'sysctl -n net.nf_conntrack_max' in directory '/root'
2018-09-29 14:03:57,971 [salt.state       :290 ][INFO    ][6387] Sysctl value net.nf_conntrack_max = 1048576 is already set
2018-09-29 14:03:57,972 [salt.state       :1941][INFO    ][6387] Completed state [net.nf_conntrack_max] at time 14:03:57.971957 duration_in_ms=13.291
2018-09-29 14:03:57,972 [salt.state       :1770][INFO    ][6387] Running state [linux_sysfs_package] at time 14:03:57.972921
2018-09-29 14:03:57,973 [salt.state       :1803][INFO    ][6387] Executing state pkg.installed for [linux_sysfs_package]
2018-09-29 14:03:57,982 [salt.state       :290 ][INFO    ][6387] All specified packages are already installed
2018-09-29 14:03:57,983 [salt.state       :1941][INFO    ][6387] Completed state [linux_sysfs_package] at time 14:03:57.983027 duration_in_ms=10.106
2018-09-29 14:03:57,985 [salt.state       :1770][INFO    ][6387] Running state [/etc/sysfs.d] at time 14:03:57.985404
2018-09-29 14:03:57,985 [salt.state       :1803][INFO    ][6387] Executing state file.directory for [/etc/sysfs.d]
2018-09-29 14:03:57,986 [salt.state       :290 ][INFO    ][6387] Directory /etc/sysfs.d is in the correct state
Directory /etc/sysfs.d updated
2018-09-29 14:03:57,987 [salt.state       :1941][INFO    ][6387] Completed state [/etc/sysfs.d] at time 14:03:57.987221 duration_in_ms=1.817
2018-09-29 14:03:57,987 [salt.state       :1770][INFO    ][6387] Running state [ondemand] at time 14:03:57.987664
2018-09-29 14:03:57,988 [salt.state       :1803][INFO    ][6387] Executing state service.dead for [ondemand]
2018-09-29 14:03:57,989 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command ['systemctl', 'status', 'ondemand.service', '-n', '0'] in directory '/root'
2018-09-29 14:03:58,003 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command ['systemctl', 'is-active', 'ondemand.service'] in directory '/root'
2018-09-29 14:03:58,017 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-09-29 14:03:58,038 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'runlevel' in directory '/root'
2018-09-29 14:03:58,053 [salt.state       :290 ][INFO    ][6387] The service ondemand is already dead
2018-09-29 14:03:58,053 [salt.state       :1941][INFO    ][6387] Completed state [ondemand] at time 14:03:58.053544 duration_in_ms=65.879
2018-09-29 14:03:58,054 [salt.state       :1770][INFO    ][6387] Running state [cs_CZ.UTF-8] at time 14:03:58.053954
2018-09-29 14:03:58,054 [salt.state       :1803][INFO    ][6387] Executing state locale.present for [cs_CZ.UTF-8]
2018-09-29 14:03:58,055 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'locale -a' in directory '/root'
2018-09-29 14:03:58,069 [salt.state       :290 ][INFO    ][6387] Locale cs_CZ.UTF-8 is already present
2018-09-29 14:03:58,069 [salt.state       :1941][INFO    ][6387] Completed state [cs_CZ.UTF-8] at time 14:03:58.069862 duration_in_ms=15.908
2018-09-29 14:03:58,070 [salt.state       :1770][INFO    ][6387] Running state [en_US.UTF-8] at time 14:03:58.070438
2018-09-29 14:03:58,070 [salt.state       :1803][INFO    ][6387] Executing state locale.present for [en_US.UTF-8]
2018-09-29 14:03:58,072 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'locale -a' in directory '/root'
2018-09-29 14:03:58,083 [salt.state       :290 ][INFO    ][6387] Locale en_US.UTF-8 is already present
2018-09-29 14:03:58,083 [salt.state       :1941][INFO    ][6387] Completed state [en_US.UTF-8] at time 14:03:58.083470 duration_in_ms=13.033
2018-09-29 14:03:58,085 [salt.state       :1770][INFO    ][6387] Running state [en_US.UTF-8] at time 14:03:58.085862
2018-09-29 14:03:58,086 [salt.state       :1803][INFO    ][6387] Executing state locale.system for [en_US.UTF-8]
2018-09-29 14:03:58,086 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'localectl' in directory '/root'
2018-09-29 14:03:58,111 [salt.state       :290 ][INFO    ][6387] System locale en_US.UTF-8 already set
2018-09-29 14:03:58,111 [salt.state       :1941][INFO    ][6387] Completed state [en_US.UTF-8] at time 14:03:58.111693 duration_in_ms=25.831
2018-09-29 14:03:58,112 [salt.state       :1770][INFO    ][6387] Running state [root] at time 14:03:58.112072
2018-09-29 14:03:58,112 [salt.state       :1803][INFO    ][6387] Executing state group.present for [root]
2018-09-29 14:03:58,113 [salt.state       :290 ][INFO    ][6387] Group root is present and up to date
2018-09-29 14:03:58,113 [salt.state       :1941][INFO    ][6387] Completed state [root] at time 14:03:58.113250 duration_in_ms=1.178
2018-09-29 14:03:58,114 [salt.state       :1770][INFO    ][6387] Running state [root] at time 14:03:58.114717
2018-09-29 14:03:58,115 [salt.state       :1803][INFO    ][6387] Executing state user.present for [root]
2018-09-29 14:03:58,116 [salt.state       :290 ][INFO    ][6387] User root is present and up to date
2018-09-29 14:03:58,116 [salt.state       :1941][INFO    ][6387] Completed state [root] at time 14:03:58.116521 duration_in_ms=1.804
2018-09-29 14:03:58,117 [salt.state       :1770][INFO    ][6387] Running state [/root] at time 14:03:58.117647
2018-09-29 14:03:58,117 [salt.state       :1803][INFO    ][6387] Executing state file.directory for [/root]
2018-09-29 14:03:58,118 [salt.state       :290 ][INFO    ][6387] Directory /root is in the correct state
Directory /root updated
2018-09-29 14:03:58,119 [salt.state       :1941][INFO    ][6387] Completed state [/root] at time 14:03:58.119152 duration_in_ms=1.505
2018-09-29 14:03:58,119 [salt.state       :1770][INFO    ][6387] Running state [/etc/sudoers.d/90-salt-user-root] at time 14:03:58.119359
2018-09-29 14:03:58,119 [salt.state       :1803][INFO    ][6387] Executing state file.absent for [/etc/sudoers.d/90-salt-user-root]
2018-09-29 14:03:58,119 [salt.state       :290 ][INFO    ][6387] File /etc/sudoers.d/90-salt-user-root is not present
2018-09-29 14:03:58,120 [salt.state       :1941][INFO    ][6387] Completed state [/etc/sudoers.d/90-salt-user-root] at time 14:03:58.120043 duration_in_ms=0.685
2018-09-29 14:03:58,121 [salt.state       :1770][INFO    ][6387] Running state [ubuntu] at time 14:03:58.121186
2018-09-29 14:03:58,121 [salt.state       :1803][INFO    ][6387] Executing state group.present for [ubuntu]
2018-09-29 14:03:58,121 [salt.state       :290 ][INFO    ][6387] Group ubuntu is present and up to date
2018-09-29 14:03:58,121 [salt.state       :1941][INFO    ][6387] Completed state [ubuntu] at time 14:03:58.121896 duration_in_ms=0.71
2018-09-29 14:03:58,122 [salt.state       :1770][INFO    ][6387] Running state [ubuntu] at time 14:03:58.122792
2018-09-29 14:03:58,123 [salt.state       :1803][INFO    ][6387] Executing state user.present for [ubuntu]
2018-09-29 14:03:58,124 [salt.state       :290 ][INFO    ][6387] User ubuntu is present and up to date
2018-09-29 14:03:58,124 [salt.state       :1941][INFO    ][6387] Completed state [ubuntu] at time 14:03:58.124208 duration_in_ms=1.416
2018-09-29 14:03:58,125 [salt.state       :1770][INFO    ][6387] Running state [/home/ubuntu] at time 14:03:58.125364
2018-09-29 14:03:58,125 [salt.state       :1803][INFO    ][6387] Executing state file.directory for [/home/ubuntu]
2018-09-29 14:03:58,126 [salt.state       :290 ][INFO    ][6387] Directory /home/ubuntu is in the correct state
Directory /home/ubuntu updated
2018-09-29 14:03:58,126 [salt.state       :1941][INFO    ][6387] Completed state [/home/ubuntu] at time 14:03:58.126525 duration_in_ms=1.161
2018-09-29 14:03:58,127 [salt.state       :1770][INFO    ][6387] Running state [/etc/sudoers.d/90-salt-user-ubuntu] at time 14:03:58.127472
2018-09-29 14:03:58,127 [salt.state       :1803][INFO    ][6387] Executing state file.managed for [/etc/sudoers.d/90-salt-user-ubuntu]
2018-09-29 14:03:58,152 [salt.state       :290 ][INFO    ][6387] File /etc/sudoers.d/90-salt-user-ubuntu is in the correct state
2018-09-29 14:03:58,152 [salt.state       :1941][INFO    ][6387] Completed state [/etc/sudoers.d/90-salt-user-ubuntu] at time 14:03:58.152516 duration_in_ms=25.044
2018-09-29 14:03:58,152 [salt.state       :1770][INFO    ][6387] Running state [/etc/security/limits.d/90-salt-default.conf] at time 14:03:58.152738
2018-09-29 14:03:58,153 [salt.state       :1803][INFO    ][6387] Executing state file.managed for [/etc/security/limits.d/90-salt-default.conf]
2018-09-29 14:03:58,276 [salt.state       :290 ][INFO    ][6387] File /etc/security/limits.d/90-salt-default.conf is in the correct state
2018-09-29 14:03:58,276 [salt.state       :1941][INFO    ][6387] Completed state [/etc/security/limits.d/90-salt-default.conf] at time 14:03:58.276612 duration_in_ms=123.874
2018-09-29 14:03:58,276 [salt.state       :1770][INFO    ][6387] Running state [/etc/systemd/system.conf.d/90-salt.conf] at time 14:03:58.276854
2018-09-29 14:03:58,277 [salt.state       :1803][INFO    ][6387] Executing state file.managed for [/etc/systemd/system.conf.d/90-salt.conf]
2018-09-29 14:03:58,387 [salt.state       :290 ][INFO    ][6387] File /etc/systemd/system.conf.d/90-salt.conf is in the correct state
2018-09-29 14:03:58,388 [salt.state       :1941][INFO    ][6387] Completed state [/etc/systemd/system.conf.d/90-salt.conf] at time 14:03:58.387951 duration_in_ms=111.097
2018-09-29 14:03:58,389 [salt.state       :1770][INFO    ][6387] Running state [service.systemctl_reload] at time 14:03:58.389711
2018-09-29 14:03:58,389 [salt.state       :1803][INFO    ][6387] Executing state module.wait for [service.systemctl_reload]
2018-09-29 14:03:58,390 [salt.state       :290 ][INFO    ][6387] No changes made for service.systemctl_reload
2018-09-29 14:03:58,390 [salt.state       :1941][INFO    ][6387] Completed state [service.systemctl_reload] at time 14:03:58.390385 duration_in_ms=0.674
2018-09-29 14:03:58,390 [salt.state       :1770][INFO    ][6387] Running state [/etc/issue] at time 14:03:58.390603
2018-09-29 14:03:58,390 [salt.state       :1803][INFO    ][6387] Executing state file.managed for [/etc/issue]
2018-09-29 14:03:58,392 [salt.state       :290 ][INFO    ][6387] File /etc/issue is in the correct state
2018-09-29 14:03:58,392 [salt.state       :1941][INFO    ][6387] Completed state [/etc/issue] at time 14:03:58.392469 duration_in_ms=1.866
2018-09-29 14:03:58,392 [salt.state       :1770][INFO    ][6387] Running state [/etc/hostname] at time 14:03:58.392686
2018-09-29 14:03:58,392 [salt.state       :1803][INFO    ][6387] Executing state file.managed for [/etc/hostname]
2018-09-29 14:03:58,411 [salt.state       :290 ][INFO    ][6387] File /etc/hostname is in the correct state
2018-09-29 14:03:58,412 [salt.state       :1941][INFO    ][6387] Completed state [/etc/hostname] at time 14:03:58.412033 duration_in_ms=19.347
2018-09-29 14:03:58,413 [salt.state       :1770][INFO    ][6387] Running state [hostname msg02] at time 14:03:58.413407
2018-09-29 14:03:58,413 [salt.state       :1803][INFO    ][6387] Executing state cmd.run for [hostname msg02]
2018-09-29 14:03:58,414 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'test "$(hostname)" = "msg02"' in directory '/root'
2018-09-29 14:03:58,429 [salt.state       :290 ][INFO    ][6387] unless execution succeeded
2018-09-29 14:03:58,430 [salt.state       :1941][INFO    ][6387] Completed state [hostname msg02] at time 14:03:58.430320 duration_in_ms=16.913
2018-09-29 14:03:58,430 [salt.state       :1770][INFO    ][6387] Running state [mdb02] at time 14:03:58.430851
2018-09-29 14:03:58,431 [salt.state       :1803][INFO    ][6387] Executing state host.present for [mdb02]
2018-09-29 14:03:58,432 [salt.state       :290 ][INFO    ][6387] Host mdb02 (192.168.10.33) already present
2018-09-29 14:03:58,432 [salt.state       :1941][INFO    ][6387] Completed state [mdb02] at time 14:03:58.432624 duration_in_ms=1.772
2018-09-29 14:03:58,432 [salt.state       :1770][INFO    ][6387] Running state [mdb02.mcp-pike-odl-ha.local] at time 14:03:58.432952
2018-09-29 14:03:58,433 [salt.state       :1803][INFO    ][6387] Executing state host.present for [mdb02.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,433 [salt.state       :290 ][INFO    ][6387] Host mdb02.mcp-pike-odl-ha.local (192.168.10.33) already present
2018-09-29 14:03:58,433 [salt.state       :1941][INFO    ][6387] Completed state [mdb02.mcp-pike-odl-ha.local] at time 14:03:58.433855 duration_in_ms=0.903
2018-09-29 14:03:58,434 [salt.state       :1770][INFO    ][6387] Running state [mdb03] at time 14:03:58.434130
2018-09-29 14:03:58,434 [salt.state       :1803][INFO    ][6387] Executing state host.present for [mdb03]
2018-09-29 14:03:58,434 [salt.state       :290 ][INFO    ][6387] Host mdb03 (192.168.10.34) already present
2018-09-29 14:03:58,435 [salt.state       :1941][INFO    ][6387] Completed state [mdb03] at time 14:03:58.435001 duration_in_ms=0.871
2018-09-29 14:03:58,435 [salt.state       :1770][INFO    ][6387] Running state [mdb03.mcp-pike-odl-ha.local] at time 14:03:58.435256
2018-09-29 14:03:58,435 [salt.state       :1803][INFO    ][6387] Executing state host.present for [mdb03.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,435 [salt.state       :290 ][INFO    ][6387] Host mdb03.mcp-pike-odl-ha.local (192.168.10.34) already present
2018-09-29 14:03:58,437 [salt.state       :1941][INFO    ][6387] Completed state [mdb03.mcp-pike-odl-ha.local] at time 14:03:58.436098 duration_in_ms=0.841
2018-09-29 14:03:58,437 [salt.state       :1770][INFO    ][6387] Running state [mdb01] at time 14:03:58.437255
2018-09-29 14:03:58,437 [salt.state       :1803][INFO    ][6387] Executing state host.present for [mdb01]
2018-09-29 14:03:58,438 [salt.state       :290 ][INFO    ][6387] Host mdb01 (192.168.10.32) already present
2018-09-29 14:03:58,438 [salt.state       :1941][INFO    ][6387] Completed state [mdb01] at time 14:03:58.438177 duration_in_ms=0.921
2018-09-29 14:03:58,438 [salt.state       :1770][INFO    ][6387] Running state [mdb01.mcp-pike-odl-ha.local] at time 14:03:58.438435
2018-09-29 14:03:58,438 [salt.state       :1803][INFO    ][6387] Executing state host.present for [mdb01.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,439 [salt.state       :290 ][INFO    ][6387] Host mdb01.mcp-pike-odl-ha.local (192.168.10.32) already present
2018-09-29 14:03:58,439 [salt.state       :1941][INFO    ][6387] Completed state [mdb01.mcp-pike-odl-ha.local] at time 14:03:58.439251 duration_in_ms=0.816
2018-09-29 14:03:58,439 [salt.state       :1770][INFO    ][6387] Running state [mdb] at time 14:03:58.439519
2018-09-29 14:03:58,439 [salt.state       :1803][INFO    ][6387] Executing state host.present for [mdb]
2018-09-29 14:03:58,440 [salt.state       :290 ][INFO    ][6387] Host mdb (192.168.10.31) already present
2018-09-29 14:03:58,440 [salt.state       :1941][INFO    ][6387] Completed state [mdb] at time 14:03:58.440395 duration_in_ms=0.877
2018-09-29 14:03:58,440 [salt.state       :1770][INFO    ][6387] Running state [mdb.mcp-pike-odl-ha.local] at time 14:03:58.440715
2018-09-29 14:03:58,440 [salt.state       :1803][INFO    ][6387] Executing state host.present for [mdb.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,441 [salt.state       :290 ][INFO    ][6387] Host mdb.mcp-pike-odl-ha.local (192.168.10.31) already present
2018-09-29 14:03:58,441 [salt.state       :1941][INFO    ][6387] Completed state [mdb.mcp-pike-odl-ha.local] at time 14:03:58.441598 duration_in_ms=0.884
2018-09-29 14:03:58,441 [salt.state       :1770][INFO    ][6387] Running state [cfg01] at time 14:03:58.441867
2018-09-29 14:03:58,442 [salt.state       :1803][INFO    ][6387] Executing state host.present for [cfg01]
2018-09-29 14:03:58,443 [salt.state       :290 ][INFO    ][6387] Host cfg01 (192.168.10.11) already present
2018-09-29 14:03:58,443 [salt.state       :1941][INFO    ][6387] Completed state [cfg01] at time 14:03:58.443894 duration_in_ms=2.026
2018-09-29 14:03:58,444 [salt.state       :1770][INFO    ][6387] Running state [cfg01.mcp-pike-odl-ha.local] at time 14:03:58.444822
2018-09-29 14:03:58,445 [salt.state       :1803][INFO    ][6387] Executing state host.present for [cfg01.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,445 [salt.state       :290 ][INFO    ][6387] Host cfg01.mcp-pike-odl-ha.local (192.168.10.11) already present
2018-09-29 14:03:58,445 [salt.state       :1941][INFO    ][6387] Completed state [cfg01.mcp-pike-odl-ha.local] at time 14:03:58.445706 duration_in_ms=0.885
2018-09-29 14:03:58,446 [salt.state       :1770][INFO    ][6387] Running state [prx01] at time 14:03:58.445973
2018-09-29 14:03:58,446 [salt.state       :1803][INFO    ][6387] Executing state host.present for [prx01]
2018-09-29 14:03:58,446 [salt.state       :290 ][INFO    ][6387] Host prx01 (192.168.10.14) already present
2018-09-29 14:03:58,446 [salt.state       :1941][INFO    ][6387] Completed state [prx01] at time 14:03:58.446784 duration_in_ms=0.812
2018-09-29 14:03:58,447 [salt.state       :1770][INFO    ][6387] Running state [prx01.mcp-pike-odl-ha.local] at time 14:03:58.447051
2018-09-29 14:03:58,447 [salt.state       :1803][INFO    ][6387] Executing state host.present for [prx01.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,447 [salt.state       :290 ][INFO    ][6387] Host prx01.mcp-pike-odl-ha.local (192.168.10.14) already present
2018-09-29 14:03:58,447 [salt.state       :1941][INFO    ][6387] Completed state [prx01.mcp-pike-odl-ha.local] at time 14:03:58.447858 duration_in_ms=0.807
2018-09-29 14:03:58,448 [salt.state       :1770][INFO    ][6387] Running state [kvm01] at time 14:03:58.448147
2018-09-29 14:03:58,448 [salt.state       :1803][INFO    ][6387] Executing state host.present for [kvm01]
2018-09-29 14:03:58,448 [salt.state       :290 ][INFO    ][6387] Host kvm01 (192.168.10.20) already present
2018-09-29 14:03:58,449 [salt.state       :1941][INFO    ][6387] Completed state [kvm01] at time 14:03:58.449032 duration_in_ms=0.885
2018-09-29 14:03:58,449 [salt.state       :1770][INFO    ][6387] Running state [kvm01.mcp-pike-odl-ha.local] at time 14:03:58.449285
2018-09-29 14:03:58,449 [salt.state       :1803][INFO    ][6387] Executing state host.present for [kvm01.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,449 [salt.state       :290 ][INFO    ][6387] Host kvm01.mcp-pike-odl-ha.local (192.168.10.20) already present
2018-09-29 14:03:58,450 [salt.state       :1941][INFO    ][6387] Completed state [kvm01.mcp-pike-odl-ha.local] at time 14:03:58.450100 duration_in_ms=0.815
2018-09-29 14:03:58,450 [salt.state       :1770][INFO    ][6387] Running state [kvm03] at time 14:03:58.450367
2018-09-29 14:03:58,450 [salt.state       :1803][INFO    ][6387] Executing state host.present for [kvm03]
2018-09-29 14:03:58,450 [salt.state       :290 ][INFO    ][6387] Host kvm03 (192.168.10.22) already present
2018-09-29 14:03:58,451 [salt.state       :1941][INFO    ][6387] Completed state [kvm03] at time 14:03:58.451138 duration_in_ms=0.772
2018-09-29 14:03:58,451 [salt.state       :1770][INFO    ][6387] Running state [kvm03.mcp-pike-odl-ha.local] at time 14:03:58.451402
2018-09-29 14:03:58,451 [salt.state       :1803][INFO    ][6387] Executing state host.present for [kvm03.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,452 [salt.state       :290 ][INFO    ][6387] Host kvm03.mcp-pike-odl-ha.local (192.168.10.22) already present
2018-09-29 14:03:58,452 [salt.state       :1941][INFO    ][6387] Completed state [kvm03.mcp-pike-odl-ha.local] at time 14:03:58.452565 duration_in_ms=1.162
2018-09-29 14:03:58,452 [salt.state       :1770][INFO    ][6387] Running state [kvm02] at time 14:03:58.452885
2018-09-29 14:03:58,453 [salt.state       :1803][INFO    ][6387] Executing state host.present for [kvm02]
2018-09-29 14:03:58,453 [salt.state       :290 ][INFO    ][6387] Host kvm02 (192.168.10.21) already present
2018-09-29 14:03:58,453 [salt.state       :1941][INFO    ][6387] Completed state [kvm02] at time 14:03:58.453677 duration_in_ms=0.792
2018-09-29 14:03:58,453 [salt.state       :1770][INFO    ][6387] Running state [kvm02.mcp-pike-odl-ha.local] at time 14:03:58.453950
2018-09-29 14:03:58,454 [salt.state       :1803][INFO    ][6387] Executing state host.present for [kvm02.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,454 [salt.state       :290 ][INFO    ][6387] Host kvm02.mcp-pike-odl-ha.local (192.168.10.21) already present
2018-09-29 14:03:58,454 [salt.state       :1941][INFO    ][6387] Completed state [kvm02.mcp-pike-odl-ha.local] at time 14:03:58.454723 duration_in_ms=0.773
2018-09-29 14:03:58,455 [salt.state       :1770][INFO    ][6387] Running state [dbs] at time 14:03:58.454991
2018-09-29 14:03:58,455 [salt.state       :1803][INFO    ][6387] Executing state host.present for [dbs]
2018-09-29 14:03:58,455 [salt.state       :290 ][INFO    ][6387] Host dbs (192.168.10.23) already present
2018-09-29 14:03:58,455 [salt.state       :1941][INFO    ][6387] Completed state [dbs] at time 14:03:58.455789 duration_in_ms=0.798
2018-09-29 14:03:58,456 [salt.state       :1770][INFO    ][6387] Running state [dbs.mcp-pike-odl-ha.local] at time 14:03:58.456048
2018-09-29 14:03:58,456 [salt.state       :1803][INFO    ][6387] Executing state host.present for [dbs.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,457 [salt.state       :290 ][INFO    ][6387] Host dbs.mcp-pike-odl-ha.local (192.168.10.23) already present
2018-09-29 14:03:58,457 [salt.state       :1941][INFO    ][6387] Completed state [dbs.mcp-pike-odl-ha.local] at time 14:03:58.457565 duration_in_ms=1.517
2018-09-29 14:03:58,457 [salt.state       :1770][INFO    ][6387] Running state [prx] at time 14:03:58.457845
2018-09-29 14:03:58,458 [salt.state       :1803][INFO    ][6387] Executing state host.present for [prx]
2018-09-29 14:03:58,458 [salt.state       :290 ][INFO    ][6387] Host prx (192.168.10.13) already present
2018-09-29 14:03:58,458 [salt.state       :1941][INFO    ][6387] Completed state [prx] at time 14:03:58.458648 duration_in_ms=0.803
2018-09-29 14:03:58,458 [salt.state       :1770][INFO    ][6387] Running state [prx.mcp-pike-odl-ha.local] at time 14:03:58.458919
2018-09-29 14:03:58,459 [salt.state       :1803][INFO    ][6387] Executing state host.present for [prx.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,459 [salt.state       :290 ][INFO    ][6387] Host prx.mcp-pike-odl-ha.local (192.168.10.13) already present
2018-09-29 14:03:58,459 [salt.state       :1941][INFO    ][6387] Completed state [prx.mcp-pike-odl-ha.local] at time 14:03:58.459722 duration_in_ms=0.804
2018-09-29 14:03:58,460 [salt.state       :1770][INFO    ][6387] Running state [prx02] at time 14:03:58.460001
2018-09-29 14:03:58,460 [salt.state       :1803][INFO    ][6387] Executing state host.present for [prx02]
2018-09-29 14:03:58,460 [salt.state       :290 ][INFO    ][6387] Host prx02 (192.168.10.15) already present
2018-09-29 14:03:58,460 [salt.state       :1941][INFO    ][6387] Completed state [prx02] at time 14:03:58.460900 duration_in_ms=0.899
2018-09-29 14:03:58,461 [salt.state       :1770][INFO    ][6387] Running state [prx02.mcp-pike-odl-ha.local] at time 14:03:58.461173
2018-09-29 14:03:58,461 [salt.state       :1803][INFO    ][6387] Executing state host.present for [prx02.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,461 [salt.state       :290 ][INFO    ][6387] Host prx02.mcp-pike-odl-ha.local (192.168.10.15) already present
2018-09-29 14:03:58,462 [salt.state       :1941][INFO    ][6387] Completed state [prx02.mcp-pike-odl-ha.local] at time 14:03:58.461969 duration_in_ms=0.797
2018-09-29 14:03:58,462 [salt.state       :1770][INFO    ][6387] Running state [msg02] at time 14:03:58.462244
2018-09-29 14:03:58,462 [salt.state       :1803][INFO    ][6387] Executing state host.present for [msg02]
2018-09-29 14:03:58,462 [salt.state       :290 ][INFO    ][6387] Host msg02 (192.168.10.29) already present
2018-09-29 14:03:58,463 [salt.state       :1941][INFO    ][6387] Completed state [msg02] at time 14:03:58.463044 duration_in_ms=0.8
2018-09-29 14:03:58,463 [salt.state       :1770][INFO    ][6387] Running state [msg02.mcp-pike-odl-ha.local] at time 14:03:58.463322
2018-09-29 14:03:58,463 [salt.state       :1803][INFO    ][6387] Executing state host.present for [msg02.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,463 [salt.state       :290 ][INFO    ][6387] Host msg02.mcp-pike-odl-ha.local (192.168.10.29) already present
2018-09-29 14:03:58,464 [salt.state       :1941][INFO    ][6387] Completed state [msg02.mcp-pike-odl-ha.local] at time 14:03:58.464138 duration_in_ms=0.817
2018-09-29 14:03:58,465 [salt.state       :1770][INFO    ][6387] Running state [file.replace] at time 14:03:58.465659
2018-09-29 14:03:58,465 [salt.state       :1803][INFO    ][6387] Executing state module.run for [file.replace]
2018-09-29 14:03:58,471 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command 'grep -q "msg02 msg02.mcp-pike-odl-ha.local" /etc/hosts' in directory '/root'
2018-09-29 14:03:58,486 [salt.utils.decorators:613 ][WARNING ][6387] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-29 14:03:58,490 [salt.state       :290 ][INFO    ][6387] {'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-09-29 14:03:58,491 [salt.state       :1941][INFO    ][6387] Completed state [file.replace] at time 14:03:58.490931 duration_in_ms=25.271
2018-09-29 14:03:58,491 [salt.state       :1770][INFO    ][6387] Running state [msg03] at time 14:03:58.491666
2018-09-29 14:03:58,492 [salt.state       :1803][INFO    ][6387] Executing state host.present for [msg03]
2018-09-29 14:03:58,493 [salt.state       :290 ][INFO    ][6387] Host msg03 (192.168.10.30) already present
2018-09-29 14:03:58,493 [salt.state       :1941][INFO    ][6387] Completed state [msg03] at time 14:03:58.493541 duration_in_ms=1.875
2018-09-29 14:03:58,493 [salt.state       :1770][INFO    ][6387] Running state [msg03.mcp-pike-odl-ha.local] at time 14:03:58.493840
2018-09-29 14:03:58,494 [salt.state       :1803][INFO    ][6387] Executing state host.present for [msg03.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,494 [salt.state       :290 ][INFO    ][6387] Host msg03.mcp-pike-odl-ha.local (192.168.10.30) already present
2018-09-29 14:03:58,494 [salt.state       :1941][INFO    ][6387] Completed state [msg03.mcp-pike-odl-ha.local] at time 14:03:58.494749 duration_in_ms=0.909
2018-09-29 14:03:58,495 [salt.state       :1770][INFO    ][6387] Running state [msg01] at time 14:03:58.495042
2018-09-29 14:03:58,495 [salt.state       :1803][INFO    ][6387] Executing state host.present for [msg01]
2018-09-29 14:03:58,495 [salt.state       :290 ][INFO    ][6387] Host msg01 (192.168.10.28) already present
2018-09-29 14:03:58,495 [salt.state       :1941][INFO    ][6387] Completed state [msg01] at time 14:03:58.495895 duration_in_ms=0.853
2018-09-29 14:03:58,496 [salt.state       :1770][INFO    ][6387] Running state [msg01.mcp-pike-odl-ha.local] at time 14:03:58.496531
2018-09-29 14:03:58,496 [salt.state       :1803][INFO    ][6387] Executing state host.present for [msg01.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,497 [salt.state       :290 ][INFO    ][6387] Host msg01.mcp-pike-odl-ha.local (192.168.10.28) already present
2018-09-29 14:03:58,497 [salt.state       :1941][INFO    ][6387] Completed state [msg01.mcp-pike-odl-ha.local] at time 14:03:58.497430 duration_in_ms=0.898
2018-09-29 14:03:58,497 [salt.state       :1770][INFO    ][6387] Running state [msg] at time 14:03:58.497726
2018-09-29 14:03:58,497 [salt.state       :1803][INFO    ][6387] Executing state host.present for [msg]
2018-09-29 14:03:58,498 [salt.state       :290 ][INFO    ][6387] Host msg (192.168.10.27) already present
2018-09-29 14:03:58,498 [salt.state       :1941][INFO    ][6387] Completed state [msg] at time 14:03:58.498634 duration_in_ms=0.908
2018-09-29 14:03:58,499 [salt.state       :1770][INFO    ][6387] Running state [msg.mcp-pike-odl-ha.local] at time 14:03:58.498955
2018-09-29 14:03:58,499 [salt.state       :1803][INFO    ][6387] Executing state host.present for [msg.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,499 [salt.state       :290 ][INFO    ][6387] Host msg.mcp-pike-odl-ha.local (192.168.10.27) already present
2018-09-29 14:03:58,499 [salt.state       :1941][INFO    ][6387] Completed state [msg.mcp-pike-odl-ha.local] at time 14:03:58.499886 duration_in_ms=0.932
2018-09-29 14:03:58,500 [salt.state       :1770][INFO    ][6387] Running state [cfg01] at time 14:03:58.500202
2018-09-29 14:03:58,500 [salt.state       :1803][INFO    ][6387] Executing state host.present for [cfg01]
2018-09-29 14:03:58,501 [salt.state       :290 ][INFO    ][6387] Host cfg01 (192.168.10.11) already present
2018-09-29 14:03:58,501 [salt.state       :1941][INFO    ][6387] Completed state [cfg01] at time 14:03:58.501190 duration_in_ms=0.988
2018-09-29 14:03:58,501 [salt.state       :1770][INFO    ][6387] Running state [cfg01.mcp-pike-odl-ha.local] at time 14:03:58.501486
2018-09-29 14:03:58,501 [salt.state       :1803][INFO    ][6387] Executing state host.present for [cfg01.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,502 [salt.state       :290 ][INFO    ][6387] Host cfg01.mcp-pike-odl-ha.local (192.168.10.11) already present
2018-09-29 14:03:58,502 [salt.state       :1941][INFO    ][6387] Completed state [cfg01.mcp-pike-odl-ha.local] at time 14:03:58.502385 duration_in_ms=0.899
2018-09-29 14:03:58,502 [salt.state       :1770][INFO    ][6387] Running state [cmp002] at time 14:03:58.502681
2018-09-29 14:03:58,502 [salt.state       :1803][INFO    ][6387] Executing state host.present for [cmp002]
2018-09-29 14:03:58,503 [salt.state       :290 ][INFO    ][6387] Host cmp002 (192.168.10.53) already present
2018-09-29 14:03:58,503 [salt.state       :1941][INFO    ][6387] Completed state [cmp002] at time 14:03:58.503542 duration_in_ms=0.861
2018-09-29 14:03:58,503 [salt.state       :1770][INFO    ][6387] Running state [cmp002.mcp-pike-odl-ha.local] at time 14:03:58.503854
2018-09-29 14:03:58,504 [salt.state       :1803][INFO    ][6387] Executing state host.present for [cmp002.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,505 [salt.state       :290 ][INFO    ][6387] Host cmp002.mcp-pike-odl-ha.local (192.168.10.53) already present
2018-09-29 14:03:58,505 [salt.state       :1941][INFO    ][6387] Completed state [cmp002.mcp-pike-odl-ha.local] at time 14:03:58.505418 duration_in_ms=1.563
2018-09-29 14:03:58,505 [salt.state       :1770][INFO    ][6387] Running state [cmp001] at time 14:03:58.505721
2018-09-29 14:03:58,505 [salt.state       :1803][INFO    ][6387] Executing state host.present for [cmp001]
2018-09-29 14:03:58,506 [salt.state       :290 ][INFO    ][6387] Host cmp001 (192.168.10.52) already present
2018-09-29 14:03:58,506 [salt.state       :1941][INFO    ][6387] Completed state [cmp001] at time 14:03:58.506604 duration_in_ms=0.882
2018-09-29 14:03:58,506 [salt.state       :1770][INFO    ][6387] Running state [cmp001.mcp-pike-odl-ha.local] at time 14:03:58.506906
2018-09-29 14:03:58,507 [salt.state       :1803][INFO    ][6387] Executing state host.present for [cmp001.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,507 [salt.state       :290 ][INFO    ][6387] Host cmp001.mcp-pike-odl-ha.local (192.168.10.52) already present
2018-09-29 14:03:58,507 [salt.state       :1941][INFO    ][6387] Completed state [cmp001.mcp-pike-odl-ha.local] at time 14:03:58.507752 duration_in_ms=0.846
2018-09-29 14:03:58,508 [salt.state       :1770][INFO    ][6387] Running state [dbs01] at time 14:03:58.508055
2018-09-29 14:03:58,508 [salt.state       :1803][INFO    ][6387] Executing state host.present for [dbs01]
2018-09-29 14:03:58,508 [salt.state       :290 ][INFO    ][6387] Host dbs01 (192.168.10.24) already present
2018-09-29 14:03:58,509 [salt.state       :1941][INFO    ][6387] Completed state [dbs01] at time 14:03:58.509039 duration_in_ms=0.983
2018-09-29 14:03:58,509 [salt.state       :1770][INFO    ][6387] Running state [dbs01.mcp-pike-odl-ha.local] at time 14:03:58.509343
2018-09-29 14:03:58,509 [salt.state       :1803][INFO    ][6387] Executing state host.present for [dbs01.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,510 [salt.state       :290 ][INFO    ][6387] Host dbs01.mcp-pike-odl-ha.local (192.168.10.24) already present
2018-09-29 14:03:58,510 [salt.state       :1941][INFO    ][6387] Completed state [dbs01.mcp-pike-odl-ha.local] at time 14:03:58.510271 duration_in_ms=0.928
2018-09-29 14:03:58,510 [salt.state       :1770][INFO    ][6387] Running state [dbs02] at time 14:03:58.510578
2018-09-29 14:03:58,510 [salt.state       :1803][INFO    ][6387] Executing state host.present for [dbs02]
2018-09-29 14:03:58,511 [salt.state       :290 ][INFO    ][6387] Host dbs02 (192.168.10.25) already present
2018-09-29 14:03:58,511 [salt.state       :1941][INFO    ][6387] Completed state [dbs02] at time 14:03:58.511566 duration_in_ms=0.988
2018-09-29 14:03:58,511 [salt.state       :1770][INFO    ][6387] Running state [dbs02.mcp-pike-odl-ha.local] at time 14:03:58.511888
2018-09-29 14:03:58,512 [salt.state       :1803][INFO    ][6387] Executing state host.present for [dbs02.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,512 [salt.state       :290 ][INFO    ][6387] Host dbs02.mcp-pike-odl-ha.local (192.168.10.25) already present
2018-09-29 14:03:58,512 [salt.state       :1941][INFO    ][6387] Completed state [dbs02.mcp-pike-odl-ha.local] at time 14:03:58.512788 duration_in_ms=0.901
2018-09-29 14:03:58,513 [salt.state       :1770][INFO    ][6387] Running state [dbs03] at time 14:03:58.513097
2018-09-29 14:03:58,513 [salt.state       :1803][INFO    ][6387] Executing state host.present for [dbs03]
2018-09-29 14:03:58,513 [salt.state       :290 ][INFO    ][6387] Host dbs03 (192.168.10.26) already present
2018-09-29 14:03:58,514 [salt.state       :1941][INFO    ][6387] Completed state [dbs03] at time 14:03:58.513956 duration_in_ms=0.859
2018-09-29 14:03:58,514 [salt.state       :1770][INFO    ][6387] Running state [dbs03.mcp-pike-odl-ha.local] at time 14:03:58.514268
2018-09-29 14:03:58,514 [salt.state       :1803][INFO    ][6387] Executing state host.present for [dbs03.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,514 [salt.state       :290 ][INFO    ][6387] Host dbs03.mcp-pike-odl-ha.local (192.168.10.26) already present
2018-09-29 14:03:58,515 [salt.state       :1941][INFO    ][6387] Completed state [dbs03.mcp-pike-odl-ha.local] at time 14:03:58.515102 duration_in_ms=0.835
2018-09-29 14:03:58,515 [salt.state       :1770][INFO    ][6387] Running state [odl01] at time 14:03:58.515413
2018-09-29 14:03:58,515 [salt.state       :1803][INFO    ][6387] Executing state host.present for [odl01]
2018-09-29 14:03:58,516 [salt.state       :290 ][INFO    ][6387] Host odl01 (192.168.10.39) already present
2018-09-29 14:03:58,516 [salt.state       :1941][INFO    ][6387] Completed state [odl01] at time 14:03:58.516631 duration_in_ms=1.218
2018-09-29 14:03:58,517 [salt.state       :1770][INFO    ][6387] Running state [odl01.mcp-pike-odl-ha.local] at time 14:03:58.516982
2018-09-29 14:03:58,517 [salt.state       :1803][INFO    ][6387] Executing state host.present for [odl01.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,517 [salt.state       :290 ][INFO    ][6387] Host odl01.mcp-pike-odl-ha.local (192.168.10.39) already present
2018-09-29 14:03:58,517 [salt.state       :1941][INFO    ][6387] Completed state [odl01.mcp-pike-odl-ha.local] at time 14:03:58.517868 duration_in_ms=0.886
2018-09-29 14:03:58,518 [salt.state       :1770][INFO    ][6387] Running state [mas01] at time 14:03:58.518182
2018-09-29 14:03:58,518 [salt.state       :1803][INFO    ][6387] Executing state host.present for [mas01]
2018-09-29 14:03:58,518 [salt.state       :290 ][INFO    ][6387] Host mas01 (192.168.10.12) already present
2018-09-29 14:03:58,519 [salt.state       :1941][INFO    ][6387] Completed state [mas01] at time 14:03:58.518983 duration_in_ms=0.801
2018-09-29 14:03:58,519 [salt.state       :1770][INFO    ][6387] Running state [mas01.mcp-pike-odl-ha.local] at time 14:03:58.519273
2018-09-29 14:03:58,519 [salt.state       :1803][INFO    ][6387] Executing state host.present for [mas01.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,519 [salt.state       :290 ][INFO    ][6387] Host mas01.mcp-pike-odl-ha.local (192.168.10.12) already present
2018-09-29 14:03:58,520 [salt.state       :1941][INFO    ][6387] Completed state [mas01.mcp-pike-odl-ha.local] at time 14:03:58.520477 duration_in_ms=1.204
2018-09-29 14:03:58,520 [salt.state       :1770][INFO    ][6387] Running state [ctl02] at time 14:03:58.520810
2018-09-29 14:03:58,521 [salt.state       :1803][INFO    ][6387] Executing state host.present for [ctl02]
2018-09-29 14:03:58,521 [salt.state       :290 ][INFO    ][6387] Host ctl02 (192.168.10.37) already present
2018-09-29 14:03:58,521 [salt.state       :1941][INFO    ][6387] Completed state [ctl02] at time 14:03:58.521666 duration_in_ms=0.855
2018-09-29 14:03:58,522 [salt.state       :1770][INFO    ][6387] Running state [ctl02.mcp-pike-odl-ha.local] at time 14:03:58.521971
2018-09-29 14:03:58,522 [salt.state       :1803][INFO    ][6387] Executing state host.present for [ctl02.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,522 [salt.state       :290 ][INFO    ][6387] Host ctl02.mcp-pike-odl-ha.local (192.168.10.37) already present
2018-09-29 14:03:58,522 [salt.state       :1941][INFO    ][6387] Completed state [ctl02.mcp-pike-odl-ha.local] at time 14:03:58.522815 duration_in_ms=0.845
2018-09-29 14:03:58,523 [salt.state       :1770][INFO    ][6387] Running state [ctl03] at time 14:03:58.523138
2018-09-29 14:03:58,523 [salt.state       :1803][INFO    ][6387] Executing state host.present for [ctl03]
2018-09-29 14:03:58,523 [salt.state       :290 ][INFO    ][6387] Host ctl03 (192.168.10.38) already present
2018-09-29 14:03:58,524 [salt.state       :1941][INFO    ][6387] Completed state [ctl03] at time 14:03:58.523992 duration_in_ms=0.854
2018-09-29 14:03:58,524 [salt.state       :1770][INFO    ][6387] Running state [ctl03.mcp-pike-odl-ha.local] at time 14:03:58.524324
2018-09-29 14:03:58,524 [salt.state       :1803][INFO    ][6387] Executing state host.present for [ctl03.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,525 [salt.state       :290 ][INFO    ][6387] Host ctl03.mcp-pike-odl-ha.local (192.168.10.38) already present
2018-09-29 14:03:58,525 [salt.state       :1941][INFO    ][6387] Completed state [ctl03.mcp-pike-odl-ha.local] at time 14:03:58.525255 duration_in_ms=0.931
2018-09-29 14:03:58,525 [salt.state       :1770][INFO    ][6387] Running state [ctl01] at time 14:03:58.525592
2018-09-29 14:03:58,525 [salt.state       :1803][INFO    ][6387] Executing state host.present for [ctl01]
2018-09-29 14:03:58,526 [salt.state       :290 ][INFO    ][6387] Host ctl01 (192.168.10.36) already present
2018-09-29 14:03:58,526 [salt.state       :1941][INFO    ][6387] Completed state [ctl01] at time 14:03:58.526457 duration_in_ms=0.865
2018-09-29 14:03:58,526 [salt.state       :1770][INFO    ][6387] Running state [ctl01.mcp-pike-odl-ha.local] at time 14:03:58.526761
2018-09-29 14:03:58,526 [salt.state       :1803][INFO    ][6387] Executing state host.present for [ctl01.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,527 [salt.state       :290 ][INFO    ][6387] Host ctl01.mcp-pike-odl-ha.local (192.168.10.36) already present
2018-09-29 14:03:58,527 [salt.state       :1941][INFO    ][6387] Completed state [ctl01.mcp-pike-odl-ha.local] at time 14:03:58.527610 duration_in_ms=0.849
2018-09-29 14:03:58,527 [salt.state       :1770][INFO    ][6387] Running state [ctl] at time 14:03:58.527925
2018-09-29 14:03:58,529 [salt.state       :1803][INFO    ][6387] Executing state host.present for [ctl]
2018-09-29 14:03:58,529 [salt.state       :290 ][INFO    ][6387] Host ctl (192.168.10.35) already present
2018-09-29 14:03:58,529 [salt.state       :1941][INFO    ][6387] Completed state [ctl] at time 14:03:58.529717 duration_in_ms=1.793
2018-09-29 14:03:58,530 [salt.state       :1770][INFO    ][6387] Running state [ctl.mcp-pike-odl-ha.local] at time 14:03:58.530024
2018-09-29 14:03:58,530 [salt.state       :1803][INFO    ][6387] Executing state host.present for [ctl.mcp-pike-odl-ha.local]
2018-09-29 14:03:58,530 [salt.state       :290 ][INFO    ][6387] Host ctl.mcp-pike-odl-ha.local (192.168.10.35) already present
2018-09-29 14:03:58,530 [salt.state       :1941][INFO    ][6387] Completed state [ctl.mcp-pike-odl-ha.local] at time 14:03:58.530895 duration_in_ms=0.87
2018-09-29 14:03:58,531 [salt.state       :1770][INFO    ][6387] Running state [/etc/network/interfaces.d/50-cloud-init.cfg] at time 14:03:58.531116
2018-09-29 14:03:58,531 [salt.state       :1803][INFO    ][6387] Executing state file.absent for [/etc/network/interfaces.d/50-cloud-init.cfg]
2018-09-29 14:03:58,531 [salt.state       :290 ][INFO    ][6387] File /etc/network/interfaces.d/50-cloud-init.cfg is not present
2018-09-29 14:03:58,531 [salt.state       :1941][INFO    ][6387] Completed state [/etc/network/interfaces.d/50-cloud-init.cfg] at time 14:03:58.531828 duration_in_ms=0.712
2018-09-29 14:03:58,532 [salt.state       :1770][INFO    ][6387] Running state [ens2] at time 14:03:58.532067
2018-09-29 14:03:58,532 [salt.state       :1803][INFO    ][6387] Executing state network.managed for [ens2]
2018-09-29 14:03:58,668 [salt.state       :290 ][INFO    ][6387] Interface ens2 is up to date.
2018-09-29 14:03:58,669 [salt.state       :1941][INFO    ][6387] Completed state [ens2] at time 14:03:58.669272 duration_in_ms=137.204
2018-09-29 14:03:58,669 [salt.state       :1770][INFO    ][6387] Running state [ens3] at time 14:03:58.669663
2018-09-29 14:03:58,670 [salt.state       :1803][INFO    ][6387] Executing state network.managed for [ens3]
2018-09-29 14:03:58,699 [salt.state       :290 ][INFO    ][6387] Interface ens3 is up to date.
2018-09-29 14:03:58,700 [salt.state       :1941][INFO    ][6387] Completed state [ens3] at time 14:03:58.700864 duration_in_ms=31.201
2018-09-29 14:03:58,701 [salt.state       :1770][INFO    ][6387] Running state [/etc/profile.d/proxy.sh] at time 14:03:58.701266
2018-09-29 14:03:58,701 [salt.state       :1803][INFO    ][6387] Executing state file.absent for [/etc/profile.d/proxy.sh]
2018-09-29 14:03:58,702 [salt.state       :290 ][INFO    ][6387] File /etc/profile.d/proxy.sh is not present
2018-09-29 14:03:58,702 [salt.state       :1941][INFO    ][6387] Completed state [/etc/profile.d/proxy.sh] at time 14:03:58.702373 duration_in_ms=1.106
2018-09-29 14:03:58,702 [salt.state       :1770][INFO    ][6387] Running state [/etc/apt/apt.conf.d/95proxies] at time 14:03:58.702611
2018-09-29 14:03:58,702 [salt.state       :1803][INFO    ][6387] Executing state file.absent for [/etc/apt/apt.conf.d/95proxies]
2018-09-29 14:03:58,703 [salt.state       :290 ][INFO    ][6387] File /etc/apt/apt.conf.d/95proxies is not present
2018-09-29 14:03:58,703 [salt.state       :1941][INFO    ][6387] Completed state [/etc/apt/apt.conf.d/95proxies] at time 14:03:58.703285 duration_in_ms=0.674
2018-09-29 14:03:58,703 [salt.state       :1770][INFO    ][6387] Running state [ntp] at time 14:03:58.703511
2018-09-29 14:03:58,703 [salt.state       :1803][INFO    ][6387] Executing state pkg.installed for [ntp]
2018-09-29 14:03:58,714 [salt.state       :290 ][INFO    ][6387] All specified packages are already installed
2018-09-29 14:03:58,714 [salt.state       :1941][INFO    ][6387] Completed state [ntp] at time 14:03:58.714861 duration_in_ms=11.349
2018-09-29 14:03:58,717 [salt.state       :1770][INFO    ][6387] Running state [/etc/ntp.conf] at time 14:03:58.717097
2018-09-29 14:03:58,717 [salt.state       :1803][INFO    ][6387] Executing state file.managed for [/etc/ntp.conf]
2018-09-29 14:03:58,794 [salt.state       :290 ][INFO    ][6387] File /etc/ntp.conf is in the correct state
2018-09-29 14:03:58,794 [salt.state       :1941][INFO    ][6387] Completed state [/etc/ntp.conf] at time 14:03:58.794935 duration_in_ms=77.838
2018-09-29 14:03:58,796 [salt.state       :1770][INFO    ][6387] Running state [ntp] at time 14:03:58.796135
2018-09-29 14:03:58,796 [salt.state       :1803][INFO    ][6387] Executing state service.running for [ntp]
2018-09-29 14:03:58,797 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command ['systemctl', 'status', 'ntp.service', '-n', '0'] in directory '/root'
2018-09-29 14:03:58,812 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command ['systemctl', 'is-active', 'ntp.service'] in directory '/root'
2018-09-29 14:03:58,831 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6387] Executing command ['systemctl', 'is-enabled', 'ntp.service'] in directory '/root'
2018-09-29 14:03:58,845 [salt.state       :290 ][INFO    ][6387] The service ntp is already running
2018-09-29 14:03:58,846 [salt.state       :1941][INFO    ][6387] Completed state [ntp] at time 14:03:58.846310 duration_in_ms=50.175
2018-09-29 14:03:58,851 [salt.minion      :1708][INFO    ][6387] Returning information for job: 20180929140342487606
2018-09-29 14:04:01,159 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command ssh.set_auth_key with jid 20180929140401139289
2018-09-29 14:04:01,187 [salt.minion      :1431][INFO    ][7116] Starting a new job with PID 7116
2018-09-29 14:04:01,206 [salt.minion      :1708][INFO    ][7116] Returning information for job: 20180929140401139289
2018-09-29 14:04:37,451 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command pkg.upgrade with jid 20180929140437438549
2018-09-29 14:04:37,471 [salt.minion      :1431][INFO    ][7140] Starting a new job with PID 7140
2018-09-29 14:04:37,493 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][7140] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-09-29 14:04:37,848 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][7140] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'upgrade'] in directory '/root'
2018-09-29 14:04:44,964 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][7140] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-09-29 14:04:45,005 [salt.minion      :1708][INFO    ][7140] Returning information for job: 20180929140437438549
2018-09-29 14:14:13,704 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command test.ping with jid 20180929141413692333
2018-09-29 14:14:13,722 [salt.minion      :1431][INFO    ][8092] Starting a new job with PID 8092
2018-09-29 14:14:13,744 [salt.minion      :1708][INFO    ][8092] Returning information for job: 20180929141413692333
2018-09-29 14:14:13,912 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command state.sls with jid 20180929141413898201
2018-09-29 14:14:13,923 [salt.minion      :1431][INFO    ][8097] Starting a new job with PID 8097
2018-09-29 14:14:16,771 [salt.state       :905 ][INFO    ][8097] Loading fresh modules for state activity
2018-09-29 14:14:16,836 [salt.fileclient  :1215][INFO    ][8097] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2018-09-29 14:14:16,869 [salt.fileclient  :1215][INFO    ][8097] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2018-09-29 14:14:17,469 [salt.state       :1770][INFO    ][8097] Running state [keepalived] at time 14:14:17.469087
2018-09-29 14:14:17,469 [salt.state       :1803][INFO    ][8097] Executing state pkg.installed for [keepalived]
2018-09-29 14:14:17,470 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8097] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-09-29 14:14:17,798 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8097] Executing command ['apt-cache', '-q', 'policy', 'keepalived'] in directory '/root'
2018-09-29 14:14:17,888 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8097] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-09-29 14:14:19,583 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8097] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-09-29 14:14:19,604 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8097] 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-09-29 14:14:23,287 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8097] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-09-29 14:14:23,327 [salt.state       :290 ][INFO    ][8097] 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-09-29 14:14:23,345 [salt.state       :905 ][INFO    ][8097] Loading fresh modules for state activity
2018-09-29 14:14:23,378 [salt.state       :1941][INFO    ][8097] Completed state [keepalived] at time 14:14:23.378113 duration_in_ms=5909.026
2018-09-29 14:14:23,383 [salt.state       :1770][INFO    ][8097] Running state [lsof] at time 14:14:23.383086
2018-09-29 14:14:23,383 [salt.state       :1803][INFO    ][8097] Executing state pkg.installed for [lsof]
2018-09-29 14:14:23,968 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929141423950328
2018-09-29 14:14:23,968 [salt.state       :290 ][INFO    ][8097] All specified packages are already installed
2018-09-29 14:14:23,969 [salt.state       :1941][INFO    ][8097] Completed state [lsof] at time 14:14:23.969121 duration_in_ms=586.035
2018-09-29 14:14:23,972 [salt.state       :1770][INFO    ][8097] Running state [/etc/keepalived/keepalived.conf] at time 14:14:23.972490
2018-09-29 14:14:23,972 [salt.state       :1803][INFO    ][8097] Executing state file.managed for [/etc/keepalived/keepalived.conf]
2018-09-29 14:14:23,978 [salt.minion      :1431][INFO    ][9477] Starting a new job with PID 9477
2018-09-29 14:14:23,992 [salt.minion      :1708][INFO    ][9477] Returning information for job: 20180929141423950328
2018-09-29 14:14:24,007 [salt.fileclient  :1215][INFO    ][8097] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2018-09-29 14:14:24,063 [salt.state       :290 ][INFO    ][8097] File changed:
New file
2018-09-29 14:14:24,063 [salt.state       :1941][INFO    ][8097] Completed state [/etc/keepalived/keepalived.conf] at time 14:14:24.063814 duration_in_ms=91.324
2018-09-29 14:14:24,064 [salt.state       :1770][INFO    ][8097] Running state [keepalived] at time 14:14:24.064846
2018-09-29 14:14:24,065 [salt.state       :1803][INFO    ][8097] Executing state service.running for [keepalived]
2018-09-29 14:14:24,066 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8097] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2018-09-29 14:14:24,083 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8097] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2018-09-29 14:14:24,103 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8097] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2018-09-29 14:14:24,116 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8097] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2018-09-29 14:14:24,177 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8097] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2018-09-29 14:14:24,196 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8097] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2018-09-29 14:14:24,216 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8097] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2018-09-29 14:14:24,234 [salt.state       :290 ][INFO    ][8097] {'keepalived': True}
2018-09-29 14:14:24,235 [salt.state       :1941][INFO    ][8097] Completed state [keepalived] at time 14:14:24.235426 duration_in_ms=170.578
2018-09-29 14:14:24,238 [salt.minion      :1708][INFO    ][8097] Returning information for job: 20180929141413898201
2018-09-29 14:17:18,818 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command pillar.get with jid 20180929141718804924
2018-09-29 14:17:18,839 [salt.minion      :1431][INFO    ][9556] Starting a new job with PID 9556
2018-09-29 14:17:18,849 [salt.minion      :1708][INFO    ][9556] Returning information for job: 20180929141718804924
2018-09-29 14:20:12,780 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command state.sls with jid 20180929142012765242
2018-09-29 14:20:12,805 [salt.minion      :1431][INFO    ][9571] Starting a new job with PID 9571
2018-09-29 14:20:15,764 [salt.state       :905 ][INFO    ][9571] Loading fresh modules for state activity
2018-09-29 14:20:15,838 [salt.fileclient  :1215][INFO    ][9571] Fetching file from saltenv 'base', ** done ** 'rabbitmq/init.sls'
2018-09-29 14:20:15,901 [salt.fileclient  :1215][INFO    ][9571] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/init.sls'
2018-09-29 14:20:15,938 [salt.fileclient  :1215][INFO    ][9571] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/service.sls'
2018-09-29 14:20:15,984 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command 'cat /var/lib/rabbitmq/.erlang.cookie' in directory '/root'
2018-09-29 14:20:16,002 [salt.loaded.int.module.cmdmod:722 ][ERROR   ][9571] Command 'cat /var/lib/rabbitmq/.erlang.cookie' failed with return code: 1
2018-09-29 14:20:16,002 [salt.loaded.int.module.cmdmod:724 ][ERROR   ][9571] stdout: cat: /var/lib/rabbitmq/.erlang.cookie: No such file or directory
2018-09-29 14:20:16,003 [salt.loaded.int.module.cmdmod:728 ][ERROR   ][9571] retcode: 1
2018-09-29 14:20:16,044 [salt.fileclient  :1215][INFO    ][9571] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/ssl.sls'
2018-09-29 14:20:16,107 [salt.fileclient  :1215][INFO    ][9571] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/plugin.sls'
2018-09-29 14:20:16,175 [salt.fileclient  :1215][INFO    ][9571] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/vhost.sls'
2018-09-29 14:20:16,264 [salt.fileclient  :1215][INFO    ][9571] Fetching file from saltenv 'base', ** done ** 'rabbitmq/server/user.sls'
2018-09-29 14:20:16,323 [salt.fileclient  :1215][INFO    ][9571] Fetching file from saltenv 'base', ** done ** 'rabbitmq/cluster.sls'
2018-09-29 14:20:16,967 [salt.state       :1770][INFO    ][9571] Running state [rabbitmq-server] at time 14:20:16.967530
2018-09-29 14:20:16,967 [salt.state       :1803][INFO    ][9571] Executing state pkg.installed for [rabbitmq-server]
2018-09-29 14:20:16,968 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-09-29 14:20:17,296 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['apt-cache', '-q', 'policy', 'rabbitmq-server'] in directory '/root'
2018-09-29 14:20:17,399 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-09-29 14:20:19,548 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-09-29 14:20:19,576 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] 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-09-29 14:20:22,841 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929142022826468
2018-09-29 14:20:22,855 [salt.minion      :1431][INFO    ][10209] Starting a new job with PID 10209
2018-09-29 14:20:22,871 [salt.minion      :1708][INFO    ][10209] Returning information for job: 20180929142022826468
2018-09-29 14:20:33,065 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929142033048364
2018-09-29 14:20:33,080 [salt.minion      :1431][INFO    ][10939] Starting a new job with PID 10939
2018-09-29 14:20:33,103 [salt.minion      :1708][INFO    ][10939] Returning information for job: 20180929142033048364
2018-09-29 14:20:43,096 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929142043075271
2018-09-29 14:20:43,110 [salt.minion      :1431][INFO    ][10944] Starting a new job with PID 10944
2018-09-29 14:20:43,130 [salt.minion      :1708][INFO    ][10944] Returning information for job: 20180929142043075271
2018-09-29 14:20:53,150 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929142053132630
2018-09-29 14:20:53,166 [salt.minion      :1431][INFO    ][10949] Starting a new job with PID 10949
2018-09-29 14:20:53,188 [salt.minion      :1708][INFO    ][10949] Returning information for job: 20180929142053132630
2018-09-29 14:21:02,268 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-09-29 14:21:02,310 [salt.state       :290 ][INFO    ][9571] 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-09-29 14:21:02,333 [salt.state       :905 ][INFO    ][9571] Loading fresh modules for state activity
2018-09-29 14:21:02,367 [salt.state       :1941][INFO    ][9571] Completed state [rabbitmq-server] at time 14:21:02.367429 duration_in_ms=45399.899
2018-09-29 14:21:02,376 [salt.state       :1770][INFO    ][9571] Running state [/etc/rabbitmq/rabbitmq.config] at time 14:21:02.376834
2018-09-29 14:21:02,377 [salt.state       :1803][INFO    ][9571] Executing state file.managed for [/etc/rabbitmq/rabbitmq.config]
2018-09-29 14:21:02,434 [salt.fileclient  :1215][INFO    ][9571] Fetching file from saltenv 'base', ** done ** 'rabbitmq/files/rabbitmq.config'
2018-09-29 14:21:02,504 [salt.state       :290 ][INFO    ][9571] File changed:
New file
2018-09-29 14:21:02,504 [salt.state       :1941][INFO    ][9571] Completed state [/etc/rabbitmq/rabbitmq.config] at time 14:21:02.504637 duration_in_ms=127.803
2018-09-29 14:21:02,505 [salt.state       :1770][INFO    ][9571] Running state [/etc/default/rabbitmq-server] at time 14:21:02.505237
2018-09-29 14:21:02,505 [salt.state       :1803][INFO    ][9571] Executing state file.managed for [/etc/default/rabbitmq-server]
2018-09-29 14:21:02,539 [salt.fileclient  :1215][INFO    ][9571] Fetching file from saltenv 'base', ** done ** 'rabbitmq/files/default'
2018-09-29 14:21:02,581 [salt.state       :290 ][INFO    ][9571] 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-09-29 14:21:02,584 [salt.state       :1941][INFO    ][9571] Completed state [/etc/default/rabbitmq-server] at time 14:21:02.584605 duration_in_ms=79.368
2018-09-29 14:21:02,585 [salt.state       :1770][INFO    ][9571] Running state [/etc/systemd/system/rabbitmq-server.service.d/limits.conf] at time 14:21:02.584996
2018-09-29 14:21:02,585 [salt.state       :1803][INFO    ][9571] Executing state file.managed for [/etc/systemd/system/rabbitmq-server.service.d/limits.conf]
2018-09-29 14:21:02,618 [salt.fileclient  :1215][INFO    ][9571] Fetching file from saltenv 'base', ** done ** 'rabbitmq/files/limits.conf'
2018-09-29 14:21:02,661 [salt.state       :290 ][INFO    ][9571] File changed:
New file
2018-09-29 14:21:02,661 [salt.state       :1941][INFO    ][9571] Completed state [/etc/systemd/system/rabbitmq-server.service.d/limits.conf] at time 14:21:02.661819 duration_in_ms=76.823
2018-09-29 14:21:02,663 [salt.state       :1770][INFO    ][9571] Running state [sleep 30] at time 14:21:02.663140
2018-09-29 14:21:02,663 [salt.state       :1803][INFO    ][9571] Executing state cmd.run for [sleep 30]
2018-09-29 14:21:02,664 [py.warnings      :2526][WARNING ][9571] /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-09-29 14:21:02,668 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command 'sleep 30' as user 'root' in directory '/root'
2018-09-29 14:21:03,182 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929142103159325
2018-09-29 14:21:03,206 [salt.minion      :1431][INFO    ][11125] Starting a new job with PID 11125
2018-09-29 14:21:03,222 [salt.minion      :1708][INFO    ][11125] Returning information for job: 20180929142103159325
2018-09-29 14:21:13,406 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929142113389651
2018-09-29 14:21:13,426 [salt.minion      :1431][INFO    ][11130] Starting a new job with PID 11130
2018-09-29 14:21:13,443 [salt.minion      :1708][INFO    ][11130] Returning information for job: 20180929142113389651
2018-09-29 14:21:23,473 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929142123449752
2018-09-29 14:21:23,494 [salt.minion      :1431][INFO    ][11135] Starting a new job with PID 11135
2018-09-29 14:21:23,512 [salt.minion      :1708][INFO    ][11135] Returning information for job: 20180929142123449752
2018-09-29 14:21:32,762 [salt.state       :290 ][INFO    ][9571] {'pid': 11100, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-29 14:21:32,764 [salt.state       :1941][INFO    ][9571] Completed state [sleep 30] at time 14:21:32.763951 duration_in_ms=30100.809
2018-09-29 14:21:32,766 [salt.state       :1770][INFO    ][9571] Running state [service rabbitmq-server stop] at time 14:21:32.766735
2018-09-29 14:21:32,767 [salt.state       :1803][INFO    ][9571] Executing state cmd.run for [service rabbitmq-server stop]
2018-09-29 14:21:32,769 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command 'service rabbitmq-server stop' in directory '/root'
2018-09-29 14:21:33,094 [salt.state       :290 ][INFO    ][9571] {'pid': 11138, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-29 14:21:33,095 [salt.state       :1941][INFO    ][9571] Completed state [service rabbitmq-server stop] at time 14:21:33.095381 duration_in_ms=328.646
2018-09-29 14:21:33,096 [salt.state       :1770][INFO    ][9571] Running state [/var/lib/rabbitmq] at time 14:21:33.095984
2018-09-29 14:21:33,096 [salt.state       :1803][INFO    ][9571] Executing state file.directory for [/var/lib/rabbitmq]
2018-09-29 14:21:33,098 [salt.state       :290 ][INFO    ][9571] Directory /var/lib/rabbitmq is in the correct state
Directory /var/lib/rabbitmq updated
2018-09-29 14:21:33,098 [salt.state       :1941][INFO    ][9571] Completed state [/var/lib/rabbitmq] at time 14:21:33.098449 duration_in_ms=2.466
2018-09-29 14:21:33,100 [salt.state       :1770][INFO    ][9571] Running state [/var/lib/rabbitmq/.erlang.cookie] at time 14:21:33.100289
2018-09-29 14:21:33,100 [salt.state       :1803][INFO    ][9571] Executing state file.managed for [/var/lib/rabbitmq/.erlang.cookie]
2018-09-29 14:21:33,103 [salt.state       :290 ][INFO    ][9571] File changed:
--- 
+++ 
@@ -1 +1 @@
-AYFRWTVEPWEVLPTLLCSF+opnfv_secret

2018-09-29 14:21:33,104 [salt.state       :1941][INFO    ][9571] Completed state [/var/lib/rabbitmq/.erlang.cookie] at time 14:21:33.104162 duration_in_ms=3.873
2018-09-29 14:21:33,104 [salt.state       :1770][INFO    ][9571] Running state [sleep 30] at time 14:21:33.104731
2018-09-29 14:21:33,105 [salt.state       :1803][INFO    ][9571] Executing state cmd.run for [sleep 30]
2018-09-29 14:21:33,106 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command 'sleep 30' as user 'root' in directory '/root'
2018-09-29 14:21:33,497 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929142133475811
2018-09-29 14:21:33,516 [salt.minion      :1431][INFO    ][11223] Starting a new job with PID 11223
2018-09-29 14:21:33,533 [salt.minion      :1708][INFO    ][11223] Returning information for job: 20180929142133475811
2018-09-29 14:21:43,528 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929142143511490
2018-09-29 14:21:43,550 [salt.minion      :1431][INFO    ][11228] Starting a new job with PID 11228
2018-09-29 14:21:43,566 [salt.minion      :1708][INFO    ][11228] Returning information for job: 20180929142143511490
2018-09-29 14:21:53,558 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929142153541720
2018-09-29 14:21:53,578 [salt.minion      :1431][INFO    ][11233] Starting a new job with PID 11233
2018-09-29 14:21:53,595 [salt.minion      :1708][INFO    ][11233] Returning information for job: 20180929142153541720
2018-09-29 14:22:03,198 [salt.state       :290 ][INFO    ][9571] {'pid': 11199, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-29 14:22:03,200 [salt.state       :1941][INFO    ][9571] Completed state [sleep 30] at time 14:22:03.200709 duration_in_ms=30095.977
2018-09-29 14:22:03,584 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929142203563335
2018-09-29 14:22:03,596 [salt.minion      :1431][INFO    ][11239] Starting a new job with PID 11239
2018-09-29 14:22:03,613 [salt.minion      :1708][INFO    ][11239] Returning information for job: 20180929142203563335
2018-09-29 14:22:03,643 [salt.state       :1770][INFO    ][9571] Running state [rabbitmq-server] at time 14:22:03.643497
2018-09-29 14:22:03,643 [salt.state       :1803][INFO    ][9571] Executing state service.running for [rabbitmq-server]
2018-09-29 14:22:03,644 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['systemctl', 'status', 'rabbitmq-server.service', '-n', '0'] in directory '/root'
2018-09-29 14:22:03,667 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['systemctl', 'is-active', 'rabbitmq-server.service'] in directory '/root'
2018-09-29 14:22:03,687 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['systemctl', 'is-enabled', 'rabbitmq-server.service'] in directory '/root'
2018-09-29 14:22:03,720 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'rabbitmq-server.service'] in directory '/root'
2018-09-29 14:22:06,429 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['systemctl', 'is-active', 'rabbitmq-server.service'] in directory '/root'
2018-09-29 14:22:06,453 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['systemctl', 'is-enabled', 'rabbitmq-server.service'] in directory '/root'
2018-09-29 14:22:06,483 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['systemctl', 'is-enabled', 'rabbitmq-server.service'] in directory '/root'
2018-09-29 14:22:06,510 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'rabbitmq-server.service'] in directory '/root'
2018-09-29 14:22:06,921 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['systemctl', 'is-enabled', 'rabbitmq-server.service'] in directory '/root'
2018-09-29 14:22:06,941 [salt.state       :290 ][INFO    ][9571] {'rabbitmq-server': True}
2018-09-29 14:22:06,941 [salt.state       :1941][INFO    ][9571] Completed state [rabbitmq-server] at time 14:22:06.941477 duration_in_ms=3297.98
2018-09-29 14:22:06,945 [salt.state       :1770][INFO    ][9571] Running state [amqp_client] at time 14:22:06.945611
2018-09-29 14:22:06,946 [salt.state       :1803][INFO    ][9571] Executing state rabbitmq_plugin.enabled for [amqp_client]
2018-09-29 14:22:06,947 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmq-plugins', 'list', '-m', '-e'] as user 'root' in directory '/root'
2018-09-29 14:22:08,015 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmq-plugins', 'enable', 'amqp_client'] as user 'root' in directory '/root'
2018-09-29 14:22:09,135 [salt.state       :290 ][INFO    ][9571] {'new': 'amqp_client', 'old': ''}
2018-09-29 14:22:09,136 [salt.state       :1941][INFO    ][9571] Completed state [amqp_client] at time 14:22:09.136583 duration_in_ms=2190.972
2018-09-29 14:22:09,138 [salt.state       :1770][INFO    ][9571] Running state [rabbitmq_management] at time 14:22:09.137977
2018-09-29 14:22:09,138 [salt.state       :1803][INFO    ][9571] Executing state rabbitmq_plugin.enabled for [rabbitmq_management]
2018-09-29 14:22:09,140 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmq-plugins', 'list', '-m', '-e'] as user 'root' in directory '/root'
2018-09-29 14:22:10,185 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmq-plugins', 'enable', 'rabbitmq_management'] as user 'root' in directory '/root'
2018-09-29 14:22:11,920 [salt.state       :290 ][INFO    ][9571] {'new': 'rabbitmq_management', 'old': ''}
2018-09-29 14:22:11,920 [salt.state       :1941][INFO    ][9571] Completed state [rabbitmq_management] at time 14:22:11.920673 duration_in_ms=2782.696
2018-09-29 14:22:11,923 [salt.state       :1770][INFO    ][9571] Running state [/openstack] at time 14:22:11.923918
2018-09-29 14:22:11,924 [salt.state       :1803][INFO    ][9571] Executing state rabbitmq_vhost.present for [/openstack]
2018-09-29 14:22:11,925 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmqctl', 'list_vhosts', '-q'] as user 'root' in directory '/root'
2018-09-29 14:22:12,945 [salt.state       :290 ][INFO    ][9571] Virtual Host '/openstack' already exists.
2018-09-29 14:22:12,945 [salt.state       :1941][INFO    ][9571] Completed state [/openstack] at time 14:22:12.945854 duration_in_ms=1021.934
2018-09-29 14:22:12,949 [salt.state       :1770][INFO    ][9571] Running state [openstack] at time 14:22:12.949347
2018-09-29 14:22:12,949 [salt.state       :1803][INFO    ][9571] Executing state rabbitmq_user.present for [openstack]
2018-09-29 14:22:12,950 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmqctl', 'list_users', '-q'] as user 'root' in directory '/root'
2018-09-29 14:22:13,614 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929142213598141
2018-09-29 14:22:13,632 [salt.minion      :1431][INFO    ][12942] Starting a new job with PID 12942
2018-09-29 14:22:13,649 [salt.minion      :1708][INFO    ][12942] Returning information for job: 20180929142213598141
2018-09-29 14:22:13,974 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmqctl', 'status'] as user 'root' in directory '/root'
2018-09-29 14:22:17,010 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmqctl', 'list_user_permissions', 'openstack', '-q'] as user 'root' in directory '/root'
2018-09-29 14:22:18,058 [salt.state       :290 ][INFO    ][9571] Made the following changes:
'password' changed from 'absent' to 'Set password.'

2018-09-29 14:22:18,059 [salt.state       :1941][INFO    ][9571] Completed state [openstack] at time 14:22:18.059360 duration_in_ms=5110.012
2018-09-29 14:22:18,062 [salt.state       :1770][INFO    ][9571] Running state [Ceilometer] at time 14:22:18.062287
2018-09-29 14:22:18,062 [salt.state       :1803][INFO    ][9571] Executing state rabbitmq_policy.present for [Ceilometer]
2018-09-29 14:22:18,063 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmqctl', 'list_policies', '-q', '-p', '/openstack'] as user 'root' in directory '/root'
2018-09-29 14:22:19,062 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] 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-09-29 14:22:20,094 [salt.state       :290 ][INFO    ][9571] {'new': ['Definition'], 'old': {'apply_to': 'all', 'priority': '10', 'definition': '{"ha-mode":"all","message-ttl":10800000}', 'pattern': '^metering.sample$'}}
2018-09-29 14:22:20,095 [salt.state       :1941][INFO    ][9571] Completed state [Ceilometer] at time 14:22:20.095311 duration_in_ms=2033.022
2018-09-29 14:22:20,097 [salt.state       :1770][INFO    ][9571] Running state [HA] at time 14:22:20.097134
2018-09-29 14:22:20,097 [salt.state       :1803][INFO    ][9571] Executing state rabbitmq_policy.present for [HA]
2018-09-29 14:22:20,098 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmqctl', 'list_policies', '-q', '-p', '/openstack'] as user 'root' in directory '/root'
2018-09-29 14:22:21,130 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmqctl', 'set_policy', '-p', '/openstack', 'HA', '^(?!amq\\.).*', '{"ha-mode": "all", "message-ttl": 120000}'] as user 'root' in directory '/root'
2018-09-29 14:22:22,180 [salt.state       :290 ][INFO    ][9571] {'new': ['Pattern', 'Definition'], 'old': {'apply_to': 'all', 'priority': '0', 'definition': '{"ha-mode":"all","message-ttl":120000}', 'pattern': '^(?!amq\\\\.).*'}}
2018-09-29 14:22:22,181 [salt.state       :1941][INFO    ][9571] Completed state [HA] at time 14:22:22.181279 duration_in_ms=2084.145
2018-09-29 14:22:22,181 [salt.state       :1770][INFO    ][9571] Running state [guest] at time 14:22:22.181734
2018-09-29 14:22:22,182 [salt.state       :1803][INFO    ][9571] Executing state rabbitmq_user.present for [guest]
2018-09-29 14:22:22,183 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmqctl', 'list_users', '-q'] as user 'root' in directory '/root'
2018-09-29 14:22:23,201 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmqctl', 'status'] as user 'root' in directory '/root'
2018-09-29 14:22:23,654 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929142223634118
2018-09-29 14:22:23,669 [salt.minion      :1431][INFO    ][15372] Starting a new job with PID 15372
2018-09-29 14:22:23,685 [salt.minion      :1708][INFO    ][15372] Returning information for job: 20180929142223634118
2018-09-29 14:22:26,309 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmqctl', 'list_user_permissions', 'guest', '-q'] as user 'root' in directory '/root'
2018-09-29 14:22:27,314 [salt.state       :290 ][INFO    ][9571] Made the following changes:
'password' changed from 'absent' to 'Set password.'

2018-09-29 14:22:27,314 [salt.state       :1941][INFO    ][9571] Completed state [guest] at time 14:22:27.314528 duration_in_ms=5132.793
2018-09-29 14:22:27,315 [salt.state       :1770][INFO    ][9571] Running state [HA] at time 14:22:27.315337
2018-09-29 14:22:27,316 [salt.state       :1803][INFO    ][9571] Executing state rabbitmq_policy.present for [HA]
2018-09-29 14:22:27,317 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmqctl', 'list_policies', '-q', '-p', '/'] as user 'root' in directory '/root'
2018-09-29 14:22:28,351 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmqctl', 'set_policy', '-p', '/', 'HA', '^(?!amq\\.).*', '{"ha-mode": "all", "message-ttl": 120000}'] as user 'root' in directory '/root'
2018-09-29 14:22:29,370 [salt.state       :290 ][INFO    ][9571] {'new': ['Pattern', 'Definition'], 'old': {'apply_to': 'all', 'priority': '0', 'definition': '{"ha-mode":"all","message-ttl":120000}', 'pattern': '^(?!amq\\\\.).*'}}
2018-09-29 14:22:29,371 [salt.state       :1941][INFO    ][9571] Completed state [HA] at time 14:22:29.370990 duration_in_ms=2055.652
2018-09-29 14:22:29,372 [salt.state       :1770][INFO    ][9571] Running state [admin] at time 14:22:29.372240
2018-09-29 14:22:29,372 [salt.state       :1803][INFO    ][9571] Executing state rabbitmq_user.present for [admin]
2018-09-29 14:22:29,373 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmqctl', 'list_users', '-q'] as user 'root' in directory '/root'
2018-09-29 14:22:30,406 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmqctl', 'status'] as user 'root' in directory '/root'
2018-09-29 14:22:33,469 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmqctl', 'list_users', '-q'] as user 'root' in directory '/root'
2018-09-29 14:22:33,673 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929142233656125
2018-09-29 14:22:33,693 [salt.minion      :1431][INFO    ][17792] Starting a new job with PID 17792
2018-09-29 14:22:33,709 [salt.minion      :1708][INFO    ][17792] Returning information for job: 20180929142233656125
2018-09-29 14:22:34,487 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmqctl', 'list_user_permissions', 'admin', '-q'] as user 'root' in directory '/root'
2018-09-29 14:22:35,499 [salt.state       :290 ][INFO    ][9571] Made the following changes:
'password' changed from 'absent' to 'Set password.'

2018-09-29 14:22:35,501 [salt.state       :1941][INFO    ][9571] Completed state [admin] at time 14:22:35.501243 duration_in_ms=6129.016
2018-09-29 14:22:35,506 [salt.state       :1770][INFO    ][9571] Running state [rabbit@master] at time 14:22:35.506771
2018-09-29 14:22:35,507 [salt.state       :1803][INFO    ][9571] Executing state rabbitmq_cluster.join for [rabbit@master]
2018-09-29 14:22:35,508 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9571] Executing command ['/usr/sbin/rabbitmqctl', 'cluster_status'] as user 'root' in directory '/root'
2018-09-29 14:22:36,614 [salt.state       :290 ][INFO    ][9571] Already in cluster
2018-09-29 14:22:36,615 [salt.state       :1941][INFO    ][9571] Completed state [rabbit@master] at time 14:22:36.615273 duration_in_ms=1108.502
2018-09-29 14:22:36,620 [salt.minion      :1708][INFO    ][9571] Returning information for job: 20180929142012765242
2018-09-29 14:22:45,422 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command cmd.run with jid 20180929142245406532
2018-09-29 14:22:45,441 [salt.minion      :1431][INFO    ][18343] Starting a new job with PID 18343
2018-09-29 14:22:45,453 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][18343] Executing command 'rabbitmqctl cluster_status' in directory '/root'
2018-09-29 14:22:46,427 [salt.minion      :1708][INFO    ][18343] Returning information for job: 20180929142245406532
2018-09-29 14:29:21,522 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command state.sls with jid 20180929142921506864
2018-09-29 14:29:21,550 [salt.minion      :1431][INFO    ][18628] Starting a new job with PID 18628
2018-09-29 14:29:23,192 [salt.state       :905 ][INFO    ][18628] Loading fresh modules for state activity
2018-09-29 14:29:23,265 [salt.fileclient  :1215][INFO    ][18628] Fetching file from saltenv 'base', ** done ** 'haproxy/init.sls'
2018-09-29 14:29:23,297 [salt.fileclient  :1215][INFO    ][18628] Fetching file from saltenv 'base', ** done ** 'haproxy/proxy.sls'
2018-09-29 14:29:24,381 [salt.state       :1770][INFO    ][18628] Running state [haproxy] at time 14:29:24.381823
2018-09-29 14:29:24,382 [salt.state       :1803][INFO    ][18628] Executing state pkg.installed for [haproxy]
2018-09-29 14:29:24,382 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18628] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-09-29 14:29:24,842 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18628] Executing command ['apt-cache', '-q', 'policy', 'haproxy'] in directory '/root'
2018-09-29 14:29:24,955 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18628] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-09-29 14:29:27,113 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18628] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-09-29 14:29:27,136 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18628] 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-09-29 14:29:30,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18628] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-09-29 14:29:30,359 [salt.state       :290 ][INFO    ][18628] 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-09-29 14:29:30,380 [salt.state       :905 ][INFO    ][18628] Loading fresh modules for state activity
2018-09-29 14:29:30,421 [salt.state       :1941][INFO    ][18628] Completed state [haproxy] at time 14:29:30.421250 duration_in_ms=6039.427
2018-09-29 14:29:30,426 [salt.state       :1770][INFO    ][18628] Running state [/etc/default/haproxy] at time 14:29:30.426608
2018-09-29 14:29:30,426 [salt.state       :1803][INFO    ][18628] Executing state file.managed for [/etc/default/haproxy]
2018-09-29 14:29:30,469 [salt.fileclient  :1215][INFO    ][18628] Fetching file from saltenv 'base', ** done ** 'haproxy/files/haproxy.default'
2018-09-29 14:29:30,473 [salt.state       :290 ][INFO    ][18628] 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-09-29 14:29:30,473 [salt.state       :1941][INFO    ][18628] Completed state [/etc/default/haproxy] at time 14:29:30.473819 duration_in_ms=47.202
2018-09-29 14:29:30,474 [salt.state       :1770][INFO    ][18628] Running state [/etc/haproxy/haproxy.cfg] at time 14:29:30.474204
2018-09-29 14:29:30,474 [salt.state       :1803][INFO    ][18628] Executing state file.managed for [/etc/haproxy/haproxy.cfg]
2018-09-29 14:29:30,503 [salt.fileclient  :1215][INFO    ][18628] Fetching file from saltenv 'base', ** done ** 'haproxy/files/haproxy.cfg'
2018-09-29 14:29:30,685 [salt.state       :290 ][INFO    ][18628] 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-09-29 14:29:30,685 [salt.state       :1941][INFO    ][18628] Completed state [/etc/haproxy/haproxy.cfg] at time 14:29:30.685604 duration_in_ms=211.4
2018-09-29 14:29:30,685 [salt.state       :1770][INFO    ][18628] Running state [/etc/haproxy/ssl] at time 14:29:30.685946
2018-09-29 14:29:30,686 [salt.state       :1803][INFO    ][18628] Executing state file.directory for [/etc/haproxy/ssl]
2018-09-29 14:29:30,687 [salt.state       :290 ][INFO    ][18628] {'/etc/haproxy/ssl': 'New Dir'}
2018-09-29 14:29:30,687 [salt.state       :1941][INFO    ][18628] Completed state [/etc/haproxy/ssl] at time 14:29:30.687918 duration_in_ms=1.972
2018-09-29 14:29:30,689 [salt.state       :1770][INFO    ][18628] Running state [/etc/haproxy/errors/429.http11] at time 14:29:30.689060
2018-09-29 14:29:30,689 [salt.state       :1803][INFO    ][18628] Executing state file.managed for [/etc/haproxy/errors/429.http11]
2018-09-29 14:29:30,709 [salt.fileclient  :1215][INFO    ][18628] Fetching file from saltenv 'base', ** done ** 'haproxy/files/errors/429.http11'
2018-09-29 14:29:30,718 [salt.state       :290 ][INFO    ][18628] File changed:
New file
2018-09-29 14:29:30,718 [salt.state       :1941][INFO    ][18628] Completed state [/etc/haproxy/errors/429.http11] at time 14:29:30.718225 duration_in_ms=29.165
2018-09-29 14:29:30,719 [salt.state       :1770][INFO    ][18628] Running state [haproxy_status_packages] at time 14:29:30.719166
2018-09-29 14:29:30,719 [salt.state       :1803][INFO    ][18628] Executing state pkg.installed for [haproxy_status_packages]
2018-09-29 14:29:31,315 [salt.state       :290 ][INFO    ][18628] All specified packages are already installed
2018-09-29 14:29:31,316 [salt.state       :1941][INFO    ][18628] Completed state [haproxy_status_packages] at time 14:29:31.316832 duration_in_ms=597.665
2018-09-29 14:29:31,317 [salt.state       :1770][INFO    ][18628] Running state [/usr/bin/haproxy-status.sh] at time 14:29:31.317600
2018-09-29 14:29:31,317 [salt.state       :1803][INFO    ][18628] Executing state file.managed for [/usr/bin/haproxy-status.sh]
2018-09-29 14:29:31,341 [salt.fileclient  :1215][INFO    ][18628] Fetching file from saltenv 'base', ** done ** 'haproxy/files/haproxy-status.sh'
2018-09-29 14:29:31,369 [salt.state       :290 ][INFO    ][18628] File changed:
New file
2018-09-29 14:29:31,370 [salt.state       :1941][INFO    ][18628] Completed state [/usr/bin/haproxy-status.sh] at time 14:29:31.370036 duration_in_ms=52.436
2018-09-29 14:29:31,370 [salt.state       :1770][INFO    ][18628] Running state [net.ipv4.ip_nonlocal_bind] at time 14:29:31.370596
2018-09-29 14:29:31,370 [salt.state       :1803][INFO    ][18628] Executing state sysctl.present for [net.ipv4.ip_nonlocal_bind]
2018-09-29 14:29:31,371 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18628] Executing command 'sysctl -w net.ipv4.ip_nonlocal_bind="1"' in directory '/root'
2018-09-29 14:29:31,388 [salt.state       :290 ][INFO    ][18628] {'net.ipv4.ip_nonlocal_bind': 1}
2018-09-29 14:29:31,388 [salt.state       :1941][INFO    ][18628] Completed state [net.ipv4.ip_nonlocal_bind] at time 14:29:31.388916 duration_in_ms=18.319
2018-09-29 14:29:31,390 [salt.state       :1770][INFO    ][18628] Running state [haproxy] at time 14:29:31.390814
2018-09-29 14:29:31,391 [salt.state       :1803][INFO    ][18628] Executing state service.running for [haproxy]
2018-09-29 14:29:31,391 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18628] Executing command ['systemctl', 'status', 'haproxy.service', '-n', '0'] in directory '/root'
2018-09-29 14:29:31,414 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18628] Executing command ['systemctl', 'is-active', 'haproxy.service'] in directory '/root'
2018-09-29 14:29:31,433 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18628] Executing command ['systemctl', 'is-enabled', 'haproxy.service'] in directory '/root'
2018-09-29 14:29:31,450 [salt.state       :290 ][INFO    ][18628] The service haproxy is already running
2018-09-29 14:29:31,450 [salt.state       :1941][INFO    ][18628] Completed state [haproxy] at time 14:29:31.450895 duration_in_ms=60.081
2018-09-29 14:29:31,451 [salt.state       :1770][INFO    ][18628] Running state [haproxy] at time 14:29:31.451176
2018-09-29 14:29:31,451 [salt.state       :1803][INFO    ][18628] Executing state service.mod_watch for [haproxy]
2018-09-29 14:29:31,452 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18628] Executing command ['systemctl', 'is-active', 'haproxy.service'] in directory '/root'
2018-09-29 14:29:31,469 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18628] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'haproxy.service'] in directory '/root'
2018-09-29 14:29:31,526 [salt.state       :290 ][INFO    ][18628] {'haproxy': True}
2018-09-29 14:29:31,526 [salt.state       :1941][INFO    ][18628] Completed state [haproxy] at time 14:29:31.526391 duration_in_ms=75.215
2018-09-29 14:29:31,529 [salt.minion      :1708][INFO    ][18628] Returning information for job: 20180929142921506864
2018-09-29 14:29:31,547 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command saltutil.find_job with jid 20180929142931526864
2018-09-29 14:29:31,560 [salt.minion      :1431][INFO    ][19604] Starting a new job with PID 19604
2018-09-29 14:29:31,576 [salt.minion      :1708][INFO    ][19604] Returning information for job: 20180929142931526864
2018-09-29 14:29:36,241 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command service.status with jid 20180929142936223235
2018-09-29 14:29:36,261 [salt.minion      :1431][INFO    ][19621] Starting a new job with PID 19621
2018-09-29 14:29:36,689 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][19621] Executing command ['systemctl', 'status', 'haproxy.service', '-n', '0'] in directory '/root'
2018-09-29 14:29:36,707 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][19621] Executing command ['systemctl', 'is-active', 'haproxy.service'] in directory '/root'
2018-09-29 14:29:36,723 [salt.minion      :1708][INFO    ][19621] Returning information for job: 20180929142936223235
2018-09-29 14:29:37,910 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command service.restart with jid 20180929142937896363
2018-09-29 14:29:37,929 [salt.minion      :1431][INFO    ][19631] Starting a new job with PID 19631
2018-09-29 14:29:38,367 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][19631] Executing command ['systemctl', 'status', 'rsyslog.service', '-n', '0'] in directory '/root'
2018-09-29 14:29:38,396 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][19631] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'rsyslog.service'] in directory '/root'
2018-09-29 14:29:38,434 [salt.minion      :1708][INFO    ][19631] Returning information for job: 20180929142937896363
2018-09-29 15:02:01,138 [salt.minion      :1307][INFO    ][2903] User sudo_ubuntu Executing command cp.push_dir with jid 20180929150201120453
2018-09-29 15:02:01,164 [salt.minion      :1431][INFO    ][20147] Starting a new job with PID 20147
