2018-01-23 08:55:43,549 [salt.utils       ][ERROR   ][1288] DNS lookup or connection check of 'salt' failed.
2018-01-23 08:55:43,550 [salt.minion      ][ERROR   ][1288] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2018-01-23 08:55:44,142 [salt.utils.parsers][WARNING ][1288] Minion received a SIGTERM. Exiting.
2018-01-23 08:56:27,774 [salt.minion      ][ERROR   ][1373] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2018-01-23 08:56:50,953 [salt.crypt       ][ERROR   ][1373] The Salt Master has cached the public key for this node, this salt minion will wait for 10 seconds before attempting to re-authenticate
2018-01-23 08:59:36,039 [salt.utils.parsers][WARNING ][13641] Minion received a SIGTERM. Exiting.
2018-01-23 09:00:01,713 [py.warnings      ][WARNING ][21083] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2018-01-23 09:00:39,708 [salt.loaded.int.states.file][WARNING ][21083] State for file: /etc/pki/ca/salt_master_ca/ca.crt - 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-01-23 09:00:47,452 [salt.loaded.int.module.cmdmod][ERROR   ][21083] Command 'while true; do salt-call saltutil.running|grep fun: && continue; salt-call --local service.restart salt-minion; break; done' failed with return code: None
2018-01-23 09:00:50,284 [salt.loaded.int.module.cmdmod][INFO    ][27302] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2018-01-23 09:00:50,297 [salt.loaded.int.module.cmdmod][INFO    ][27302] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2018-01-23 09:00:50,318 [salt.loaded.int.module.cmdmod][INFO    ][27302] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2018-01-23 09:00:50,357 [salt.utils.parsers][WARNING ][20729] Minion received a SIGTERM. Exiting.
2018-01-23 09:00:50,854 [salt.cli.daemons ][INFO    ][27351] Setting up the Salt Minion "cfg01.baremetal-mcp-pike-odl-ha.local"
2018-01-23 09:00:50,966 [salt.cli.daemons ][INFO    ][27351] Starting up the Salt Minion
2018-01-23 09:00:50,967 [salt.utils.event ][INFO    ][27351] Starting pull socket on /var/run/salt/minion/minion_event_c34ff6f193_pull.ipc
2018-01-23 09:00:51,526 [salt.minion      ][INFO    ][27351] Creating minion process manager
2018-01-23 09:00:52,874 [salt.loader.10.20.0.2.int.module.cmdmod][INFO    ][27351] Executing command ['date', '+%z'] in directory '/root'
2018-01-23 09:00:52,891 [salt.utils.schedule][INFO    ][27351] Updating job settings for scheduled job: __mine_interval
2018-01-23 09:00:52,894 [salt.minion      ][INFO    ][27351] Added mine.update to scheduler
2018-01-23 09:00:52,899 [salt.minion      ][INFO    ][27351] Minion is starting as user 'root'
2018-01-23 09:00:52,934 [salt.minion      ][INFO    ][27351] Minion is ready to receive requests!
2018-01-23 09:00:53,936 [salt.utils.schedule][INFO    ][27351] Running scheduled job: __mine_interval
2018-01-23 09:01:01,232 [salt.minion      ][INFO    ][27351] User sudo_ubuntu Executing command saltutil.find_job with jid 20180123090101216431
2018-01-23 09:01:02,122 [salt.minion      ][INFO    ][27462] Starting a new job with PID 27462
2018-01-23 09:01:02,136 [salt.minion      ][INFO    ][27462] Returning information for job: 20180123090101216431
2018-01-23 09:01:11,984 [salt.minion      ][INFO    ][27351] User sudo_ubuntu Executing command state.apply with jid 20180123090111972544
2018-01-23 09:01:12,008 [salt.minion      ][INFO    ][27494] Starting a new job with PID 27494
2018-01-23 09:01:12,697 [salt.state       ][INFO    ][27494] Loading fresh modules for state activity
2018-01-23 09:01:14,096 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command 'reclass --version' in directory '/root'
2018-01-23 09:01:14,499 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command 'reclass --version' in directory '/root'
2018-01-23 09:01:14,823 [py.warnings      ][WARNING ][27494] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2018-01-23 09:01:14,824 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command 'salt-minion --version' in directory '/root'
2018-01-23 09:01:15,083 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command 'salt --version' in directory '/root'
2018-01-23 09:01:15,377 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command 'salt-minion --version' in directory '/root'
2018-01-23 09:01:15,644 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command 'salt --version' in directory '/root'
2018-01-23 09:01:17,333 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command 'reclass --version' in directory '/root'
2018-01-23 09:01:18,412 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command 'reclass --version' in directory '/root'
2018-01-23 09:01:18,682 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command 'salt-minion --version' in directory '/root'
2018-01-23 09:01:18,944 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command 'salt --version' in directory '/root'
2018-01-23 09:01:19,227 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command 'salt-minion --version' in directory '/root'
2018-01-23 09:01:19,487 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command 'salt --version' in directory '/root'
2018-01-23 09:01:20,978 [salt.state       ][INFO    ][27494] Running state [salt-master] at time 09:01:20.978161
2018-01-23 09:01:20,978 [salt.state       ][INFO    ][27494] Executing state pkg.installed for salt-master
2018-01-23 09:01:20,979 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2018-01-23 09:01:21,334 [salt.state       ][INFO    ][27494] All specified packages are already installed
2018-01-23 09:01:21,334 [salt.state       ][INFO    ][27494] Completed state [salt-master] at time 09:01:21.334865 duration_in_ms=356.704
2018-01-23 09:01:21,338 [salt.state       ][INFO    ][27494] Running state [/etc/salt/master.d/master.conf] at time 09:01:21.338924
2018-01-23 09:01:21,339 [salt.state       ][INFO    ][27494] Executing state file.managed for /etc/salt/master.d/master.conf
2018-01-23 09:01:21,561 [salt.state       ][INFO    ][27494] File /etc/salt/master.d/master.conf is in the correct state
2018-01-23 09:01:21,561 [salt.state       ][INFO    ][27494] Completed state [/etc/salt/master.d/master.conf] at time 09:01:21.561795 duration_in_ms=222.871
2018-01-23 09:01:21,563 [salt.state       ][INFO    ][27494] Running state [/etc/salt/master.d/_acl.conf] at time 09:01:21.563014
2018-01-23 09:01:21,563 [salt.state       ][INFO    ][27494] Executing state file.managed for /etc/salt/master.d/_acl.conf
2018-01-23 09:01:21,647 [salt.state       ][INFO    ][27494] File /etc/salt/master.d/_acl.conf is in the correct state
2018-01-23 09:01:21,648 [salt.state       ][INFO    ][27494] Completed state [/etc/salt/master.d/_acl.conf] at time 09:01:21.648232 duration_in_ms=85.218
2018-01-23 09:01:21,649 [salt.state       ][INFO    ][27494] Running state [/etc/salt/master.d/_peer.conf] at time 09:01:21.649474
2018-01-23 09:01:21,649 [salt.state       ][INFO    ][27494] Executing state file.managed for /etc/salt/master.d/_peer.conf
2018-01-23 09:01:21,741 [salt.state       ][INFO    ][27494] File /etc/salt/master.d/_peer.conf is in the correct state
2018-01-23 09:01:21,741 [salt.state       ][INFO    ][27494] Completed state [/etc/salt/master.d/_peer.conf] at time 09:01:21.741720 duration_in_ms=92.246
2018-01-23 09:01:21,744 [salt.state       ][INFO    ][27494] Running state [salt-master] at time 09:01:21.744631
2018-01-23 09:01:21,744 [salt.state       ][INFO    ][27494] Executing state service.running for salt-master
2018-01-23 09:01:21,745 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['systemctl', 'status', 'salt-master.service', '-n', '0'] in directory '/root'
2018-01-23 09:01:21,787 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['systemctl', 'is-active', 'salt-master.service'] in directory '/root'
2018-01-23 09:01:21,806 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['systemctl', 'is-enabled', 'salt-master.service'] in directory '/root'
2018-01-23 09:01:21,824 [salt.state       ][INFO    ][27494] The service salt-master is already running
2018-01-23 09:01:21,825 [salt.state       ][INFO    ][27494] Completed state [salt-master] at time 09:01:21.825393 duration_in_ms=80.761
2018-01-23 09:01:21,826 [salt.state       ][INFO    ][27494] Running state [/srv/salt/env] at time 09:01:21.826631
2018-01-23 09:01:21,827 [salt.state       ][INFO    ][27494] Executing state file.directory for /srv/salt/env
2018-01-23 09:01:21,829 [salt.state       ][INFO    ][27494] Directory /srv/salt/env is in the correct state
2018-01-23 09:01:21,830 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/env] at time 09:01:21.829982 duration_in_ms=3.35
2018-01-23 09:01:21,832 [salt.state       ][INFO    ][27494] Running state [root] at time 09:01:21.832702
2018-01-23 09:01:21,833 [salt.state       ][INFO    ][27494] Executing state user.present for root
2018-01-23 09:01:21,835 [salt.state       ][INFO    ][27494] User root is present and up to date
2018-01-23 09:01:21,835 [salt.state       ][INFO    ][27494] Completed state [root] at time 09:01:21.835950 duration_in_ms=3.248
2018-01-23 09:01:21,837 [salt.state       ][INFO    ][27494] Running state [/root] at time 09:01:21.837597
2018-01-23 09:01:21,838 [salt.state       ][INFO    ][27494] Executing state file.directory for /root
2018-01-23 09:01:21,838 [salt.state       ][INFO    ][27494] Directory /root is in the correct state
2018-01-23 09:01:21,839 [salt.state       ][INFO    ][27494] Completed state [/root] at time 09:01:21.839289 duration_in_ms=1.692
2018-01-23 09:01:21,839 [salt.state       ][INFO    ][27494] Running state [/etc/sudoers.d/90-salt-user-root] at time 09:01:21.839726
2018-01-23 09:01:21,840 [salt.state       ][INFO    ][27494] Executing state file.absent for /etc/sudoers.d/90-salt-user-root
2018-01-23 09:01:21,840 [salt.state       ][INFO    ][27494] File /etc/sudoers.d/90-salt-user-root is not present
2018-01-23 09:01:21,841 [salt.state       ][INFO    ][27494] Completed state [/etc/sudoers.d/90-salt-user-root] at time 09:01:21.841101 duration_in_ms=1.375
2018-01-23 09:01:21,841 [salt.state       ][INFO    ][27494] Running state [salt] at time 09:01:21.841539
2018-01-23 09:01:21,841 [salt.state       ][INFO    ][27494] Executing state user.present for salt
2018-01-23 09:01:21,843 [salt.state       ][INFO    ][27494] User salt is present and up to date
2018-01-23 09:01:21,843 [salt.state       ][INFO    ][27494] Completed state [salt] at time 09:01:21.843597 duration_in_ms=2.058
2018-01-23 09:01:21,844 [salt.state       ][INFO    ][27494] Running state [/var/tmp/salt] at time 09:01:21.844914
2018-01-23 09:01:21,845 [salt.state       ][INFO    ][27494] Executing state file.directory for /var/tmp/salt
2018-01-23 09:01:21,846 [salt.state       ][INFO    ][27494] Directory /var/tmp/salt is in the correct state
2018-01-23 09:01:21,847 [salt.state       ][INFO    ][27494] Completed state [/var/tmp/salt] at time 09:01:21.847073 duration_in_ms=2.159
2018-01-23 09:01:21,847 [salt.state       ][INFO    ][27494] Running state [/etc/sudoers.d/90-salt-user-salt] at time 09:01:21.847520
2018-01-23 09:01:21,847 [salt.state       ][INFO    ][27494] Executing state file.absent for /etc/sudoers.d/90-salt-user-salt
2018-01-23 09:01:21,848 [salt.state       ][INFO    ][27494] File /etc/sudoers.d/90-salt-user-salt is not present
2018-01-23 09:01:21,848 [salt.state       ][INFO    ][27494] Completed state [/etc/sudoers.d/90-salt-user-salt] at time 09:01:21.848853 duration_in_ms=1.333
2018-01-23 09:01:21,849 [salt.state       ][INFO    ][27494] Running state [ubuntu] at time 09:01:21.849329
2018-01-23 09:01:21,849 [salt.state       ][INFO    ][27494] Executing state user.present for ubuntu
2018-01-23 09:01:21,851 [salt.state       ][INFO    ][27494] User ubuntu is present and up to date
2018-01-23 09:01:21,852 [salt.state       ][INFO    ][27494] Completed state [ubuntu] at time 09:01:21.851963 duration_in_ms=2.634
2018-01-23 09:01:21,853 [salt.state       ][INFO    ][27494] Running state [/home/ubuntu] at time 09:01:21.853350
2018-01-23 09:01:21,853 [salt.state       ][INFO    ][27494] Executing state file.directory for /home/ubuntu
2018-01-23 09:01:21,854 [salt.state       ][INFO    ][27494] Directory /home/ubuntu is in the correct state
2018-01-23 09:01:21,855 [salt.state       ][INFO    ][27494] Completed state [/home/ubuntu] at time 09:01:21.855138 duration_in_ms=1.788
2018-01-23 09:01:21,856 [salt.state       ][INFO    ][27494] Running state [/etc/sudoers.d/90-salt-user-ubuntu] at time 09:01:21.856325
2018-01-23 09:01:21,856 [salt.state       ][INFO    ][27494] Executing state file.managed for /etc/sudoers.d/90-salt-user-ubuntu
2018-01-23 09:01:21,875 [salt.state       ][INFO    ][27494] File /etc/sudoers.d/90-salt-user-ubuntu is in the correct state
2018-01-23 09:01:21,876 [salt.state       ][INFO    ][27494] Completed state [/etc/sudoers.d/90-salt-user-ubuntu] at time 09:01:21.876119 duration_in_ms=19.794
2018-01-23 09:01:21,876 [salt.state       ][INFO    ][27494] Running state [git-core] at time 09:01:21.876753
2018-01-23 09:01:21,877 [salt.state       ][INFO    ][27494] Executing state pkg.installed for git-core
2018-01-23 09:01:21,886 [salt.state       ][INFO    ][27494] All specified packages are already installed
2018-01-23 09:01:21,887 [salt.state       ][INFO    ][27494] Completed state [git-core] at time 09:01:21.887118 duration_in_ms=10.365
2018-01-23 09:01:21,887 [salt.state       ][INFO    ][27494] Running state [/usr/share/salt-formulas/env/_modules] at time 09:01:21.887561
2018-01-23 09:01:21,888 [salt.state       ][INFO    ][27494] Executing state file.directory for /usr/share/salt-formulas/env/_modules
2018-01-23 09:01:21,888 [salt.state       ][INFO    ][27494] Directory /usr/share/salt-formulas/env/_modules is in the correct state
2018-01-23 09:01:21,889 [salt.state       ][INFO    ][27494] Completed state [/usr/share/salt-formulas/env/_modules] at time 09:01:21.889200 duration_in_ms=1.639
2018-01-23 09:01:21,889 [salt.state       ][INFO    ][27494] Running state [/usr/share/salt-formulas/env/_states] at time 09:01:21.889630
2018-01-23 09:01:21,890 [salt.state       ][INFO    ][27494] Executing state file.directory for /usr/share/salt-formulas/env/_states
2018-01-23 09:01:21,890 [salt.state       ][INFO    ][27494] Directory /usr/share/salt-formulas/env/_states is in the correct state
2018-01-23 09:01:21,891 [salt.state       ][INFO    ][27494] Completed state [/usr/share/salt-formulas/env/_states] at time 09:01:21.891201 duration_in_ms=1.571
2018-01-23 09:01:21,891 [salt.state       ][INFO    ][27494] Running state [/usr/share/salt-formulas/env/_grains] at time 09:01:21.891627
2018-01-23 09:01:21,892 [salt.state       ][INFO    ][27494] Executing state file.directory for /usr/share/salt-formulas/env/_grains
2018-01-23 09:01:21,892 [salt.state       ][INFO    ][27494] Directory /usr/share/salt-formulas/env/_grains is in the correct state
2018-01-23 09:01:21,893 [salt.state       ][INFO    ][27494] Completed state [/usr/share/salt-formulas/env/_grains] at time 09:01:21.893226 duration_in_ms=1.599
2018-01-23 09:01:21,893 [salt.state       ][INFO    ][27494] Running state [/usr/share/salt-formulas/env/_formulas] at time 09:01:21.893663
2018-01-23 09:01:21,894 [salt.state       ][INFO    ][27494] Executing state file.directory for /usr/share/salt-formulas/env/_formulas
2018-01-23 09:01:21,894 [salt.state       ][INFO    ][27494] Directory /usr/share/salt-formulas/env/_formulas is in the correct state
2018-01-23 09:01:21,895 [salt.state       ][INFO    ][27494] Completed state [/usr/share/salt-formulas/env/_formulas] at time 09:01:21.895278 duration_in_ms=1.615
2018-01-23 09:01:21,895 [salt.state       ][INFO    ][27494] Running state [/usr/share/salt-formulas/env/_engines] at time 09:01:21.895706
2018-01-23 09:01:21,896 [salt.state       ][INFO    ][27494] Executing state file.directory for /usr/share/salt-formulas/env/_engines
2018-01-23 09:01:21,896 [salt.state       ][INFO    ][27494] Directory /usr/share/salt-formulas/env/_engines is in the correct state
2018-01-23 09:01:21,897 [salt.state       ][INFO    ][27494] Completed state [/usr/share/salt-formulas/env/_engines] at time 09:01:21.897298 duration_in_ms=1.592
2018-01-23 09:01:21,897 [salt.state       ][INFO    ][27494] Running state [/usr/share/salt-formulas/env/_runners] at time 09:01:21.897725
2018-01-23 09:01:21,898 [salt.state       ][INFO    ][27494] Executing state file.directory for /usr/share/salt-formulas/env/_runners
2018-01-23 09:01:21,898 [salt.state       ][INFO    ][27494] Directory /usr/share/salt-formulas/env/_runners is in the correct state
2018-01-23 09:01:21,899 [salt.state       ][INFO    ][27494] Completed state [/usr/share/salt-formulas/env/_runners] at time 09:01:21.899317 duration_in_ms=1.592
2018-01-23 09:01:21,901 [salt.state       ][INFO    ][27494] Running state [/srv/salt/env/prd] at time 09:01:21.901064
2018-01-23 09:01:21,901 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/env/prd
2018-01-23 09:01:21,903 [salt.state       ][INFO    ][27494] Symlink /srv/salt/env/prd is present and owned by root:root
2018-01-23 09:01:21,903 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/env/prd] at time 09:01:21.903583 duration_in_ms=2.519
2018-01-23 09:01:21,904 [salt.state       ][INFO    ][27494] Running state [salt_master_prd_pkg_formulas] at time 09:01:21.904020
2018-01-23 09:01:21,904 [salt.state       ][INFO    ][27494] Executing state pkg.latest for salt_master_prd_pkg_formulas
2018-01-23 09:01:21,916 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-maas'] in directory '/root'
2018-01-23 09:01:21,963 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-grafana'] in directory '/root'
2018-01-23 09:01:22,015 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-xtrabackup'] in directory '/root'
2018-01-23 09:01:22,067 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-docker'] in directory '/root'
2018-01-23 09:01:22,101 [salt.minion      ][INFO    ][27351] User sudo_ubuntu Executing command saltutil.find_job with jid 20180123090122082378
2018-01-23 09:01:22,136 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-heat'] in directory '/root'
2018-01-23 09:01:22,138 [salt.minion      ][INFO    ][27633] Starting a new job with PID 27633
2018-01-23 09:01:22,155 [salt.minion      ][INFO    ][27633] Returning information for job: 20180123090122082378
2018-01-23 09:01:22,182 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-reclass'] in directory '/root'
2018-01-23 09:01:22,226 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-redis'] in directory '/root'
2018-01-23 09:01:22,275 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-nova'] in directory '/root'
2018-01-23 09:01:22,325 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-iptables'] in directory '/root'
2018-01-23 09:01:22,380 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-murano'] in directory '/root'
2018-01-23 09:01:22,431 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-glusterfs'] in directory '/root'
2018-01-23 09:01:22,474 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-backupninja'] in directory '/root'
2018-01-23 09:01:22,519 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-kubernetes'] in directory '/root'
2018-01-23 09:01:22,563 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-linux'] in directory '/root'
2018-01-23 09:01:22,615 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-git'] in directory '/root'
2018-01-23 09:01:22,666 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-apache'] in directory '/root'
2018-01-23 09:01:22,717 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-aptly'] in directory '/root'
2018-01-23 09:01:22,767 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-gerrit'] in directory '/root'
2018-01-23 09:01:22,821 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-sensu'] in directory '/root'
2018-01-23 09:01:22,872 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-aptcacher'] in directory '/root'
2018-01-23 09:01:22,920 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-glance'] in directory '/root'
2018-01-23 09:01:22,969 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-heka'] in directory '/root'
2018-01-23 09:01:23,022 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-keystone'] in directory '/root'
2018-01-23 09:01:23,076 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-ntp'] in directory '/root'
2018-01-23 09:01:23,127 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-barbican'] in directory '/root'
2018-01-23 09:01:23,179 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-aodh'] in directory '/root'
2018-01-23 09:01:23,229 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-python'] in directory '/root'
2018-01-23 09:01:23,282 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-collectd'] in directory '/root'
2018-01-23 09:01:23,332 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-neutron'] in directory '/root'
2018-01-23 09:01:23,389 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-supervisor'] in directory '/root'
2018-01-23 09:01:23,441 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-ceilometer'] in directory '/root'
2018-01-23 09:01:23,493 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-elasticsearch'] in directory '/root'
2018-01-23 09:01:23,541 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-powerdns'] in directory '/root'
2018-01-23 09:01:23,585 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-cassandra'] in directory '/root'
2018-01-23 09:01:23,630 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-libvirt'] in directory '/root'
2018-01-23 09:01:23,681 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-sahara'] in directory '/root'
2018-01-23 09:01:23,733 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-openssh'] in directory '/root'
2018-01-23 09:01:23,800 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-statsd'] in directory '/root'
2018-01-23 09:01:23,863 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-mongodb'] in directory '/root'
2018-01-23 09:01:23,913 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-kibana'] in directory '/root'
2018-01-23 09:01:23,966 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-sphinx'] in directory '/root'
2018-01-23 09:01:24,013 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-freeipa'] in directory '/root'
2018-01-23 09:01:24,071 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-octavia'] in directory '/root'
2018-01-23 09:01:24,123 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-bind'] in directory '/root'
2018-01-23 09:01:24,177 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-salt'] in directory '/root'
2018-01-23 09:01:24,229 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-etcd'] in directory '/root'
2018-01-23 09:01:24,280 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-devops-portal'] in directory '/root'
2018-01-23 09:01:24,329 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-postgresql'] in directory '/root'
2018-01-23 09:01:24,387 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-avinetworks'] in directory '/root'
2018-01-23 09:01:24,446 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-nginx'] in directory '/root'
2018-01-23 09:01:24,497 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-mysql'] in directory '/root'
2018-01-23 09:01:24,546 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-ceph'] in directory '/root'
2018-01-23 09:01:24,593 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-horizon'] in directory '/root'
2018-01-23 09:01:24,641 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-haproxy'] in directory '/root'
2018-01-23 09:01:24,697 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-java'] in directory '/root'
2018-01-23 09:01:24,747 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-billometer'] in directory '/root'
2018-01-23 09:01:24,794 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-telegraf'] in directory '/root'
2018-01-23 09:01:24,846 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-designate'] in directory '/root'
2018-01-23 09:01:24,904 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-keepalived'] in directory '/root'
2018-01-23 09:01:24,959 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-rundeck'] in directory '/root'
2018-01-23 09:01:25,016 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-ccp'] in directory '/root'
2018-01-23 09:01:25,067 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-tinyproxy'] in directory '/root'
2018-01-23 09:01:25,119 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-rabbitmq'] in directory '/root'
2018-01-23 09:01:25,175 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-openldap'] in directory '/root'
2018-01-23 09:01:25,227 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-influxdb'] in directory '/root'
2018-01-23 09:01:25,283 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-memcached'] in directory '/root'
2018-01-23 09:01:25,337 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-prometheus'] in directory '/root'
2018-01-23 09:01:25,392 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-helm'] in directory '/root'
2018-01-23 09:01:25,444 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-opencontrail'] in directory '/root'
2018-01-23 09:01:25,498 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-jenkins'] in directory '/root'
2018-01-23 09:01:25,550 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-lldp'] in directory '/root'
2018-01-23 09:01:25,603 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-nagios'] in directory '/root'
2018-01-23 09:01:25,654 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-swift'] in directory '/root'
2018-01-23 09:01:25,704 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-zookeeper'] in directory '/root'
2018-01-23 09:01:25,751 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-galera'] in directory '/root'
2018-01-23 09:01:25,809 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-graphite'] in directory '/root'
2018-01-23 09:01:25,864 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-cinder'] in directory '/root'
2018-01-23 09:01:25,922 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['apt-cache', '-q', 'policy', 'salt-formula-rsyslog'] in directory '/root'
2018-01-23 09:01:25,974 [salt.state       ][INFO    ][27494] All 78 packages are up-to-date.
2018-01-23 09:01:25,975 [salt.state       ][INFO    ][27494] Completed state [salt_master_prd_pkg_formulas] at time 09:01:25.975297 duration_in_ms=4071.276
2018-01-23 09:01:25,975 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass] at time 09:01:25.975922
2018-01-23 09:01:25,976 [salt.state       ][INFO    ][27494] Executing state file.directory for /srv/salt/reclass
2018-01-23 09:01:25,977 [salt.state       ][INFO    ][27494] Directory /srv/salt/reclass is in the correct state
2018-01-23 09:01:25,978 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass] at time 09:01:25.978511 duration_in_ms=2.589
2018-01-23 09:01:25,980 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service] at time 09:01:25.980441
2018-01-23 09:01:25,980 [salt.state       ][INFO    ][27494] Executing state file.directory for /srv/salt/reclass/classes/service
2018-01-23 09:01:25,981 [salt.state       ][INFO    ][27494] Directory /srv/salt/reclass/classes/service is in the correct state
2018-01-23 09:01:25,982 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service] at time 09:01:25.982582 duration_in_ms=2.141
2018-01-23 09:01:25,984 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/powerdns] at time 09:01:25.984065
2018-01-23 09:01:25,984 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/powerdns
2018-01-23 09:01:25,987 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/powerdns is present and owned by root:root
2018-01-23 09:01:25,987 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/powerdns] at time 09:01:25.987731 duration_in_ms=3.665
2018-01-23 09:01:25,989 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/galera] at time 09:01:25.988966
2018-01-23 09:01:25,989 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/galera
2018-01-23 09:01:25,991 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/galera is present and owned by root:root
2018-01-23 09:01:25,991 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/galera] at time 09:01:25.991783 duration_in_ms=2.818
2018-01-23 09:01:25,993 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/telegraf] at time 09:01:25.993038
2018-01-23 09:01:25,993 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/telegraf
2018-01-23 09:01:25,995 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/telegraf is present and owned by root:root
2018-01-23 09:01:25,996 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/telegraf] at time 09:01:25.996090 duration_in_ms=3.052
2018-01-23 09:01:25,997 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/supervisor] at time 09:01:25.997346
2018-01-23 09:01:25,997 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/supervisor
2018-01-23 09:01:26,000 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/supervisor is present and owned by root:root
2018-01-23 09:01:26,000 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/supervisor] at time 09:01:26.000417 duration_in_ms=3.071
2018-01-23 09:01:26,001 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/sensu] at time 09:01:26.001666
2018-01-23 09:01:26,002 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/sensu
2018-01-23 09:01:26,004 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/sensu is present and owned by root:root
2018-01-23 09:01:26,004 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/sensu] at time 09:01:26.004404 duration_in_ms=2.738
2018-01-23 09:01:26,005 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/aodh] at time 09:01:26.005640
2018-01-23 09:01:26,006 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/aodh
2018-01-23 09:01:26,008 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/aodh is present and owned by root:root
2018-01-23 09:01:26,008 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/aodh] at time 09:01:26.008654 duration_in_ms=3.014
2018-01-23 09:01:26,009 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/tinyproxy] at time 09:01:26.009904
2018-01-23 09:01:26,010 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/tinyproxy
2018-01-23 09:01:26,012 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/tinyproxy is present and owned by root:root
2018-01-23 09:01:26,013 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/tinyproxy] at time 09:01:26.012948 duration_in_ms=3.044
2018-01-23 09:01:26,014 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/collectd] at time 09:01:26.014208
2018-01-23 09:01:26,014 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/collectd
2018-01-23 09:01:26,016 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/collectd is present and owned by root:root
2018-01-23 09:01:26,017 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/collectd] at time 09:01:26.016957 duration_in_ms=2.749
2018-01-23 09:01:26,018 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/nagios] at time 09:01:26.018213
2018-01-23 09:01:26,018 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/nagios
2018-01-23 09:01:26,020 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/nagios is present and owned by root:root
2018-01-23 09:01:26,021 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/nagios] at time 09:01:26.020994 duration_in_ms=2.781
2018-01-23 09:01:26,022 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/ceph] at time 09:01:26.022226
2018-01-23 09:01:26,022 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/ceph
2018-01-23 09:01:26,024 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/ceph is present and owned by root:root
2018-01-23 09:01:26,025 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/ceph] at time 09:01:26.024958 duration_in_ms=2.733
2018-01-23 09:01:26,026 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/apache] at time 09:01:26.026513
2018-01-23 09:01:26,026 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/apache
2018-01-23 09:01:26,028 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/apache is present and owned by root:root
2018-01-23 09:01:26,029 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/apache] at time 09:01:26.029231 duration_in_ms=2.718
2018-01-23 09:01:26,030 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/glusterfs] at time 09:01:26.030411
2018-01-23 09:01:26,030 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/glusterfs
2018-01-23 09:01:26,032 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/glusterfs is present and owned by root:root
2018-01-23 09:01:26,033 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/glusterfs] at time 09:01:26.033133 duration_in_ms=2.722
2018-01-23 09:01:26,034 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/xtrabackup] at time 09:01:26.034670
2018-01-23 09:01:26,035 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/xtrabackup
2018-01-23 09:01:26,037 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/xtrabackup is present and owned by root:root
2018-01-23 09:01:26,037 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/xtrabackup] at time 09:01:26.037390 duration_in_ms=2.72
2018-01-23 09:01:26,038 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/sahara] at time 09:01:26.038600
2018-01-23 09:01:26,039 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/sahara
2018-01-23 09:01:26,040 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/sahara is present and owned by root:root
2018-01-23 09:01:26,041 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/sahara] at time 09:01:26.041260 duration_in_ms=2.66
2018-01-23 09:01:26,042 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/backupninja] at time 09:01:26.042464
2018-01-23 09:01:26,042 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/backupninja
2018-01-23 09:01:26,044 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/backupninja is present and owned by root:root
2018-01-23 09:01:26,045 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/backupninja] at time 09:01:26.045128 duration_in_ms=2.664
2018-01-23 09:01:26,047 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/rsyslog] at time 09:01:26.046122
2018-01-23 09:01:26,047 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/rsyslog
2018-01-23 09:01:26,048 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/rsyslog is present and owned by root:root
2018-01-23 09:01:26,049 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/rsyslog] at time 09:01:26.049088 duration_in_ms=2.966
2018-01-23 09:01:26,050 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/mysql] at time 09:01:26.050081
2018-01-23 09:01:26,050 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/mysql
2018-01-23 09:01:26,052 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/mysql is present and owned by root:root
2018-01-23 09:01:26,052 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/mysql] at time 09:01:26.052174 duration_in_ms=2.093
2018-01-23 09:01:26,053 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/helm] at time 09:01:26.053199
2018-01-23 09:01:26,053 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/helm
2018-01-23 09:01:26,055 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/helm is present and owned by root:root
2018-01-23 09:01:26,055 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/helm] at time 09:01:26.055564 duration_in_ms=2.365
2018-01-23 09:01:26,056 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/freeipa] at time 09:01:26.056558
2018-01-23 09:01:26,056 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/freeipa
2018-01-23 09:01:26,059 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/freeipa is present and owned by root:root
2018-01-23 09:01:26,059 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/freeipa] at time 09:01:26.059797 duration_in_ms=3.239
2018-01-23 09:01:26,061 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/barbican] at time 09:01:26.061107
2018-01-23 09:01:26,061 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/barbican
2018-01-23 09:01:26,063 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/barbican is present and owned by root:root
2018-01-23 09:01:26,063 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/barbican] at time 09:01:26.063473 duration_in_ms=2.366
2018-01-23 09:01:26,064 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/grafana] at time 09:01:26.064464
2018-01-23 09:01:26,064 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/grafana
2018-01-23 09:01:26,066 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/grafana is present and owned by root:root
2018-01-23 09:01:26,066 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/grafana] at time 09:01:26.066552 duration_in_ms=2.088
2018-01-23 09:01:26,067 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/aptly] at time 09:01:26.067532
2018-01-23 09:01:26,067 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/aptly
2018-01-23 09:01:26,069 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/aptly is present and owned by root:root
2018-01-23 09:01:26,069 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/aptly] at time 09:01:26.069586 duration_in_ms=2.054
2018-01-23 09:01:26,070 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/cassandra] at time 09:01:26.070896
2018-01-23 09:01:26,071 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/cassandra
2018-01-23 09:01:26,072 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/cassandra is present and owned by root:root
2018-01-23 09:01:26,073 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/cassandra] at time 09:01:26.073033 duration_in_ms=2.137
2018-01-23 09:01:26,074 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/lldp] at time 09:01:26.074041
2018-01-23 09:01:26,074 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/lldp
2018-01-23 09:01:26,075 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/lldp is present and owned by root:root
2018-01-23 09:01:26,076 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/lldp] at time 09:01:26.076093 duration_in_ms=2.052
2018-01-23 09:01:26,077 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/git] at time 09:01:26.077117
2018-01-23 09:01:26,077 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/git
2018-01-23 09:01:26,079 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/git is present and owned by root:root
2018-01-23 09:01:26,079 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/git] at time 09:01:26.079178 duration_in_ms=2.061
2018-01-23 09:01:26,080 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/java] at time 09:01:26.080147
2018-01-23 09:01:26,080 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/java
2018-01-23 09:01:26,082 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/java is present and owned by root:root
2018-01-23 09:01:26,082 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/java] at time 09:01:26.082241 duration_in_ms=2.093
2018-01-23 09:01:26,083 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/reclass] at time 09:01:26.083225
2018-01-23 09:01:26,083 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/reclass
2018-01-23 09:01:26,085 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/reclass is present and owned by root:root
2018-01-23 09:01:26,085 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/reclass] at time 09:01:26.085300 duration_in_ms=2.075
2018-01-23 09:01:26,086 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/linux] at time 09:01:26.086865
2018-01-23 09:01:26,087 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/linux
2018-01-23 09:01:26,088 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/linux is present and owned by root:root
2018-01-23 09:01:26,088 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/linux] at time 09:01:26.088907 duration_in_ms=2.042
2018-01-23 09:01:26,089 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/glance] at time 09:01:26.089918
2018-01-23 09:01:26,090 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/glance
2018-01-23 09:01:26,091 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/glance is present and owned by root:root
2018-01-23 09:01:26,092 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/glance] at time 09:01:26.091970 duration_in_ms=2.052
2018-01-23 09:01:26,092 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/keepalived] at time 09:01:26.092935
2018-01-23 09:01:26,093 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/keepalived
2018-01-23 09:01:26,095 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/keepalived is present and owned by root:root
2018-01-23 09:01:26,095 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/keepalived] at time 09:01:26.095326 duration_in_ms=2.391
2018-01-23 09:01:26,096 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/nova] at time 09:01:26.096304
2018-01-23 09:01:26,096 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/nova
2018-01-23 09:01:26,098 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/nova is present and owned by root:root
2018-01-23 09:01:26,098 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/nova] at time 09:01:26.098707 duration_in_ms=2.403
2018-01-23 09:01:26,099 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/rundeck] at time 09:01:26.099680
2018-01-23 09:01:26,099 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/rundeck
2018-01-23 09:01:26,101 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/rundeck is present and owned by root:root
2018-01-23 09:01:26,101 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/rundeck] at time 09:01:26.101722 duration_in_ms=2.042
2018-01-23 09:01:26,102 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/murano] at time 09:01:26.102722
2018-01-23 09:01:26,102 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/murano
2018-01-23 09:01:26,104 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/murano is present and owned by root:root
2018-01-23 09:01:26,104 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/murano] at time 09:01:26.104796 duration_in_ms=2.074
2018-01-23 09:01:26,105 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/keystone] at time 09:01:26.105795
2018-01-23 09:01:26,106 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/keystone
2018-01-23 09:01:26,108 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/keystone is present and owned by root:root
2018-01-23 09:01:26,108 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/keystone] at time 09:01:26.108676 duration_in_ms=2.881
2018-01-23 09:01:26,109 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/etcd] at time 09:01:26.109691
2018-01-23 09:01:26,109 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/etcd
2018-01-23 09:01:26,111 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/etcd is present and owned by root:root
2018-01-23 09:01:26,111 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/etcd] at time 09:01:26.111786 duration_in_ms=2.095
2018-01-23 09:01:26,112 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/ntp] at time 09:01:26.112782
2018-01-23 09:01:26,113 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/ntp
2018-01-23 09:01:26,114 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/ntp is present and owned by root:root
2018-01-23 09:01:26,115 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/ntp] at time 09:01:26.115127 duration_in_ms=2.345
2018-01-23 09:01:26,116 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/jenkins] at time 09:01:26.116111
2018-01-23 09:01:26,116 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/jenkins
2018-01-23 09:01:26,118 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/jenkins is present and owned by root:root
2018-01-23 09:01:26,119 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/jenkins] at time 09:01:26.119286 duration_in_ms=3.175
2018-01-23 09:01:26,120 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/memcached] at time 09:01:26.120265
2018-01-23 09:01:26,120 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/memcached
2018-01-23 09:01:26,122 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/memcached is present and owned by root:root
2018-01-23 09:01:26,122 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/memcached] at time 09:01:26.122374 duration_in_ms=2.109
2018-01-23 09:01:26,123 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/neutron] at time 09:01:26.123373
2018-01-23 09:01:26,123 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/neutron
2018-01-23 09:01:26,125 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/neutron is present and owned by root:root
2018-01-23 09:01:26,125 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/neutron] at time 09:01:26.125416 duration_in_ms=2.043
2018-01-23 09:01:26,126 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/designate] at time 09:01:26.126412
2018-01-23 09:01:26,126 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/designate
2018-01-23 09:01:26,128 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/designate is present and owned by root:root
2018-01-23 09:01:26,128 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/designate] at time 09:01:26.128490 duration_in_ms=2.078
2018-01-23 09:01:26,129 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/iptables] at time 09:01:26.129502
2018-01-23 09:01:26,129 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/iptables
2018-01-23 09:01:26,131 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/iptables is present and owned by root:root
2018-01-23 09:01:26,132 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/iptables] at time 09:01:26.132171 duration_in_ms=2.669
2018-01-23 09:01:26,133 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/statsd] at time 09:01:26.133175
2018-01-23 09:01:26,133 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/statsd
2018-01-23 09:01:26,135 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/statsd is present and owned by root:root
2018-01-23 09:01:26,135 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/statsd] at time 09:01:26.135229 duration_in_ms=2.054
2018-01-23 09:01:26,136 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/gerrit] at time 09:01:26.136208
2018-01-23 09:01:26,136 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/gerrit
2018-01-23 09:01:26,138 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/gerrit is present and owned by root:root
2018-01-23 09:01:26,138 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/gerrit] at time 09:01:26.138252 duration_in_ms=2.043
2018-01-23 09:01:26,139 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/zookeeper] at time 09:01:26.139225
2018-01-23 09:01:26,139 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/zookeeper
2018-01-23 09:01:26,141 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/zookeeper is present and owned by root:root
2018-01-23 09:01:26,141 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/zookeeper] at time 09:01:26.141263 duration_in_ms=2.038
2018-01-23 09:01:26,142 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/kubernetes] at time 09:01:26.142273
2018-01-23 09:01:26,142 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/kubernetes
2018-01-23 09:01:26,144 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/kubernetes is present and owned by root:root
2018-01-23 09:01:26,144 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/kubernetes] at time 09:01:26.144306 duration_in_ms=2.033
2018-01-23 09:01:26,145 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/python] at time 09:01:26.145328
2018-01-23 09:01:26,145 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/python
2018-01-23 09:01:26,147 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/python is present and owned by root:root
2018-01-23 09:01:26,147 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/python] at time 09:01:26.147365 duration_in_ms=2.037
2018-01-23 09:01:26,148 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/mongodb] at time 09:01:26.148353
2018-01-23 09:01:26,148 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/mongodb
2018-01-23 09:01:26,150 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/mongodb is present and owned by root:root
2018-01-23 09:01:26,150 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/mongodb] at time 09:01:26.150464 duration_in_ms=2.111
2018-01-23 09:01:26,151 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/maas] at time 09:01:26.151439
2018-01-23 09:01:26,151 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/maas
2018-01-23 09:01:26,153 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/maas is present and owned by root:root
2018-01-23 09:01:26,153 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/maas] at time 09:01:26.153501 duration_in_ms=2.062
2018-01-23 09:01:26,154 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/libvirt] at time 09:01:26.154846
2018-01-23 09:01:26,155 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/libvirt
2018-01-23 09:01:26,156 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/libvirt is present and owned by root:root
2018-01-23 09:01:26,156 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/libvirt] at time 09:01:26.156889 duration_in_ms=2.043
2018-01-23 09:01:26,157 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/influxdb] at time 09:01:26.157894
2018-01-23 09:01:26,158 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/influxdb
2018-01-23 09:01:26,160 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/influxdb is present and owned by root:root
2018-01-23 09:01:26,160 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/influxdb] at time 09:01:26.160242 duration_in_ms=2.348
2018-01-23 09:01:26,161 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/heka] at time 09:01:26.161263
2018-01-23 09:01:26,161 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/heka
2018-01-23 09:01:26,163 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/heka is present and owned by root:root
2018-01-23 09:01:26,163 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/heka] at time 09:01:26.163339 duration_in_ms=2.076
2018-01-23 09:01:26,164 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/heat] at time 09:01:26.164314
2018-01-23 09:01:26,164 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/heat
2018-01-23 09:01:26,166 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/heat is present and owned by root:root
2018-01-23 09:01:26,167 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/heat] at time 09:01:26.166991 duration_in_ms=2.677
2018-01-23 09:01:26,168 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/openldap] at time 09:01:26.167981
2018-01-23 09:01:26,168 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/openldap
2018-01-23 09:01:26,169 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/openldap is present and owned by root:root
2018-01-23 09:01:26,170 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/openldap] at time 09:01:26.170026 duration_in_ms=2.045
2018-01-23 09:01:26,171 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/kibana] at time 09:01:26.171311
2018-01-23 09:01:26,171 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/kibana
2018-01-23 09:01:26,173 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/kibana is present and owned by root:root
2018-01-23 09:01:26,173 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/kibana] at time 09:01:26.173469 duration_in_ms=2.158
2018-01-23 09:01:26,174 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/avinetworks] at time 09:01:26.174513
2018-01-23 09:01:26,174 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/avinetworks
2018-01-23 09:01:26,176 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/avinetworks is present and owned by root:root
2018-01-23 09:01:26,176 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/avinetworks] at time 09:01:26.176580 duration_in_ms=2.067
2018-01-23 09:01:26,177 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/sphinx] at time 09:01:26.177587
2018-01-23 09:01:26,177 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/sphinx
2018-01-23 09:01:26,179 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/sphinx is present and owned by root:root
2018-01-23 09:01:26,179 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/sphinx] at time 09:01:26.179934 duration_in_ms=2.347
2018-01-23 09:01:26,181 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/prometheus] at time 09:01:26.180943
2018-01-23 09:01:26,181 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/prometheus
2018-01-23 09:01:26,182 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/prometheus is present and owned by root:root
2018-01-23 09:01:26,183 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/prometheus] at time 09:01:26.183016 duration_in_ms=2.072
2018-01-23 09:01:26,184 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/swift] at time 09:01:26.183996
2018-01-23 09:01:26,184 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/swift
2018-01-23 09:01:26,186 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/swift is present and owned by root:root
2018-01-23 09:01:26,186 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/swift] at time 09:01:26.186849 duration_in_ms=2.853
2018-01-23 09:01:26,187 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/billometer] at time 09:01:26.187839
2018-01-23 09:01:26,188 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/billometer
2018-01-23 09:01:26,189 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/billometer is present and owned by root:root
2018-01-23 09:01:26,189 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/billometer] at time 09:01:26.189929 duration_in_ms=2.09
2018-01-23 09:01:26,191 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/haproxy] at time 09:01:26.191225
2018-01-23 09:01:26,191 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/haproxy
2018-01-23 09:01:26,193 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/haproxy is present and owned by root:root
2018-01-23 09:01:26,193 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/haproxy] at time 09:01:26.193272 duration_in_ms=2.047
2018-01-23 09:01:26,194 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/ceilometer] at time 09:01:26.194281
2018-01-23 09:01:26,194 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/ceilometer
2018-01-23 09:01:26,196 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/ceilometer is present and owned by root:root
2018-01-23 09:01:26,196 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/ceilometer] at time 09:01:26.196309 duration_in_ms=2.028
2018-01-23 09:01:26,197 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/aptcacher] at time 09:01:26.197322
2018-01-23 09:01:26,197 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/aptcacher
2018-01-23 09:01:26,199 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/aptcacher is present and owned by root:root
2018-01-23 09:01:26,199 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/aptcacher] at time 09:01:26.199677 duration_in_ms=2.355
2018-01-23 09:01:26,200 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/nginx] at time 09:01:26.200667
2018-01-23 09:01:26,200 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/nginx
2018-01-23 09:01:26,202 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/nginx is present and owned by root:root
2018-01-23 09:01:26,202 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/nginx] at time 09:01:26.202745 duration_in_ms=2.078
2018-01-23 09:01:26,203 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/bind] at time 09:01:26.203719
2018-01-23 09:01:26,203 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/bind
2018-01-23 09:01:26,205 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/bind is present and owned by root:root
2018-01-23 09:01:26,205 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/bind] at time 09:01:26.205791 duration_in_ms=2.072
2018-01-23 09:01:26,207 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/redis] at time 09:01:26.207087
2018-01-23 09:01:26,207 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/redis
2018-01-23 09:01:26,208 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/redis is present and owned by root:root
2018-01-23 09:01:26,209 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/redis] at time 09:01:26.209139 duration_in_ms=2.051
2018-01-23 09:01:26,210 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/opencontrail] at time 09:01:26.210118
2018-01-23 09:01:26,210 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/opencontrail
2018-01-23 09:01:26,212 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/opencontrail is present and owned by root:root
2018-01-23 09:01:26,212 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/opencontrail] at time 09:01:26.212170 duration_in_ms=2.052
2018-01-23 09:01:26,213 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/ccp] at time 09:01:26.213250
2018-01-23 09:01:26,213 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/ccp
2018-01-23 09:01:26,215 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/ccp is present and owned by root:root
2018-01-23 09:01:26,215 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/ccp] at time 09:01:26.215630 duration_in_ms=2.38
2018-01-23 09:01:26,216 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/rabbitmq] at time 09:01:26.216611
2018-01-23 09:01:26,216 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/rabbitmq
2018-01-23 09:01:26,218 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/rabbitmq is present and owned by root:root
2018-01-23 09:01:26,218 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/rabbitmq] at time 09:01:26.218687 duration_in_ms=2.076
2018-01-23 09:01:26,219 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/octavia] at time 09:01:26.219671
2018-01-23 09:01:26,219 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/octavia
2018-01-23 09:01:26,221 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/octavia is present and owned by root:root
2018-01-23 09:01:26,221 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/octavia] at time 09:01:26.221732 duration_in_ms=2.061
2018-01-23 09:01:26,223 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/elasticsearch] at time 09:01:26.223012
2018-01-23 09:01:26,223 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/elasticsearch
2018-01-23 09:01:26,224 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/elasticsearch is present and owned by root:root
2018-01-23 09:01:26,225 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/elasticsearch] at time 09:01:26.225105 duration_in_ms=2.093
2018-01-23 09:01:26,226 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/graphite] at time 09:01:26.226094
2018-01-23 09:01:26,226 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/graphite
2018-01-23 09:01:26,228 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/graphite is present and owned by root:root
2018-01-23 09:01:26,228 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/graphite] at time 09:01:26.228457 duration_in_ms=2.363
2018-01-23 09:01:26,229 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/postgresql] at time 09:01:26.229459
2018-01-23 09:01:26,229 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/postgresql
2018-01-23 09:01:26,231 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/postgresql is present and owned by root:root
2018-01-23 09:01:26,231 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/postgresql] at time 09:01:26.231498 duration_in_ms=2.039
2018-01-23 09:01:26,232 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/horizon] at time 09:01:26.232481
2018-01-23 09:01:26,232 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/horizon
2018-01-23 09:01:26,234 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/horizon is present and owned by root:root
2018-01-23 09:01:26,234 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/horizon] at time 09:01:26.234538 duration_in_ms=2.057
2018-01-23 09:01:26,235 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/openssh] at time 09:01:26.235515
2018-01-23 09:01:26,235 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/openssh
2018-01-23 09:01:26,237 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/openssh is present and owned by root:root
2018-01-23 09:01:26,237 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/openssh] at time 09:01:26.237578 duration_in_ms=2.063
2018-01-23 09:01:26,238 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/cinder] at time 09:01:26.238896
2018-01-23 09:01:26,239 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/cinder
2018-01-23 09:01:26,240 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/cinder is present and owned by root:root
2018-01-23 09:01:26,240 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/cinder] at time 09:01:26.240916 duration_in_ms=2.02
2018-01-23 09:01:26,241 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/docker] at time 09:01:26.241932
2018-01-23 09:01:26,242 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/docker
2018-01-23 09:01:26,244 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/docker is present and owned by root:root
2018-01-23 09:01:26,244 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/docker] at time 09:01:26.244285 duration_in_ms=2.352
2018-01-23 09:01:26,245 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/salt] at time 09:01:26.245301
2018-01-23 09:01:26,245 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/salt
2018-01-23 09:01:26,247 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/salt is present and owned by root:root
2018-01-23 09:01:26,247 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/salt] at time 09:01:26.247630 duration_in_ms=2.329
2018-01-23 09:01:26,248 [salt.state       ][INFO    ][27494] Running state [/srv/salt/reclass/classes/service/devops_portal] at time 09:01:26.248609
2018-01-23 09:01:26,248 [salt.state       ][INFO    ][27494] Executing state file.symlink for /srv/salt/reclass/classes/service/devops_portal
2018-01-23 09:01:26,251 [salt.state       ][INFO    ][27494] Symlink /srv/salt/reclass/classes/service/devops_portal is present and owned by root:root
2018-01-23 09:01:26,251 [salt.state       ][INFO    ][27494] Completed state [/srv/salt/reclass/classes/service/devops_portal] at time 09:01:26.251537 duration_in_ms=2.928
2018-01-23 09:01:26,251 [salt.state       ][INFO    ][27494] Running state [salt-minion] at time 09:01:26.251766
2018-01-23 09:01:26,252 [salt.state       ][INFO    ][27494] Executing state pkg.installed for salt-minion
2018-01-23 09:01:26,258 [salt.state       ][INFO    ][27494] All specified packages are already installed
2018-01-23 09:01:26,259 [salt.state       ][INFO    ][27494] Completed state [salt-minion] at time 09:01:26.259112 duration_in_ms=7.346
2018-01-23 09:01:26,259 [salt.state       ][INFO    ][27494] Running state [salt_minion_dependency_packages] at time 09:01:26.259339
2018-01-23 09:01:26,259 [salt.state       ][INFO    ][27494] Executing state pkg.installed for salt_minion_dependency_packages
2018-01-23 09:01:26,266 [salt.state       ][INFO    ][27494] All specified packages are already installed
2018-01-23 09:01:26,266 [salt.state       ][INFO    ][27494] Completed state [salt_minion_dependency_packages] at time 09:01:26.266758 duration_in_ms=7.419
2018-01-23 09:01:26,267 [salt.state       ][INFO    ][27494] Running state [/etc/salt/minion.d/minion.conf] at time 09:01:26.267875
2018-01-23 09:01:26,268 [salt.state       ][INFO    ][27494] Executing state file.managed for /etc/salt/minion.d/minion.conf
2018-01-23 09:01:26,487 [salt.state       ][INFO    ][27494] File /etc/salt/minion.d/minion.conf is in the correct state
2018-01-23 09:01:26,487 [salt.state       ][INFO    ][27494] Completed state [/etc/salt/minion.d/minion.conf] at time 09:01:26.487457 duration_in_ms=219.582
2018-01-23 09:01:26,488 [salt.state       ][INFO    ][27494] Running state [/etc/salt/minion.d/_pki.conf] at time 09:01:26.488627
2018-01-23 09:01:26,488 [salt.state       ][INFO    ][27494] Executing state file.managed for /etc/salt/minion.d/_pki.conf
2018-01-23 09:01:26,490 [salt.state       ][INFO    ][27494] File /etc/salt/minion.d/_pki.conf is in the correct state
2018-01-23 09:01:26,490 [salt.state       ][INFO    ][27494] Completed state [/etc/salt/minion.d/_pki.conf] at time 09:01:26.490566 duration_in_ms=1.938
2018-01-23 09:01:26,495 [salt.state       ][INFO    ][27494] Running state [salt-minion] at time 09:01:26.495110
2018-01-23 09:01:26,495 [salt.state       ][INFO    ][27494] Executing state service.running for salt-minion
2018-01-23 09:01:26,496 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2018-01-23 09:01:26,516 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2018-01-23 09:01:26,529 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2018-01-23 09:01:26,543 [salt.state       ][INFO    ][27494] The service salt-minion is already running
2018-01-23 09:01:26,543 [salt.state       ][INFO    ][27494] Completed state [salt-minion] at time 09:01:26.543413 duration_in_ms=48.301
2018-01-23 09:01:26,547 [salt.state       ][INFO    ][27494] Running state [/etc/salt/grains.d] at time 09:01:26.547946
2018-01-23 09:01:26,548 [salt.state       ][INFO    ][27494] Executing state file.directory for /etc/salt/grains.d
2018-01-23 09:01:26,549 [salt.state       ][INFO    ][27494] Directory /etc/salt/grains.d is in the correct state
2018-01-23 09:01:26,549 [salt.state       ][INFO    ][27494] Completed state [/etc/salt/grains.d] at time 09:01:26.549408 duration_in_ms=1.462
2018-01-23 09:01:26,551 [salt.state       ][INFO    ][27494] Running state [/etc/salt/grains] at time 09:01:26.551202
2018-01-23 09:01:26,551 [salt.state       ][INFO    ][27494] Executing state file.managed for /etc/salt/grains
2018-01-23 09:01:26,551 [salt.state       ][INFO    ][27494] File /etc/salt/grains exists with proper permissions. No changes made.
2018-01-23 09:01:26,552 [salt.state       ][INFO    ][27494] Completed state [/etc/salt/grains] at time 09:01:26.552144 duration_in_ms=0.942
2018-01-23 09:01:26,553 [salt.state       ][INFO    ][27494] Running state [/etc/salt/grains.d/placeholder] at time 09:01:26.553129
2018-01-23 09:01:26,553 [salt.state       ][INFO    ][27494] Executing state file.managed for /etc/salt/grains.d/placeholder
2018-01-23 09:01:26,553 [salt.state       ][INFO    ][27494] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2018-01-23 09:01:26,554 [salt.state       ][INFO    ][27494] Completed state [/etc/salt/grains.d/placeholder] at time 09:01:26.554036 duration_in_ms=0.906
2018-01-23 09:01:26,555 [salt.state       ][INFO    ][27494] Running state [/etc/salt/grains.d/sphinx] at time 09:01:26.555340
2018-01-23 09:01:26,555 [salt.state       ][INFO    ][27494] Executing state file.managed for /etc/salt/grains.d/sphinx
2018-01-23 09:01:26,556 [salt.state       ][INFO    ][27494] File /etc/salt/grains.d/sphinx is in the correct state
2018-01-23 09:01:26,557 [salt.state       ][INFO    ][27494] Completed state [/etc/salt/grains.d/sphinx] at time 09:01:26.557041 duration_in_ms=1.701
2018-01-23 09:01:26,559 [salt.state       ][INFO    ][27494] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:01:26.559260
2018-01-23 09:01:26,559 [salt.state       ][INFO    ][27494] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2018-01-23 09:01:26,559 [salt.state       ][INFO    ][27494] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2018-01-23 09:01:26,560 [salt.state       ][INFO    ][27494] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:01:26.560350 duration_in_ms=1.09
2018-01-23 09:01:26,561 [salt.state       ][INFO    ][27494] Running state [/etc/salt/grains.d/dns_records] at time 09:01:26.561351
2018-01-23 09:01:26,561 [salt.state       ][INFO    ][27494] Executing state file.managed for /etc/salt/grains.d/dns_records
2018-01-23 09:01:26,562 [salt.state       ][INFO    ][27494] File /etc/salt/grains.d/dns_records is in the correct state
2018-01-23 09:01:26,563 [salt.state       ][INFO    ][27494] Completed state [/etc/salt/grains.d/dns_records] at time 09:01:26.563033 duration_in_ms=1.681
2018-01-23 09:01:26,565 [salt.state       ][INFO    ][27494] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:01:26.564937
2018-01-23 09:01:26,565 [salt.state       ][INFO    ][27494] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2018-01-23 09:01:26,566 [salt.state       ][INFO    ][27494] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2018-01-23 09:01:26,566 [salt.state       ][INFO    ][27494] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:01:26.566551 duration_in_ms=1.614
2018-01-23 09:01:26,567 [salt.state       ][INFO    ][27494] Running state [/etc/salt/grains.d/salt] at time 09:01:26.567545
2018-01-23 09:01:26,567 [salt.state       ][INFO    ][27494] Executing state file.managed for /etc/salt/grains.d/salt
2018-01-23 09:01:26,568 [salt.state       ][INFO    ][27494] File /etc/salt/grains.d/salt is in the correct state
2018-01-23 09:01:26,568 [salt.state       ][INFO    ][27494] Completed state [/etc/salt/grains.d/salt] at time 09:01:26.568858 duration_in_ms=1.313
2018-01-23 09:01:26,570 [salt.state       ][INFO    ][27494] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:01:26.570775
2018-01-23 09:01:26,571 [salt.state       ][INFO    ][27494] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2018-01-23 09:01:26,571 [salt.state       ][INFO    ][27494] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2018-01-23 09:01:26,571 [salt.state       ][INFO    ][27494] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:01:26.571501 duration_in_ms=0.726
2018-01-23 09:01:26,575 [salt.state       ][INFO    ][27494] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:01:26.575007
2018-01-23 09:01:26,575 [salt.state       ][INFO    ][27494] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2018-01-23 09:01:26,575 [salt.state       ][INFO    ][27494] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2018-01-23 09:01:26,575 [salt.state       ][INFO    ][27494] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:01:26.575756 duration_in_ms=0.749
2018-01-23 09:01:26,576 [salt.state       ][INFO    ][27494] Running state [mine.update] at time 09:01:26.576891
2018-01-23 09:01:26,577 [salt.state       ][INFO    ][27494] Executing state module.wait for mine.update
2018-01-23 09:01:26,577 [salt.state       ][INFO    ][27494] No changes made for mine.update
2018-01-23 09:01:26,577 [salt.state       ][INFO    ][27494] Completed state [mine.update] at time 09:01:26.577607 duration_in_ms=0.716
2018-01-23 09:01:26,577 [salt.state       ][INFO    ][27494] Running state [/etc/pki/ca/salt_master_ca] at time 09:01:26.577833
2018-01-23 09:01:26,578 [salt.state       ][INFO    ][27494] Executing state file.directory for /etc/pki/ca/salt_master_ca
2018-01-23 09:01:26,578 [salt.state       ][INFO    ][27494] Directory /etc/pki/ca/salt_master_ca is in the correct state
2018-01-23 09:01:26,579 [salt.state       ][INFO    ][27494] Completed state [/etc/pki/ca/salt_master_ca] at time 09:01:26.579123 duration_in_ms=1.29
2018-01-23 09:01:26,579 [salt.state       ][INFO    ][27494] Running state [/etc/pki/ca/salt_master_ca/certs] at time 09:01:26.579341
2018-01-23 09:01:26,579 [salt.state       ][INFO    ][27494] Executing state file.directory for /etc/pki/ca/salt_master_ca/certs
2018-01-23 09:01:26,580 [salt.state       ][INFO    ][27494] Directory /etc/pki/ca/salt_master_ca/certs is in the correct state
2018-01-23 09:01:26,580 [salt.state       ][INFO    ][27494] Completed state [/etc/pki/ca/salt_master_ca/certs] at time 09:01:26.580274 duration_in_ms=0.933
2018-01-23 09:01:26,582 [salt.state       ][INFO    ][27494] Running state [/etc/pki/ca/salt_master_ca/ca.key] at time 09:01:26.582236
2018-01-23 09:01:26,582 [salt.state       ][INFO    ][27494] Executing state x509.private_key_managed for /etc/pki/ca/salt_master_ca/ca.key
2018-01-23 09:01:26,586 [salt.state       ][INFO    ][27494] File /etc/pki/ca/salt_master_ca/ca.key is in the correct state
2018-01-23 09:01:26,587 [salt.state       ][INFO    ][27494] Completed state [/etc/pki/ca/salt_master_ca/ca.key] at time 09:01:26.587260 duration_in_ms=5.023
2018-01-23 09:01:26,588 [salt.state       ][INFO    ][27494] Running state [/etc/pki/ca/salt_master_ca/ca.key] at time 09:01:26.588658
2018-01-23 09:01:26,588 [salt.state       ][INFO    ][27494] Executing state file.managed for /etc/pki/ca/salt_master_ca/ca.key
2018-01-23 09:01:26,589 [salt.state       ][INFO    ][27494] File /etc/pki/ca/salt_master_ca/ca.key exists with proper permissions. No changes made.
2018-01-23 09:01:26,589 [salt.state       ][INFO    ][27494] Completed state [/etc/pki/ca/salt_master_ca/ca.key] at time 09:01:26.589926 duration_in_ms=1.269
2018-01-23 09:01:26,591 [salt.state       ][INFO    ][27494] Running state [/etc/pki/ca/salt_master_ca/ca.crt] at time 09:01:26.591241
2018-01-23 09:01:26,591 [salt.state       ][INFO    ][27494] Executing state x509.certificate_managed for /etc/pki/ca/salt_master_ca/ca.crt
2018-01-23 09:01:26,601 [salt.state       ][INFO    ][27494] File /etc/pki/ca/salt_master_ca/ca.crt is in the correct state
2018-01-23 09:01:26,601 [salt.state       ][INFO    ][27494] Completed state [/etc/pki/ca/salt_master_ca/ca.crt] at time 09:01:26.601408 duration_in_ms=10.167
2018-01-23 09:01:26,602 [salt.state       ][INFO    ][27494] Running state [/etc/pki/ca/salt_master_ca/ca.crt] at time 09:01:26.602613
2018-01-23 09:01:26,602 [salt.state       ][INFO    ][27494] Executing state file.managed for /etc/pki/ca/salt_master_ca/ca.crt
2018-01-23 09:01:26,603 [salt.loaded.int.states.file][WARNING ][27494] State for file: /etc/pki/ca/salt_master_ca/ca.crt - 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-01-23 09:01:26,603 [salt.state       ][INFO    ][27494] File /etc/pki/ca/salt_master_ca/ca.crt exists with proper permissions. No changes made.
2018-01-23 09:01:26,604 [salt.state       ][INFO    ][27494] Completed state [/etc/pki/ca/salt_master_ca/ca.crt] at time 09:01:26.603972 duration_in_ms=1.359
2018-01-23 09:01:26,604 [salt.state       ][INFO    ][27494] Running state [mine.send] at time 09:01:26.604922
2018-01-23 09:01:26,605 [salt.state       ][INFO    ][27494] Executing state module.run for mine.send
2018-01-23 09:01:27,109 [salt.state       ][INFO    ][27494] {'ret': True}
2018-01-23 09:01:27,109 [salt.state       ][INFO    ][27494] Completed state [mine.send] at time 09:01:27.109616 duration_in_ms=504.632
2018-01-23 09:01:27,109 [salt.state       ][INFO    ][27494] Running state [ca-certificates] at time 09:01:27.109941
2018-01-23 09:01:27,110 [salt.state       ][INFO    ][27494] Executing state pkg.installed for ca-certificates
2018-01-23 09:01:27,118 [salt.state       ][INFO    ][27494] All specified packages are already installed
2018-01-23 09:01:27,118 [salt.state       ][INFO    ][27494] Completed state [ca-certificates] at time 09:01:27.118801 duration_in_ms=8.86
2018-01-23 09:01:27,120 [salt.state       ][INFO    ][27494] Running state [update-ca-certificates] at time 09:01:27.120094
2018-01-23 09:01:27,120 [salt.state       ][INFO    ][27494] Executing state cmd.wait for update-ca-certificates
2018-01-23 09:01:27,120 [salt.state       ][INFO    ][27494] No changes made for update-ca-certificates
2018-01-23 09:01:27,120 [salt.state       ][INFO    ][27494] Completed state [update-ca-certificates] at time 09:01:27.120813 duration_in_ms=0.719
2018-01-23 09:01:27,121 [salt.state       ][INFO    ][27494] Running state [salt-api] at time 09:01:27.121081
2018-01-23 09:01:27,121 [salt.state       ][INFO    ][27494] Executing state pkg.installed for salt-api
2018-01-23 09:01:27,127 [salt.state       ][INFO    ][27494] All specified packages are already installed
2018-01-23 09:01:27,127 [salt.state       ][INFO    ][27494] Completed state [salt-api] at time 09:01:27.127912 duration_in_ms=6.831
2018-01-23 09:01:27,129 [salt.state       ][INFO    ][27494] Running state [/etc/salt/master.d/_api.conf] at time 09:01:27.129050
2018-01-23 09:01:27,129 [salt.state       ][INFO    ][27494] Executing state file.managed for /etc/salt/master.d/_api.conf
2018-01-23 09:01:27,295 [salt.state       ][INFO    ][27494] File /etc/salt/master.d/_api.conf is in the correct state
2018-01-23 09:01:27,295 [salt.state       ][INFO    ][27494] Completed state [/etc/salt/master.d/_api.conf] at time 09:01:27.295451 duration_in_ms=166.4
2018-01-23 09:01:27,298 [salt.state       ][INFO    ][27494] Running state [salt-api] at time 09:01:27.298063
2018-01-23 09:01:27,298 [salt.state       ][INFO    ][27494] Executing state service.running for salt-api
2018-01-23 09:01:27,298 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['systemctl', 'status', 'salt-api.service', '-n', '0'] in directory '/root'
2018-01-23 09:01:27,320 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['systemctl', 'is-active', 'salt-api.service'] in directory '/root'
2018-01-23 09:01:27,341 [salt.loaded.int.module.cmdmod][INFO    ][27494] Executing command ['systemctl', 'is-enabled', 'salt-api.service'] in directory '/root'
2018-01-23 09:01:27,361 [salt.state       ][INFO    ][27494] The service salt-api is already running
2018-01-23 09:01:27,361 [salt.state       ][INFO    ][27494] Completed state [salt-api] at time 09:01:27.361799 duration_in_ms=63.736
2018-01-23 09:01:27,370 [salt.minion      ][INFO    ][27494] Returning information for job: 20180123090111972544
2018-01-23 09:01:29,357 [salt.minion      ][INFO    ][27351] User sudo_ubuntu Executing command state.apply with jid 20180123090129346204
2018-01-23 09:01:29,379 [salt.minion      ][INFO    ][27897] Starting a new job with PID 27897
2018-01-23 09:01:31,855 [salt.state       ][INFO    ][27897] Loading fresh modules for state activity
2018-01-23 09:01:31,914 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/init.sls'
2018-01-23 09:01:31,961 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/system/init.sls'
2018-01-23 09:01:32,091 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/system/env.sls'
2018-01-23 09:01:32,192 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/system/profile.sls'
2018-01-23 09:01:32,303 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/system/repo.sls'
2018-01-23 09:01:32,521 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/system/package.sls'
2018-01-23 09:01:32,643 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/system/timezone.sls'
2018-01-23 09:01:32,744 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/system/kernel.sls'
2018-01-23 09:01:32,932 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/system/cpu.sls'
2018-01-23 09:01:33,035 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/system/sysfs.sls'
2018-01-23 09:01:33,129 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/system/locale.sls'
2018-01-23 09:01:33,443 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/system/limit.sls'
2018-01-23 09:01:33,542 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/system/systemd.sls'
2018-01-23 09:01:33,644 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/system/apt.sls'
2018-01-23 09:01:33,746 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/network/init.sls'
2018-01-23 09:01:33,840 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/network/hostname.sls'
2018-01-23 09:01:33,937 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/network/host.sls'
2018-01-23 09:01:34,119 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/network/interface.sls'
2018-01-23 09:01:34,311 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/network/proxy.sls'
2018-01-23 09:01:34,415 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/storage/init.sls'
2018-01-23 09:01:34,512 [salt.state       ][INFO    ][27897] Running state [/etc/environment] at time 09:01:34.512690
2018-01-23 09:01:34,513 [salt.state       ][INFO    ][27897] Executing state file.blockreplace for /etc/environment
2018-01-23 09:01:34,521 [salt.state       ][INFO    ][27897] 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-01-23 09:01:34,522 [salt.state       ][INFO    ][27897] Completed state [/etc/environment] at time 09:01:34.522667 duration_in_ms=9.978
2018-01-23 09:01:34,523 [salt.state       ][INFO    ][27897] Running state [/etc/profile.d] at time 09:01:34.523131
2018-01-23 09:01:34,523 [salt.state       ][INFO    ][27897] Executing state file.directory for /etc/profile.d
2018-01-23 09:01:34,525 [salt.state       ][INFO    ][27897] Directory /etc/profile.d is in the correct state
2018-01-23 09:01:34,525 [salt.state       ][INFO    ][27897] Completed state [/etc/profile.d] at time 09:01:34.525552 duration_in_ms=2.42
2018-01-23 09:01:35,059 [salt.state       ][INFO    ][27897] Running state [/etc/apt/apt.conf.d/99compression-workaround-salt] at time 09:01:35.059099
2018-01-23 09:01:35,059 [salt.state       ][INFO    ][27897] Executing state file.managed for /etc/apt/apt.conf.d/99compression-workaround-salt
2018-01-23 09:01:35,087 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/files/apt.conf'
2018-01-23 09:01:35,096 [salt.state       ][INFO    ][27897] File changed:
New file
2018-01-23 09:01:35,096 [salt.state       ][INFO    ][27897] Completed state [/etc/apt/apt.conf.d/99compression-workaround-salt] at time 09:01:35.096708 duration_in_ms=37.61
2018-01-23 09:01:35,097 [salt.state       ][INFO    ][27897] Running state [/etc/apt/apt.conf.d/99prefer_ipv4-salt] at time 09:01:35.096962
2018-01-23 09:01:35,097 [salt.state       ][INFO    ][27897] Executing state file.managed for /etc/apt/apt.conf.d/99prefer_ipv4-salt
2018-01-23 09:01:35,114 [salt.state       ][INFO    ][27897] File changed:
New file
2018-01-23 09:01:35,114 [salt.state       ][INFO    ][27897] Completed state [/etc/apt/apt.conf.d/99prefer_ipv4-salt] at time 09:01:35.114590 duration_in_ms=17.628
2018-01-23 09:01:35,115 [salt.state       ][INFO    ][27897] Running state [linux_repo_prereq_pkgs] at time 09:01:35.115778
2018-01-23 09:01:35,116 [salt.state       ][INFO    ][27897] Executing state pkg.installed for linux_repo_prereq_pkgs
2018-01-23 09:01:35,116 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2018-01-23 09:01:35,487 [salt.state       ][INFO    ][27897] All specified packages are already installed
2018-01-23 09:01:35,487 [salt.state       ][INFO    ][27897] Completed state [linux_repo_prereq_pkgs] at time 09:01:35.487573 duration_in_ms=371.794
2018-01-23 09:01:35,487 [salt.state       ][INFO    ][27897] Running state [/etc/apt/apt.conf.d/99proxies-salt-saltstack] at time 09:01:35.487922
2018-01-23 09:01:35,488 [salt.state       ][INFO    ][27897] Executing state file.absent for /etc/apt/apt.conf.d/99proxies-salt-saltstack
2018-01-23 09:01:35,488 [salt.state       ][INFO    ][27897] File /etc/apt/apt.conf.d/99proxies-salt-saltstack is not present
2018-01-23 09:01:35,488 [salt.state       ][INFO    ][27897] Completed state [/etc/apt/apt.conf.d/99proxies-salt-saltstack] at time 09:01:35.488913 duration_in_ms=0.992
2018-01-23 09:01:35,489 [salt.state       ][INFO    ][27897] Running state [/etc/apt/preferences.d/saltstack] at time 09:01:35.489172
2018-01-23 09:01:35,489 [salt.state       ][INFO    ][27897] Executing state file.absent for /etc/apt/preferences.d/saltstack
2018-01-23 09:01:35,489 [salt.state       ][INFO    ][27897] {'removed': '/etc/apt/preferences.d/saltstack'}
2018-01-23 09:01:35,490 [salt.state       ][INFO    ][27897] Completed state [/etc/apt/preferences.d/saltstack] at time 09:01:35.490001 duration_in_ms=0.828
2018-01-23 09:01:35,494 [salt.state       ][INFO    ][27897] Running state [deb http://repo.saltstack.com/apt/ubuntu/16.04/amd64/2016.11 xenial main] at time 09:01:35.494556
2018-01-23 09:01:35,494 [salt.state       ][INFO    ][27897] Executing state pkgrepo.managed for deb http://repo.saltstack.com/apt/ubuntu/16.04/amd64/2016.11 xenial main
2018-01-23 09:01:35,818 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['apt-key', 'add', '/var/cache/salt/minion/extrn_files/base/repo.saltstack.com/apt/ubuntu/16.04/amd64/2016.11/SALTSTACK-GPG-KEY.pub'] in directory '/root'
2018-01-23 09:01:35,990 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-01-23 09:01:39,452 [salt.minion      ][INFO    ][27351] User sudo_ubuntu Executing command saltutil.find_job with jid 20180123090139435959
2018-01-23 09:01:39,540 [salt.minion      ][INFO    ][28350] Starting a new job with PID 28350
2018-01-23 09:01:39,575 [salt.minion      ][INFO    ][28350] Returning information for job: 20180123090139435959
2018-01-23 09:01:42,680 [salt.state       ][INFO    ][27897] Configured package repo 'deb http://repo.saltstack.com/apt/ubuntu/16.04/amd64/2016.11 xenial main'
2018-01-23 09:01:42,680 [salt.state       ][INFO    ][27897] Completed state [deb http://repo.saltstack.com/apt/ubuntu/16.04/amd64/2016.11 xenial main] at time 09:01:42.680758 duration_in_ms=7186.201
2018-01-23 09:01:42,681 [salt.state       ][INFO    ][27897] Running state [/etc/apt/apt.conf.d/99proxies-salt-salt] at time 09:01:42.681208
2018-01-23 09:01:42,681 [salt.state       ][INFO    ][27897] Executing state file.absent for /etc/apt/apt.conf.d/99proxies-salt-salt
2018-01-23 09:01:42,682 [salt.state       ][INFO    ][27897] File /etc/apt/apt.conf.d/99proxies-salt-salt is not present
2018-01-23 09:01:42,682 [salt.state       ][INFO    ][27897] Completed state [/etc/apt/apt.conf.d/99proxies-salt-salt] at time 09:01:42.682645 duration_in_ms=1.437
2018-01-23 09:01:42,682 [salt.state       ][INFO    ][27897] Running state [/etc/apt/preferences.d/salt] at time 09:01:42.682898
2018-01-23 09:01:42,683 [salt.state       ][INFO    ][27897] Executing state file.absent for /etc/apt/preferences.d/salt
2018-01-23 09:01:42,683 [salt.state       ][INFO    ][27897] File /etc/apt/preferences.d/salt is not present
2018-01-23 09:01:42,683 [salt.state       ][INFO    ][27897] Completed state [/etc/apt/preferences.d/salt] at time 09:01:42.683635 duration_in_ms=0.738
2018-01-23 09:01:42,683 [salt.state       ][INFO    ][27897] Running state [/etc/apt/sources.list.d/salt.list] at time 09:01:42.683866
2018-01-23 09:01:42,684 [salt.state       ][INFO    ][27897] Executing state file.absent for /etc/apt/sources.list.d/salt.list
2018-01-23 09:01:42,684 [salt.state       ][INFO    ][27897] File /etc/apt/sources.list.d/salt.list is not present
2018-01-23 09:01:42,684 [salt.state       ][INFO    ][27897] Completed state [/etc/apt/sources.list.d/salt.list] at time 09:01:42.684601 duration_in_ms=0.734
2018-01-23 09:01:42,684 [salt.state       ][INFO    ][27897] Running state [/etc/apt/apt.conf.d/99proxies-salt-uca] at time 09:01:42.684834
2018-01-23 09:01:42,685 [salt.state       ][INFO    ][27897] Executing state file.absent for /etc/apt/apt.conf.d/99proxies-salt-uca
2018-01-23 09:01:42,685 [salt.state       ][INFO    ][27897] File /etc/apt/apt.conf.d/99proxies-salt-uca is not present
2018-01-23 09:01:42,685 [salt.state       ][INFO    ][27897] Completed state [/etc/apt/apt.conf.d/99proxies-salt-uca] at time 09:01:42.685575 duration_in_ms=0.741
2018-01-23 09:01:42,685 [salt.state       ][INFO    ][27897] Running state [/etc/apt/preferences.d/uca] at time 09:01:42.685804
2018-01-23 09:01:42,686 [salt.state       ][INFO    ][27897] Executing state file.absent for /etc/apt/preferences.d/uca
2018-01-23 09:01:42,686 [salt.state       ][INFO    ][27897] File /etc/apt/preferences.d/uca is not present
2018-01-23 09:01:42,686 [salt.state       ][INFO    ][27897] Completed state [/etc/apt/preferences.d/uca] at time 09:01:42.686518 duration_in_ms=0.714
2018-01-23 09:01:42,687 [salt.state       ][INFO    ][27897] Running state [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main] at time 09:01:42.687644
2018-01-23 09:01:42,687 [salt.state       ][INFO    ][27897] Executing state pkgrepo.managed for deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main
2018-01-23 09:01:42,816 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['apt-key', 'export', 'EC4926EA'] in directory '/root'
2018-01-23 09:01:42,920 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['apt-key', 'adv', '--keyserver', 'keyserver.ubuntu.com', '--logger-fd', '1', '--recv-keys', 'EC4926EA'] in directory '/root'
2018-01-23 09:01:43,252 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-01-23 09:01:48,992 [salt.state       ][INFO    ][27897] {'repo': 'deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main'}
2018-01-23 09:01:48,993 [salt.state       ][INFO    ][27897] Completed state [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/pike main] at time 09:01:48.993215 duration_in_ms=6305.571
2018-01-23 09:01:48,993 [salt.state       ][INFO    ][27897] Running state [/etc/apt/apt.conf.d/99proxies-salt-mcp_salt] at time 09:01:48.993762
2018-01-23 09:01:48,994 [salt.state       ][INFO    ][27897] Executing state file.absent for /etc/apt/apt.conf.d/99proxies-salt-mcp_salt
2018-01-23 09:01:48,994 [salt.state       ][INFO    ][27897] File /etc/apt/apt.conf.d/99proxies-salt-mcp_salt is not present
2018-01-23 09:01:48,995 [salt.state       ][INFO    ][27897] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mcp_salt] at time 09:01:48.995355 duration_in_ms=1.593
2018-01-23 09:01:48,995 [salt.state       ][INFO    ][27897] Running state [/etc/apt/preferences.d/mcp_salt] at time 09:01:48.995812
2018-01-23 09:01:48,996 [salt.state       ][INFO    ][27897] Executing state file.managed for /etc/apt/preferences.d/mcp_salt
2018-01-23 09:01:49,018 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/files/preferences_repo'
2018-01-23 09:01:49,101 [salt.state       ][INFO    ][27897] File changed:
New file
2018-01-23 09:01:49,102 [salt.state       ][INFO    ][27897] Completed state [/etc/apt/preferences.d/mcp_salt] at time 09:01:49.101995 duration_in_ms=106.183
2018-01-23 09:01:49,103 [salt.state       ][INFO    ][27897] Running state [deb [arch=amd64] http://apt.mirantis.com/xenial/ stable salt] at time 09:01:49.103277
2018-01-23 09:01:49,103 [salt.state       ][INFO    ][27897] Executing state pkgrepo.managed for deb [arch=amd64] http://apt.mirantis.com/xenial/ stable salt
2018-01-23 09:01:49,537 [salt.minion      ][INFO    ][27351] User sudo_ubuntu Executing command saltutil.find_job with jid 20180123090149520938
2018-01-23 09:01:49,559 [salt.minion      ][INFO    ][28888] Starting a new job with PID 28888
2018-01-23 09:01:49,562 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['apt-key', 'add', '/var/cache/salt/minion/extrn_files/base/apt.mirantis.com/public.gpg'] in directory '/root'
2018-01-23 09:01:49,579 [salt.minion      ][INFO    ][28888] Returning information for job: 20180123090149520938
2018-01-23 09:01:49,709 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-01-23 09:01:53,767 [salt.state       ][INFO    ][27897] {'repo': 'deb [arch=amd64] http://apt.mirantis.com/xenial stable salt'}
2018-01-23 09:01:53,768 [salt.state       ][INFO    ][27897] Completed state [deb [arch=amd64] http://apt.mirantis.com/xenial/ stable salt] at time 09:01:53.768038 duration_in_ms=4664.761
2018-01-23 09:01:53,768 [salt.state       ][INFO    ][27897] Running state [linux_extra_packages_purged] at time 09:01:53.768615
2018-01-23 09:01:53,769 [salt.state       ][INFO    ][27897] Executing state pkg.purged for linux_extra_packages_purged
2018-01-23 09:01:53,807 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', 'purge', 'cloud-init', 'unattended-upgrades'] in directory '/root'
2018-01-23 09:01:58,168 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2018-01-23 09:01:58,217 [salt.state       ][INFO    ][27897] {'removed': {}, 'installed': {'cloud-init': {'new': '', 'old': '17.1-46-g7acc9e68-0ubuntu1~16.04.1'}, 'ec2-init': {'new': '', 'old': '1'}, 'unattended-upgrades': {'new': '', 'old': '0.90ubuntu0.9'}}}
2018-01-23 09:01:58,240 [salt.state       ][INFO    ][27897] Loading fresh modules for state activity
2018-01-23 09:01:58,271 [salt.state       ][INFO    ][27897] Completed state [linux_extra_packages_purged] at time 09:01:58.271406 duration_in_ms=4502.791
2018-01-23 09:01:58,275 [salt.state       ][INFO    ][27897] Running state [linux_extra_packages_latest] at time 09:01:58.275896
2018-01-23 09:01:58,276 [salt.state       ][INFO    ][27897] Executing state pkg.latest for linux_extra_packages_latest
2018-01-23 09:01:58,717 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['apt-cache', '-q', 'policy', 'mcelog'] in directory '/root'
2018-01-23 09:01:58,769 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-01-23 09:01:58,802 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'mcelog'] in directory '/root'
2018-01-23 09:01:59,746 [salt.minion      ][INFO    ][27351] User sudo_ubuntu Executing command saltutil.find_job with jid 20180123090159735228
2018-01-23 09:01:59,763 [salt.minion      ][INFO    ][30244] Starting a new job with PID 30244
2018-01-23 09:01:59,783 [salt.minion      ][INFO    ][30244] Returning information for job: 20180123090159735228
2018-01-23 09:02:01,600 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2018-01-23 09:02:01,647 [salt.state       ][INFO    ][27897] Made the following changes:
'mcelog' changed from 'absent' to '128+dfsg-1'

2018-01-23 09:02:01,663 [salt.state       ][INFO    ][27897] Loading fresh modules for state activity
2018-01-23 09:02:01,694 [salt.state       ][INFO    ][27897] Completed state [linux_extra_packages_latest] at time 09:02:01.694320 duration_in_ms=3418.423
2018-01-23 09:02:01,697 [salt.state       ][INFO    ][27897] Running state [UTC] at time 09:02:01.697897
2018-01-23 09:02:01,698 [salt.state       ][INFO    ][27897] Executing state timezone.system for UTC
2018-01-23 09:02:01,701 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['timedatectl'] in directory '/root'
2018-01-23 09:02:01,788 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['timedatectl'] in directory '/root'
2018-01-23 09:02:01,801 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'timedatectl set-timezone UTC' in directory '/root'
2018-01-23 09:02:01,817 [salt.state       ][INFO    ][27897] {'timezone': 'UTC'}
2018-01-23 09:02:01,817 [salt.state       ][INFO    ][27897] Completed state [UTC] at time 09:02:01.817533 duration_in_ms=119.635
2018-01-23 09:02:01,821 [salt.state       ][INFO    ][27897] Running state [nf_conntrack] at time 09:02:01.821613
2018-01-23 09:02:01,821 [salt.state       ][INFO    ][27897] Executing state kmod.present for nf_conntrack
2018-01-23 09:02:01,823 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'lsmod' in directory '/root'
2018-01-23 09:02:01,896 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'lsmod' in directory '/root'
2018-01-23 09:02:01,912 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'modprobe nf_conntrack' in directory '/root'
2018-01-23 09:02:01,936 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'lsmod' in directory '/root'
2018-01-23 09:02:01,970 [salt.state       ][INFO    ][27897] {'nf_conntrack': 'loaded'}
2018-01-23 09:02:01,971 [salt.state       ][INFO    ][27897] Completed state [nf_conntrack] at time 09:02:01.971174 duration_in_ms=149.561
2018-01-23 09:02:01,975 [salt.state       ][INFO    ][27897] Running state [kernel.panic] at time 09:02:01.975685
2018-01-23 09:02:01,976 [salt.state       ][INFO    ][27897] Executing state sysctl.present for kernel.panic
2018-01-23 09:02:01,976 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,042 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w kernel.panic="60"' in directory '/root'
2018-01-23 09:02:02,053 [salt.state       ][INFO    ][27897] {'kernel.panic': 60}
2018-01-23 09:02:02,053 [salt.state       ][INFO    ][27897] Completed state [kernel.panic] at time 09:02:02.053851 duration_in_ms=78.165
2018-01-23 09:02:02,054 [salt.state       ][INFO    ][27897] Running state [net.ipv4.tcp_keepalive_probes] at time 09:02:02.054274
2018-01-23 09:02:02,054 [salt.state       ][INFO    ][27897] Executing state sysctl.present for net.ipv4.tcp_keepalive_probes
2018-01-23 09:02:02,055 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,080 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w net.ipv4.tcp_keepalive_probes="8"' in directory '/root'
2018-01-23 09:02:02,091 [salt.state       ][INFO    ][27897] {'net.ipv4.tcp_keepalive_probes': 8}
2018-01-23 09:02:02,091 [salt.state       ][INFO    ][27897] Completed state [net.ipv4.tcp_keepalive_probes] at time 09:02:02.091355 duration_in_ms=37.081
2018-01-23 09:02:02,091 [salt.state       ][INFO    ][27897] Running state [fs.file-max] at time 09:02:02.091741
2018-01-23 09:02:02,092 [salt.state       ][INFO    ][27897] Executing state sysctl.present for fs.file-max
2018-01-23 09:02:02,092 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,128 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w fs.file-max="124165"' in directory '/root'
2018-01-23 09:02:02,140 [salt.state       ][INFO    ][27897] {'fs.file-max': 124165}
2018-01-23 09:02:02,141 [salt.state       ][INFO    ][27897] Completed state [fs.file-max] at time 09:02:02.141197 duration_in_ms=49.456
2018-01-23 09:02:02,141 [salt.state       ][INFO    ][27897] Running state [net.core.somaxconn] at time 09:02:02.141602
2018-01-23 09:02:02,141 [salt.state       ][INFO    ][27897] Executing state sysctl.present for net.core.somaxconn
2018-01-23 09:02:02,142 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,168 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w net.core.somaxconn="4096"' in directory '/root'
2018-01-23 09:02:02,177 [salt.state       ][INFO    ][27897] {'net.core.somaxconn': 4096}
2018-01-23 09:02:02,178 [salt.state       ][INFO    ][27897] Completed state [net.core.somaxconn] at time 09:02:02.178104 duration_in_ms=36.501
2018-01-23 09:02:02,179 [salt.state       ][INFO    ][27897] Running state [net.ipv4.tcp_max_syn_backlog] at time 09:02:02.179136
2018-01-23 09:02:02,179 [salt.state       ][INFO    ][27897] Executing state sysctl.present for net.ipv4.tcp_max_syn_backlog
2018-01-23 09:02:02,180 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,204 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w net.ipv4.tcp_max_syn_backlog="8192"' in directory '/root'
2018-01-23 09:02:02,214 [salt.state       ][INFO    ][27897] {'net.ipv4.tcp_max_syn_backlog': 8192}
2018-01-23 09:02:02,215 [salt.state       ][INFO    ][27897] Completed state [net.ipv4.tcp_max_syn_backlog] at time 09:02:02.214964 duration_in_ms=35.828
2018-01-23 09:02:02,215 [salt.state       ][INFO    ][27897] Running state [net.ipv4.tcp_tw_reuse] at time 09:02:02.215313
2018-01-23 09:02:02,215 [salt.state       ][INFO    ][27897] Executing state sysctl.present for net.ipv4.tcp_tw_reuse
2018-01-23 09:02:02,216 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,241 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w net.ipv4.tcp_tw_reuse="1"' in directory '/root'
2018-01-23 09:02:02,252 [salt.state       ][INFO    ][27897] {'net.ipv4.tcp_tw_reuse': 1}
2018-01-23 09:02:02,252 [salt.state       ][INFO    ][27897] Completed state [net.ipv4.tcp_tw_reuse] at time 09:02:02.252855 duration_in_ms=37.541
2018-01-23 09:02:02,253 [salt.state       ][INFO    ][27897] Running state [net.ipv4.tcp_congestion_control] at time 09:02:02.253226
2018-01-23 09:02:02,253 [salt.state       ][INFO    ][27897] Executing state sysctl.present for net.ipv4.tcp_congestion_control
2018-01-23 09:02:02,254 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,287 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w net.ipv4.tcp_congestion_control="yeah"' in directory '/root'
2018-01-23 09:02:02,305 [salt.state       ][INFO    ][27897] {'net.ipv4.tcp_congestion_control': 'yeah'}
2018-01-23 09:02:02,306 [salt.state       ][INFO    ][27897] Completed state [net.ipv4.tcp_congestion_control] at time 09:02:02.306139 duration_in_ms=52.911
2018-01-23 09:02:02,307 [salt.state       ][INFO    ][27897] Running state [net.ipv4.tcp_retries2] at time 09:02:02.307018
2018-01-23 09:02:02,307 [salt.state       ][INFO    ][27897] Executing state sysctl.present for net.ipv4.tcp_retries2
2018-01-23 09:02:02,309 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,339 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w net.ipv4.tcp_retries2="5"' in directory '/root'
2018-01-23 09:02:02,349 [salt.state       ][INFO    ][27897] {'net.ipv4.tcp_retries2': 5}
2018-01-23 09:02:02,350 [salt.state       ][INFO    ][27897] Completed state [net.ipv4.tcp_retries2] at time 09:02:02.350044 duration_in_ms=43.027
2018-01-23 09:02:02,350 [salt.state       ][INFO    ][27897] Running state [net.ipv4.tcp_keepalive_intvl] at time 09:02:02.350736
2018-01-23 09:02:02,351 [salt.state       ][INFO    ][27897] Executing state sysctl.present for net.ipv4.tcp_keepalive_intvl
2018-01-23 09:02:02,351 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,385 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w net.ipv4.tcp_keepalive_intvl="3"' in directory '/root'
2018-01-23 09:02:02,399 [salt.state       ][INFO    ][27897] {'net.ipv4.tcp_keepalive_intvl': 3}
2018-01-23 09:02:02,399 [salt.state       ][INFO    ][27897] Completed state [net.ipv4.tcp_keepalive_intvl] at time 09:02:02.399665 duration_in_ms=48.929
2018-01-23 09:02:02,400 [salt.state       ][INFO    ][27897] Running state [net.ipv4.tcp_slow_start_after_idle] at time 09:02:02.400241
2018-01-23 09:02:02,400 [salt.state       ][INFO    ][27897] Executing state sysctl.present for net.ipv4.tcp_slow_start_after_idle
2018-01-23 09:02:02,401 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,428 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w net.ipv4.tcp_slow_start_after_idle="0"' in directory '/root'
2018-01-23 09:02:02,443 [salt.state       ][INFO    ][27897] {'net.ipv4.tcp_slow_start_after_idle': 0}
2018-01-23 09:02:02,444 [salt.state       ][INFO    ][27897] Completed state [net.ipv4.tcp_slow_start_after_idle] at time 09:02:02.444159 duration_in_ms=43.918
2018-01-23 09:02:02,444 [salt.state       ][INFO    ][27897] Running state [vm.swappiness] at time 09:02:02.444761
2018-01-23 09:02:02,445 [salt.state       ][INFO    ][27897] Executing state sysctl.present for vm.swappiness
2018-01-23 09:02:02,446 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,479 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w vm.swappiness="10"' in directory '/root'
2018-01-23 09:02:02,495 [salt.state       ][INFO    ][27897] {'vm.swappiness': 10}
2018-01-23 09:02:02,495 [salt.state       ][INFO    ][27897] Completed state [vm.swappiness] at time 09:02:02.495678 duration_in_ms=50.917
2018-01-23 09:02:02,496 [salt.state       ][INFO    ][27897] Running state [net.core.netdev_max_backlog] at time 09:02:02.496380
2018-01-23 09:02:02,497 [salt.state       ][INFO    ][27897] Executing state sysctl.present for net.core.netdev_max_backlog
2018-01-23 09:02:02,498 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,529 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w net.core.netdev_max_backlog="261144"' in directory '/root'
2018-01-23 09:02:02,543 [salt.state       ][INFO    ][27897] {'net.core.netdev_max_backlog': 261144}
2018-01-23 09:02:02,544 [salt.state       ][INFO    ][27897] Completed state [net.core.netdev_max_backlog] at time 09:02:02.544337 duration_in_ms=47.958
2018-01-23 09:02:02,545 [salt.state       ][INFO    ][27897] Running state [net.ipv4.neigh.default.gc_thresh1] at time 09:02:02.544935
2018-01-23 09:02:02,545 [salt.state       ][INFO    ][27897] Executing state sysctl.present for net.ipv4.neigh.default.gc_thresh1
2018-01-23 09:02:02,546 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,580 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w net.ipv4.neigh.default.gc_thresh1="4096"' in directory '/root'
2018-01-23 09:02:02,593 [salt.state       ][INFO    ][27897] {'net.ipv4.neigh.default.gc_thresh1': 4096}
2018-01-23 09:02:02,593 [salt.state       ][INFO    ][27897] Completed state [net.ipv4.neigh.default.gc_thresh1] at time 09:02:02.593707 duration_in_ms=48.772
2018-01-23 09:02:02,594 [salt.state       ][INFO    ][27897] Running state [net.ipv4.neigh.default.gc_thresh2] at time 09:02:02.594139
2018-01-23 09:02:02,594 [salt.state       ][INFO    ][27897] Executing state sysctl.present for net.ipv4.neigh.default.gc_thresh2
2018-01-23 09:02:02,595 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,622 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w net.ipv4.neigh.default.gc_thresh2="8192"' in directory '/root'
2018-01-23 09:02:02,636 [salt.state       ][INFO    ][27897] {'net.ipv4.neigh.default.gc_thresh2': 8192}
2018-01-23 09:02:02,636 [salt.state       ][INFO    ][27897] Completed state [net.ipv4.neigh.default.gc_thresh2] at time 09:02:02.636618 duration_in_ms=42.478
2018-01-23 09:02:02,637 [salt.state       ][INFO    ][27897] Running state [net.ipv4.neigh.default.gc_thresh3] at time 09:02:02.637081
2018-01-23 09:02:02,637 [salt.state       ][INFO    ][27897] Executing state sysctl.present for net.ipv4.neigh.default.gc_thresh3
2018-01-23 09:02:02,638 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,675 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w net.ipv4.neigh.default.gc_thresh3="16384"' in directory '/root'
2018-01-23 09:02:02,687 [salt.state       ][INFO    ][27897] {'net.ipv4.neigh.default.gc_thresh3': 16384}
2018-01-23 09:02:02,687 [salt.state       ][INFO    ][27897] Completed state [net.ipv4.neigh.default.gc_thresh3] at time 09:02:02.687533 duration_in_ms=50.451
2018-01-23 09:02:02,688 [salt.state       ][INFO    ][27897] Running state [net.ipv4.tcp_fin_timeout] at time 09:02:02.688140
2018-01-23 09:02:02,688 [salt.state       ][INFO    ][27897] Executing state sysctl.present for net.ipv4.tcp_fin_timeout
2018-01-23 09:02:02,689 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,721 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w net.ipv4.tcp_fin_timeout="30"' in directory '/root'
2018-01-23 09:02:02,735 [salt.state       ][INFO    ][27897] {'net.ipv4.tcp_fin_timeout': 30}
2018-01-23 09:02:02,735 [salt.state       ][INFO    ][27897] Completed state [net.ipv4.tcp_fin_timeout] at time 09:02:02.735586 duration_in_ms=47.446
2018-01-23 09:02:02,736 [salt.state       ][INFO    ][27897] Running state [net.ipv4.tcp_keepalive_time] at time 09:02:02.735997
2018-01-23 09:02:02,736 [salt.state       ][INFO    ][27897] Executing state sysctl.present for net.ipv4.tcp_keepalive_time
2018-01-23 09:02:02,737 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,769 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w net.ipv4.tcp_keepalive_time="30"' in directory '/root'
2018-01-23 09:02:02,783 [salt.state       ][INFO    ][27897] {'net.ipv4.tcp_keepalive_time': 30}
2018-01-23 09:02:02,783 [salt.state       ][INFO    ][27897] Completed state [net.ipv4.tcp_keepalive_time] at time 09:02:02.783668 duration_in_ms=47.671
2018-01-23 09:02:02,784 [salt.state       ][INFO    ][27897] Running state [net.nf_conntrack_max] at time 09:02:02.784080
2018-01-23 09:02:02,784 [salt.state       ][INFO    ][27897] Executing state sysctl.present for net.nf_conntrack_max
2018-01-23 09:02:02,785 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -a' in directory '/root'
2018-01-23 09:02:02,813 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'sysctl -w net.nf_conntrack_max="1048576"' in directory '/root'
2018-01-23 09:02:02,827 [salt.state       ][INFO    ][27897] {'net.nf_conntrack_max': 1048576}
2018-01-23 09:02:02,827 [salt.state       ][INFO    ][27897] Completed state [net.nf_conntrack_max] at time 09:02:02.827415 duration_in_ms=43.335
2018-01-23 09:02:02,834 [salt.state       ][INFO    ][27897] Running state [linux_sysfs_package] at time 09:02:02.834513
2018-01-23 09:02:02,834 [salt.state       ][INFO    ][27897] Executing state pkg.installed for linux_sysfs_package
2018-01-23 09:02:03,237 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['apt-cache', '-q', 'policy', 'sysfsutils'] in directory '/root'
2018-01-23 09:02:03,296 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-01-23 09:02:05,909 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-01-23 09:02:05,940 [salt.loaded.int.module.cmdmod][INFO    ][27897] 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-01-23 09:02:08,737 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2018-01-23 09:02:08,786 [salt.state       ][INFO    ][27897] 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-01-23 09:02:08,801 [salt.state       ][INFO    ][27897] Loading fresh modules for state activity
2018-01-23 09:02:08,841 [salt.state       ][INFO    ][27897] Completed state [linux_sysfs_package] at time 09:02:08.841125 duration_in_ms=6006.611
2018-01-23 09:02:08,846 [salt.state       ][INFO    ][27897] Running state [/etc/sysfs.d] at time 09:02:08.846032
2018-01-23 09:02:08,846 [salt.state       ][INFO    ][27897] Executing state file.directory for /etc/sysfs.d
2018-01-23 09:02:08,853 [salt.state       ][INFO    ][27897] Directory /etc/sysfs.d is in the correct state
2018-01-23 09:02:08,853 [salt.state       ][INFO    ][27897] Completed state [/etc/sysfs.d] at time 09:02:08.853240 duration_in_ms=7.207
2018-01-23 09:02:08,904 [salt.state       ][INFO    ][27897] Running state [ondemand] at time 09:02:08.904431
2018-01-23 09:02:08,904 [salt.state       ][INFO    ][27897] Executing state service.dead for ondemand
2018-01-23 09:02:08,907 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['systemctl', 'status', 'ondemand.service', '-n', '0'] in directory '/root'
2018-01-23 09:02:08,930 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['systemctl', 'is-active', 'ondemand.service'] in directory '/root'
2018-01-23 09:02:08,944 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-01-23 09:02:08,964 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['systemd-run', '--scope', 'systemctl', 'stop', 'ondemand.service'] in directory '/root'
2018-01-23 09:02:09,041 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['systemctl', 'is-active', 'ondemand.service'] in directory '/root'
2018-01-23 09:02:09,057 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-01-23 09:02:09,074 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-01-23 09:02:09,096 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['systemd-run', '--scope', '/usr/sbin/update-rc.d', '-f', 'ondemand', 'remove'] in directory '/root'
2018-01-23 09:02:09,238 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-01-23 09:02:09,259 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'runlevel' in directory '/root'
2018-01-23 09:02:09,271 [salt.state       ][INFO    ][27897] {'ondemand': True}
2018-01-23 09:02:09,271 [salt.state       ][INFO    ][27897] Completed state [ondemand] at time 09:02:09.271742 duration_in_ms=367.311
2018-01-23 09:02:09,275 [salt.state       ][INFO    ][27897] Running state [cs_CZ.UTF-8] at time 09:02:09.275225
2018-01-23 09:02:09,275 [salt.state       ][INFO    ][27897] Executing state locale.present for cs_CZ.UTF-8
2018-01-23 09:02:09,276 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'locale -a' in directory '/root'
2018-01-23 09:02:09,300 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['locale-gen', 'cs_CZ.utf8'] in directory '/root'
2018-01-23 09:02:10,436 [salt.minion      ][INFO    ][27351] User sudo_ubuntu Executing command saltutil.find_job with jid 20180123090209948455
2018-01-23 09:02:10,465 [salt.minion      ][INFO    ][31484] Starting a new job with PID 31484
2018-01-23 09:02:10,486 [salt.minion      ][INFO    ][31484] Returning information for job: 20180123090209948455
2018-01-23 09:02:11,000 [salt.state       ][INFO    ][27897] {'locale': 'cs_CZ.UTF-8'}
2018-01-23 09:02:11,001 [salt.state       ][INFO    ][27897] Completed state [cs_CZ.UTF-8] at time 09:02:11.000932 duration_in_ms=1725.706
2018-01-23 09:02:11,001 [salt.state       ][INFO    ][27897] Running state [en_US.UTF-8] at time 09:02:11.001364
2018-01-23 09:02:11,001 [salt.state       ][INFO    ][27897] Executing state locale.present for en_US.UTF-8
2018-01-23 09:02:11,002 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'locale -a' in directory '/root'
2018-01-23 09:02:11,021 [salt.state       ][INFO    ][27897] Locale en_US.UTF-8 is already present
2018-01-23 09:02:11,022 [salt.state       ][INFO    ][27897] Completed state [en_US.UTF-8] at time 09:02:11.022383 duration_in_ms=21.018
2018-01-23 09:02:11,025 [salt.state       ][INFO    ][27897] Running state [en_US.UTF-8] at time 09:02:11.024907
2018-01-23 09:02:11,025 [salt.state       ][INFO    ][27897] Executing state locale.system for en_US.UTF-8
2018-01-23 09:02:11,026 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command 'localectl' in directory '/root'
2018-01-23 09:02:11,112 [salt.state       ][INFO    ][27897] System locale en_US.UTF-8 already set
2018-01-23 09:02:11,112 [salt.state       ][INFO    ][27897] Completed state [en_US.UTF-8] at time 09:02:11.112679 duration_in_ms=87.773
2018-01-23 09:02:11,115 [salt.state       ][INFO    ][27897] Running state [root] at time 09:02:11.115584
2018-01-23 09:02:11,116 [salt.state       ][INFO    ][27897] Executing state user.present for root
2018-01-23 09:02:11,124 [salt.state       ][INFO    ][27897] User root is present and up to date
2018-01-23 09:02:11,124 [salt.state       ][INFO    ][27897] Completed state [root] at time 09:02:11.124625 duration_in_ms=9.041
2018-01-23 09:02:11,125 [salt.state       ][INFO    ][27897] Running state [/root] at time 09:02:11.125883
2018-01-23 09:02:11,126 [salt.state       ][INFO    ][27897] Executing state file.directory for /root
2018-01-23 09:02:11,127 [salt.state       ][INFO    ][27897] Directory /root is in the correct state
2018-01-23 09:02:11,127 [salt.state       ][INFO    ][27897] Completed state [/root] at time 09:02:11.127720 duration_in_ms=1.837
2018-01-23 09:02:11,127 [salt.state       ][INFO    ][27897] Running state [/etc/sudoers.d/90-salt-user-root] at time 09:02:11.127952
2018-01-23 09:02:11,128 [salt.state       ][INFO    ][27897] Executing state file.absent for /etc/sudoers.d/90-salt-user-root
2018-01-23 09:02:11,128 [salt.state       ][INFO    ][27897] File /etc/sudoers.d/90-salt-user-root is not present
2018-01-23 09:02:11,128 [salt.state       ][INFO    ][27897] Completed state [/etc/sudoers.d/90-salt-user-root] at time 09:02:11.128679 duration_in_ms=0.727
2018-01-23 09:02:11,128 [salt.state       ][INFO    ][27897] Running state [salt] at time 09:02:11.128917
2018-01-23 09:02:11,129 [salt.state       ][INFO    ][27897] Executing state user.present for salt
2018-01-23 09:02:11,130 [salt.state       ][INFO    ][27897] User salt is present and up to date
2018-01-23 09:02:11,130 [salt.state       ][INFO    ][27897] Completed state [salt] at time 09:02:11.130379 duration_in_ms=1.461
2018-01-23 09:02:11,131 [salt.state       ][INFO    ][27897] Running state [/var/tmp/salt] at time 09:02:11.131785
2018-01-23 09:02:11,132 [salt.state       ][INFO    ][27897] Executing state file.directory for /var/tmp/salt
2018-01-23 09:02:11,132 [salt.state       ][INFO    ][27897] Directory /var/tmp/salt is in the correct state
2018-01-23 09:02:11,133 [salt.state       ][INFO    ][27897] Completed state [/var/tmp/salt] at time 09:02:11.132948 duration_in_ms=1.163
2018-01-23 09:02:11,133 [salt.state       ][INFO    ][27897] Running state [/etc/sudoers.d/90-salt-user-salt] at time 09:02:11.133296
2018-01-23 09:02:11,133 [salt.state       ][INFO    ][27897] Executing state file.absent for /etc/sudoers.d/90-salt-user-salt
2018-01-23 09:02:11,133 [salt.state       ][INFO    ][27897] File /etc/sudoers.d/90-salt-user-salt is not present
2018-01-23 09:02:11,134 [salt.state       ][INFO    ][27897] Completed state [/etc/sudoers.d/90-salt-user-salt] at time 09:02:11.134024 duration_in_ms=0.728
2018-01-23 09:02:11,134 [salt.state       ][INFO    ][27897] Running state [ubuntu] at time 09:02:11.134262
2018-01-23 09:02:11,134 [salt.state       ][INFO    ][27897] Executing state user.present for ubuntu
2018-01-23 09:02:11,135 [salt.state       ][INFO    ][27897] User ubuntu is present and up to date
2018-01-23 09:02:11,135 [salt.state       ][INFO    ][27897] Completed state [ubuntu] at time 09:02:11.135729 duration_in_ms=1.467
2018-01-23 09:02:11,136 [salt.state       ][INFO    ][27897] Running state [/home/ubuntu] at time 09:02:11.136778
2018-01-23 09:02:11,137 [salt.state       ][INFO    ][27897] Executing state file.directory for /home/ubuntu
2018-01-23 09:02:11,137 [salt.state       ][INFO    ][27897] Directory /home/ubuntu is in the correct state
2018-01-23 09:02:11,138 [salt.state       ][INFO    ][27897] Completed state [/home/ubuntu] at time 09:02:11.137966 duration_in_ms=1.188
2018-01-23 09:02:11,138 [salt.state       ][INFO    ][27897] Running state [/etc/sudoers.d/90-salt-user-ubuntu] at time 09:02:11.138879
2018-01-23 09:02:11,139 [salt.state       ][INFO    ][27897] Executing state file.managed for /etc/sudoers.d/90-salt-user-ubuntu
2018-01-23 09:02:11,167 [salt.state       ][INFO    ][27897] File /etc/sudoers.d/90-salt-user-ubuntu is in the correct state
2018-01-23 09:02:11,168 [salt.state       ][INFO    ][27897] Completed state [/etc/sudoers.d/90-salt-user-ubuntu] at time 09:02:11.168034 duration_in_ms=29.155
2018-01-23 09:02:11,168 [salt.state       ][INFO    ][27897] Running state [/etc/security/limits.d/90-salt-default.conf] at time 09:02:11.168274
2018-01-23 09:02:11,168 [salt.state       ][INFO    ][27897] Executing state file.managed for /etc/security/limits.d/90-salt-default.conf
2018-01-23 09:02:11,196 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/files/limits.conf'
2018-01-23 09:02:11,294 [salt.state       ][INFO    ][27897] File changed:
New file
2018-01-23 09:02:11,294 [salt.state       ][INFO    ][27897] Completed state [/etc/security/limits.d/90-salt-default.conf] at time 09:02:11.294490 duration_in_ms=126.216
2018-01-23 09:02:11,294 [salt.state       ][INFO    ][27897] Running state [/etc/systemd/system.conf.d/90-salt.conf] at time 09:02:11.294739
2018-01-23 09:02:11,295 [salt.state       ][INFO    ][27897] Executing state file.managed for /etc/systemd/system.conf.d/90-salt.conf
2018-01-23 09:02:11,330 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/files/systemd.conf'
2018-01-23 09:02:11,413 [salt.state       ][INFO    ][27897] File changed:
New file
2018-01-23 09:02:11,414 [salt.state       ][INFO    ][27897] Completed state [/etc/systemd/system.conf.d/90-salt.conf] at time 09:02:11.414086 duration_in_ms=119.345
2018-01-23 09:02:11,416 [salt.state       ][INFO    ][27897] Running state [service.systemctl_reload] at time 09:02:11.416064
2018-01-23 09:02:11,416 [salt.state       ][INFO    ][27897] Executing state module.wait for service.systemctl_reload
2018-01-23 09:02:11,416 [salt.state       ][INFO    ][27897] No changes made for service.systemctl_reload
2018-01-23 09:02:11,416 [salt.state       ][INFO    ][27897] Completed state [service.systemctl_reload] at time 09:02:11.416779 duration_in_ms=0.716
2018-01-23 09:02:11,417 [salt.state       ][INFO    ][27897] Running state [service.systemctl_reload] at time 09:02:11.417007
2018-01-23 09:02:11,417 [salt.state       ][INFO    ][27897] Executing state module.mod_watch for service.systemctl_reload
2018-01-23 09:02:11,417 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2018-01-23 09:02:11,524 [salt.state       ][INFO    ][27897] {'ret': True}
2018-01-23 09:02:11,525 [salt.state       ][INFO    ][27897] Completed state [service.systemctl_reload] at time 09:02:11.525035 duration_in_ms=108.027
2018-01-23 09:02:11,525 [salt.state       ][INFO    ][27897] Running state [/etc/hostname] at time 09:02:11.525461
2018-01-23 09:02:11,525 [salt.state       ][INFO    ][27897] Executing state file.managed for /etc/hostname
2018-01-23 09:02:11,556 [salt.fileclient  ][INFO    ][27897] Fetching file from saltenv 'base', ** done ** 'linux/files/hostname'
2018-01-23 09:02:11,560 [salt.state       ][INFO    ][27897] File /etc/hostname is in the correct state
2018-01-23 09:02:11,560 [salt.state       ][INFO    ][27897] Completed state [/etc/hostname] at time 09:02:11.560277 duration_in_ms=34.815
2018-01-23 09:02:11,562 [salt.state       ][INFO    ][27897] Running state [hostname cfg01] at time 09:02:11.562726
2018-01-23 09:02:11,563 [salt.state       ][INFO    ][27897] Executing state cmd.wait for hostname cfg01
2018-01-23 09:02:11,563 [salt.state       ][INFO    ][27897] No changes made for hostname cfg01
2018-01-23 09:02:11,563 [salt.state       ][INFO    ][27897] Completed state [hostname cfg01] at time 09:02:11.563491 duration_in_ms=0.765
2018-01-23 09:02:11,564 [salt.state       ][INFO    ][27897] Running state [mdb02] at time 09:02:11.564450
2018-01-23 09:02:11,564 [salt.state       ][INFO    ][27897] Executing state host.present for mdb02
2018-01-23 09:02:11,565 [salt.state       ][INFO    ][27897] {'host': 'mdb02'}
2018-01-23 09:02:11,566 [salt.state       ][INFO    ][27897] Completed state [mdb02] at time 09:02:11.566001 duration_in_ms=1.551
2018-01-23 09:02:11,566 [salt.state       ][INFO    ][27897] Running state [mdb02.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.566255
2018-01-23 09:02:11,566 [salt.state       ][INFO    ][27897] Executing state host.present for mdb02.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,567 [salt.state       ][INFO    ][27897] {'host': 'mdb02.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,567 [salt.state       ][INFO    ][27897] Completed state [mdb02.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.567923 duration_in_ms=1.668
2018-01-23 09:02:11,568 [salt.state       ][INFO    ][27897] Running state [mdb03] at time 09:02:11.568169
2018-01-23 09:02:11,568 [salt.state       ][INFO    ][27897] Executing state host.present for mdb03
2018-01-23 09:02:11,569 [salt.state       ][INFO    ][27897] {'host': 'mdb03'}
2018-01-23 09:02:11,569 [salt.state       ][INFO    ][27897] Completed state [mdb03] at time 09:02:11.569489 duration_in_ms=1.32
2018-01-23 09:02:11,569 [salt.state       ][INFO    ][27897] Running state [mdb03.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.569728
2018-01-23 09:02:11,569 [salt.state       ][INFO    ][27897] Executing state host.present for mdb03.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,570 [salt.state       ][INFO    ][27897] {'host': 'mdb03.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,571 [salt.state       ][INFO    ][27897] Completed state [mdb03.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.571006 duration_in_ms=1.278
2018-01-23 09:02:11,571 [salt.state       ][INFO    ][27897] Running state [mdb01] at time 09:02:11.571253
2018-01-23 09:02:11,571 [salt.state       ][INFO    ][27897] Executing state host.present for mdb01
2018-01-23 09:02:11,572 [salt.state       ][INFO    ][27897] {'host': 'mdb01'}
2018-01-23 09:02:11,572 [salt.state       ][INFO    ][27897] Completed state [mdb01] at time 09:02:11.572512 duration_in_ms=1.259
2018-01-23 09:02:11,572 [salt.state       ][INFO    ][27897] Running state [mdb01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.572756
2018-01-23 09:02:11,573 [salt.state       ][INFO    ][27897] Executing state host.present for mdb01.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,573 [salt.state       ][INFO    ][27897] {'host': 'mdb01.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,574 [salt.state       ][INFO    ][27897] Completed state [mdb01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.574076 duration_in_ms=1.32
2018-01-23 09:02:11,574 [salt.state       ][INFO    ][27897] Running state [mdb] at time 09:02:11.574321
2018-01-23 09:02:11,574 [salt.state       ][INFO    ][27897] Executing state host.present for mdb
2018-01-23 09:02:11,575 [salt.state       ][INFO    ][27897] {'host': 'mdb'}
2018-01-23 09:02:11,576 [salt.state       ][INFO    ][27897] Completed state [mdb] at time 09:02:11.575962 duration_in_ms=1.641
2018-01-23 09:02:11,576 [salt.state       ][INFO    ][27897] Running state [mdb.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.576208
2018-01-23 09:02:11,576 [salt.state       ][INFO    ][27897] Executing state host.present for mdb.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,577 [salt.state       ][INFO    ][27897] {'host': 'mdb.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,577 [salt.state       ][INFO    ][27897] Completed state [mdb.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.577550 duration_in_ms=1.342
2018-01-23 09:02:11,577 [salt.state       ][INFO    ][27897] Running state [cfg01] at time 09:02:11.577783
2018-01-23 09:02:11,578 [salt.state       ][INFO    ][27897] Executing state host.present for cfg01
2018-01-23 09:02:11,578 [salt.state       ][INFO    ][27897] {'host': 'cfg01'}
2018-01-23 09:02:11,579 [salt.state       ][INFO    ][27897] Completed state [cfg01] at time 09:02:11.579083 duration_in_ms=1.3
2018-01-23 09:02:11,579 [salt.state       ][INFO    ][27897] Running state [cfg01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.579321
2018-01-23 09:02:11,579 [salt.state       ][INFO    ][27897] Executing state host.present for cfg01.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,580 [salt.state       ][INFO    ][27897] {'host': 'cfg01.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,580 [salt.state       ][INFO    ][27897] Completed state [cfg01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.580635 duration_in_ms=1.314
2018-01-23 09:02:11,580 [salt.state       ][INFO    ][27897] Running state [prx01] at time 09:02:11.580876
2018-01-23 09:02:11,581 [salt.state       ][INFO    ][27897] Executing state host.present for prx01
2018-01-23 09:02:11,582 [salt.state       ][INFO    ][27897] {'host': 'prx01'}
2018-01-23 09:02:11,582 [salt.state       ][INFO    ][27897] Completed state [prx01] at time 09:02:11.582195 duration_in_ms=1.319
2018-01-23 09:02:11,582 [salt.state       ][INFO    ][27897] Running state [prx01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.582424
2018-01-23 09:02:11,582 [salt.state       ][INFO    ][27897] Executing state host.present for prx01.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,583 [salt.state       ][INFO    ][27897] {'host': 'prx01.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,583 [salt.state       ][INFO    ][27897] Completed state [prx01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.583752 duration_in_ms=1.328
2018-01-23 09:02:11,584 [salt.state       ][INFO    ][27897] Running state [kvm01] at time 09:02:11.583989
2018-01-23 09:02:11,584 [salt.state       ][INFO    ][27897] Executing state host.present for kvm01
2018-01-23 09:02:11,585 [salt.state       ][INFO    ][27897] {'host': 'kvm01'}
2018-01-23 09:02:11,585 [salt.state       ][INFO    ][27897] Completed state [kvm01] at time 09:02:11.585325 duration_in_ms=1.335
2018-01-23 09:02:11,585 [salt.state       ][INFO    ][27897] Running state [kvm01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.585562
2018-01-23 09:02:11,585 [salt.state       ][INFO    ][27897] Executing state host.present for kvm01.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,586 [salt.state       ][INFO    ][27897] {'host': 'kvm01.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,586 [salt.state       ][INFO    ][27897] Completed state [kvm01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.586948 duration_in_ms=1.386
2018-01-23 09:02:11,587 [salt.state       ][INFO    ][27897] Running state [kvm03] at time 09:02:11.587191
2018-01-23 09:02:11,587 [salt.state       ][INFO    ][27897] Executing state host.present for kvm03
2018-01-23 09:02:11,588 [salt.state       ][INFO    ][27897] {'host': 'kvm03'}
2018-01-23 09:02:11,588 [salt.state       ][INFO    ][27897] Completed state [kvm03] at time 09:02:11.588740 duration_in_ms=1.549
2018-01-23 09:02:11,589 [salt.state       ][INFO    ][27897] Running state [kvm03.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.589001
2018-01-23 09:02:11,589 [salt.state       ][INFO    ][27897] Executing state host.present for kvm03.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,590 [salt.state       ][INFO    ][27897] {'host': 'kvm03.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,590 [salt.state       ][INFO    ][27897] Completed state [kvm03.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.590344 duration_in_ms=1.343
2018-01-23 09:02:11,590 [salt.state       ][INFO    ][27897] Running state [kvm02] at time 09:02:11.590938
2018-01-23 09:02:11,591 [salt.state       ][INFO    ][27897] Executing state host.present for kvm02
2018-01-23 09:02:11,592 [salt.state       ][INFO    ][27897] {'host': 'kvm02'}
2018-01-23 09:02:11,592 [salt.state       ][INFO    ][27897] Completed state [kvm02] at time 09:02:11.592256 duration_in_ms=1.319
2018-01-23 09:02:11,592 [salt.state       ][INFO    ][27897] Running state [kvm02.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.592485
2018-01-23 09:02:11,592 [salt.state       ][INFO    ][27897] Executing state host.present for kvm02.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,593 [salt.state       ][INFO    ][27897] {'host': 'kvm02.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,593 [salt.state       ][INFO    ][27897] Completed state [kvm02.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.593870 duration_in_ms=1.384
2018-01-23 09:02:11,594 [salt.state       ][INFO    ][27897] Running state [dbs] at time 09:02:11.594102
2018-01-23 09:02:11,594 [salt.state       ][INFO    ][27897] Executing state host.present for dbs
2018-01-23 09:02:11,595 [salt.state       ][INFO    ][27897] {'host': 'dbs'}
2018-01-23 09:02:11,595 [salt.state       ][INFO    ][27897] Completed state [dbs] at time 09:02:11.595460 duration_in_ms=1.358
2018-01-23 09:02:11,595 [salt.state       ][INFO    ][27897] Running state [dbs.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.595698
2018-01-23 09:02:11,595 [salt.state       ][INFO    ][27897] Executing state host.present for dbs.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,596 [salt.state       ][INFO    ][27897] {'host': 'dbs.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,597 [salt.state       ][INFO    ][27897] Completed state [dbs.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.597077 duration_in_ms=1.379
2018-01-23 09:02:11,597 [salt.state       ][INFO    ][27897] Running state [prx] at time 09:02:11.597318
2018-01-23 09:02:11,597 [salt.state       ][INFO    ][27897] Executing state host.present for prx
2018-01-23 09:02:11,598 [salt.state       ][INFO    ][27897] {'host': 'prx'}
2018-01-23 09:02:11,599 [salt.state       ][INFO    ][27897] Completed state [prx] at time 09:02:11.599043 duration_in_ms=1.725
2018-01-23 09:02:11,599 [salt.state       ][INFO    ][27897] Running state [prx.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.599287
2018-01-23 09:02:11,599 [salt.state       ][INFO    ][27897] Executing state host.present for prx.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,600 [salt.state       ][INFO    ][27897] {'host': 'prx.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,600 [salt.state       ][INFO    ][27897] Completed state [prx.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.600664 duration_in_ms=1.377
2018-01-23 09:02:11,602 [salt.state       ][INFO    ][27897] Running state [prx02] at time 09:02:11.600910
2018-01-23 09:02:11,602 [salt.state       ][INFO    ][27897] Executing state host.present for prx02
2018-01-23 09:02:11,603 [salt.state       ][INFO    ][27897] {'host': 'prx02'}
2018-01-23 09:02:11,603 [salt.state       ][INFO    ][27897] Completed state [prx02] at time 09:02:11.603628 duration_in_ms=2.718
2018-01-23 09:02:11,603 [salt.state       ][INFO    ][27897] Running state [prx02.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.603866
2018-01-23 09:02:11,604 [salt.state       ][INFO    ][27897] Executing state host.present for prx02.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,605 [salt.state       ][INFO    ][27897] {'host': 'prx02.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,605 [salt.state       ][INFO    ][27897] Completed state [prx02.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.605291 duration_in_ms=1.426
2018-01-23 09:02:11,605 [salt.state       ][INFO    ][27897] Running state [msg02] at time 09:02:11.605534
2018-01-23 09:02:11,605 [salt.state       ][INFO    ][27897] Executing state host.present for msg02
2018-01-23 09:02:11,607 [salt.state       ][INFO    ][27897] {'host': 'msg02'}
2018-01-23 09:02:11,607 [salt.state       ][INFO    ][27897] Completed state [msg02] at time 09:02:11.607274 duration_in_ms=1.74
2018-01-23 09:02:11,607 [salt.state       ][INFO    ][27897] Running state [msg02.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.607512
2018-01-23 09:02:11,607 [salt.state       ][INFO    ][27897] Executing state host.present for msg02.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,608 [salt.state       ][INFO    ][27897] {'host': 'msg02.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,609 [salt.state       ][INFO    ][27897] Completed state [msg02.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.608949 duration_in_ms=1.437
2018-01-23 09:02:11,609 [salt.state       ][INFO    ][27897] Running state [msg03] at time 09:02:11.609227
2018-01-23 09:02:11,609 [salt.state       ][INFO    ][27897] Executing state host.present for msg03
2018-01-23 09:02:11,614 [salt.state       ][INFO    ][27897] {'host': 'msg03'}
2018-01-23 09:02:11,615 [salt.state       ][INFO    ][27897] Completed state [msg03] at time 09:02:11.615116 duration_in_ms=5.889
2018-01-23 09:02:11,615 [salt.state       ][INFO    ][27897] Running state [msg03.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.615368
2018-01-23 09:02:11,615 [salt.state       ][INFO    ][27897] Executing state host.present for msg03.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,616 [salt.state       ][INFO    ][27897] {'host': 'msg03.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,616 [salt.state       ][INFO    ][27897] Completed state [msg03.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.616828 duration_in_ms=1.46
2018-01-23 09:02:11,617 [salt.state       ][INFO    ][27897] Running state [msg01] at time 09:02:11.617106
2018-01-23 09:02:11,617 [salt.state       ][INFO    ][27897] Executing state host.present for msg01
2018-01-23 09:02:11,618 [salt.state       ][INFO    ][27897] {'host': 'msg01'}
2018-01-23 09:02:11,618 [salt.state       ][INFO    ][27897] Completed state [msg01] at time 09:02:11.618882 duration_in_ms=1.776
2018-01-23 09:02:11,619 [salt.state       ][INFO    ][27897] Running state [msg01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.619117
2018-01-23 09:02:11,619 [salt.state       ][INFO    ][27897] Executing state host.present for msg01.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,620 [salt.state       ][INFO    ][27897] {'host': 'msg01.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,620 [salt.state       ][INFO    ][27897] Completed state [msg01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.620561 duration_in_ms=1.444
2018-01-23 09:02:11,620 [salt.state       ][INFO    ][27897] Running state [msg] at time 09:02:11.620804
2018-01-23 09:02:11,621 [salt.state       ][INFO    ][27897] Executing state host.present for msg
2018-01-23 09:02:11,622 [salt.state       ][INFO    ][27897] {'host': 'msg'}
2018-01-23 09:02:11,622 [salt.state       ][INFO    ][27897] Completed state [msg] at time 09:02:11.622291 duration_in_ms=1.487
2018-01-23 09:02:11,623 [salt.state       ][INFO    ][27897] Running state [msg.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.623724
2018-01-23 09:02:11,623 [salt.state       ][INFO    ][27897] Executing state host.present for msg.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,625 [salt.state       ][INFO    ][27897] {'host': 'msg.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,625 [salt.state       ][INFO    ][27897] Completed state [msg.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.625231 duration_in_ms=1.507
2018-01-23 09:02:11,625 [salt.state       ][INFO    ][27897] Running state [cfg01] at time 09:02:11.625479
2018-01-23 09:02:11,625 [salt.state       ][INFO    ][27897] Executing state host.present for cfg01
2018-01-23 09:02:11,626 [salt.state       ][INFO    ][27897] Host cfg01 (192.168.10.100) already present
2018-01-23 09:02:11,626 [salt.state       ][INFO    ][27897] Completed state [cfg01] at time 09:02:11.626367 duration_in_ms=0.888
2018-01-23 09:02:11,626 [salt.state       ][INFO    ][27897] Running state [cfg01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.626619
2018-01-23 09:02:11,626 [salt.state       ][INFO    ][27897] Executing state host.present for cfg01.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,627 [salt.state       ][INFO    ][27897] Host cfg01.baremetal-mcp-pike-odl-ha.local (192.168.10.100) already present
2018-01-23 09:02:11,627 [salt.state       ][INFO    ][27897] Completed state [cfg01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.627499 duration_in_ms=0.88
2018-01-23 09:02:11,627 [salt.state       ][INFO    ][27897] Running state [cmp002] at time 09:02:11.627736
2018-01-23 09:02:11,627 [salt.state       ][INFO    ][27897] Executing state host.present for cmp002
2018-01-23 09:02:11,629 [salt.state       ][INFO    ][27897] {'host': 'cmp002'}
2018-01-23 09:02:11,629 [salt.state       ][INFO    ][27897] Completed state [cmp002] at time 09:02:11.629219 duration_in_ms=1.483
2018-01-23 09:02:11,629 [salt.state       ][INFO    ][27897] Running state [cmp002.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.629464
2018-01-23 09:02:11,629 [salt.state       ][INFO    ][27897] Executing state host.present for cmp002.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,630 [salt.state       ][INFO    ][27897] {'host': 'cmp002.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,631 [salt.state       ][INFO    ][27897] Completed state [cmp002.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.630971 duration_in_ms=1.507
2018-01-23 09:02:11,631 [salt.state       ][INFO    ][27897] Running state [cmp001] at time 09:02:11.631224
2018-01-23 09:02:11,631 [salt.state       ][INFO    ][27897] Executing state host.present for cmp001
2018-01-23 09:02:11,632 [salt.state       ][INFO    ][27897] {'host': 'cmp001'}
2018-01-23 09:02:11,632 [salt.state       ][INFO    ][27897] Completed state [cmp001] at time 09:02:11.632706 duration_in_ms=1.482
2018-01-23 09:02:11,633 [salt.state       ][INFO    ][27897] Running state [cmp001.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.632948
2018-01-23 09:02:11,633 [salt.state       ][INFO    ][27897] Executing state host.present for cmp001.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,634 [salt.state       ][INFO    ][27897] {'host': 'cmp001.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,634 [salt.state       ][INFO    ][27897] Completed state [cmp001.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.634647 duration_in_ms=1.699
2018-01-23 09:02:11,634 [salt.state       ][INFO    ][27897] Running state [dbs01] at time 09:02:11.634895
2018-01-23 09:02:11,635 [salt.state       ][INFO    ][27897] Executing state host.present for dbs01
2018-01-23 09:02:11,636 [salt.state       ][INFO    ][27897] {'host': 'dbs01'}
2018-01-23 09:02:11,636 [salt.state       ][INFO    ][27897] Completed state [dbs01] at time 09:02:11.636389 duration_in_ms=1.494
2018-01-23 09:02:11,636 [salt.state       ][INFO    ][27897] Running state [dbs01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.636639
2018-01-23 09:02:11,636 [salt.state       ][INFO    ][27897] Executing state host.present for dbs01.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,637 [salt.state       ][INFO    ][27897] {'host': 'dbs01.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,638 [salt.state       ][INFO    ][27897] Completed state [dbs01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.638167 duration_in_ms=1.528
2018-01-23 09:02:11,638 [salt.state       ][INFO    ][27897] Running state [dbs02] at time 09:02:11.638418
2018-01-23 09:02:11,639 [salt.state       ][INFO    ][27897] Executing state host.present for dbs02
2018-01-23 09:02:11,640 [salt.state       ][INFO    ][27897] {'host': 'dbs02'}
2018-01-23 09:02:11,640 [salt.state       ][INFO    ][27897] Completed state [dbs02] at time 09:02:11.640276 duration_in_ms=1.858
2018-01-23 09:02:11,640 [salt.state       ][INFO    ][27897] Running state [dbs02.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.640520
2018-01-23 09:02:11,640 [salt.state       ][INFO    ][27897] Executing state host.present for dbs02.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,641 [salt.state       ][INFO    ][27897] {'host': 'dbs02.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,642 [salt.state       ][INFO    ][27897] Completed state [dbs02.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.642085 duration_in_ms=1.565
2018-01-23 09:02:11,642 [salt.state       ][INFO    ][27897] Running state [dbs03] at time 09:02:11.642322
2018-01-23 09:02:11,642 [salt.state       ][INFO    ][27897] Executing state host.present for dbs03
2018-01-23 09:02:11,643 [salt.state       ][INFO    ][27897] {'host': 'dbs03'}
2018-01-23 09:02:11,643 [salt.state       ][INFO    ][27897] Completed state [dbs03] at time 09:02:11.643843 duration_in_ms=1.521
2018-01-23 09:02:11,644 [salt.state       ][INFO    ][27897] Running state [dbs03.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.644087
2018-01-23 09:02:11,644 [salt.state       ][INFO    ][27897] Executing state host.present for dbs03.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,645 [salt.state       ][INFO    ][27897] {'host': 'dbs03.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,645 [salt.state       ][INFO    ][27897] Completed state [dbs03.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.645658 duration_in_ms=1.571
2018-01-23 09:02:11,645 [salt.state       ][INFO    ][27897] Running state [odl01] at time 09:02:11.645897
2018-01-23 09:02:11,646 [salt.state       ][INFO    ][27897] Executing state host.present for odl01
2018-01-23 09:02:11,647 [salt.state       ][INFO    ][27897] {'host': 'odl01'}
2018-01-23 09:02:11,647 [salt.state       ][INFO    ][27897] Completed state [odl01] at time 09:02:11.647781 duration_in_ms=1.884
2018-01-23 09:02:11,648 [salt.state       ][INFO    ][27897] Running state [odl01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.648021
2018-01-23 09:02:11,648 [salt.state       ][INFO    ][27897] Executing state host.present for odl01.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,649 [salt.state       ][INFO    ][27897] {'host': 'odl01.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,649 [salt.state       ][INFO    ][27897] Completed state [odl01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.649736 duration_in_ms=1.714
2018-01-23 09:02:11,650 [salt.state       ][INFO    ][27897] Running state [mas01] at time 09:02:11.649976
2018-01-23 09:02:11,650 [salt.state       ][INFO    ][27897] Executing state host.present for mas01
2018-01-23 09:02:11,651 [salt.state       ][INFO    ][27897] {'host': 'mas01'}
2018-01-23 09:02:11,651 [salt.state       ][INFO    ][27897] Completed state [mas01] at time 09:02:11.651548 duration_in_ms=1.572
2018-01-23 09:02:11,651 [salt.state       ][INFO    ][27897] Running state [mas01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.651792
2018-01-23 09:02:11,652 [salt.state       ][INFO    ][27897] Executing state host.present for mas01.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,653 [salt.state       ][INFO    ][27897] {'host': 'mas01.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,653 [salt.state       ][INFO    ][27897] Completed state [mas01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.653390 duration_in_ms=1.598
2018-01-23 09:02:11,653 [salt.state       ][INFO    ][27897] Running state [ctl02] at time 09:02:11.653629
2018-01-23 09:02:11,653 [salt.state       ][INFO    ][27897] Executing state host.present for ctl02
2018-01-23 09:02:11,655 [salt.state       ][INFO    ][27897] {'host': 'ctl02'}
2018-01-23 09:02:11,655 [salt.state       ][INFO    ][27897] Completed state [ctl02] at time 09:02:11.655203 duration_in_ms=1.574
2018-01-23 09:02:11,655 [salt.state       ][INFO    ][27897] Running state [ctl02.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.655449
2018-01-23 09:02:11,655 [salt.state       ][INFO    ][27897] Executing state host.present for ctl02.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,656 [salt.state       ][INFO    ][27897] {'host': 'ctl02.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,657 [salt.state       ][INFO    ][27897] Completed state [ctl02.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.657053 duration_in_ms=1.604
2018-01-23 09:02:11,657 [salt.state       ][INFO    ][27897] Running state [ctl03] at time 09:02:11.657303
2018-01-23 09:02:11,657 [salt.state       ][INFO    ][27897] Executing state host.present for ctl03
2018-01-23 09:02:11,659 [salt.state       ][INFO    ][27897] {'host': 'ctl03'}
2018-01-23 09:02:11,659 [salt.state       ][INFO    ][27897] Completed state [ctl03] at time 09:02:11.659272 duration_in_ms=1.969
2018-01-23 09:02:11,659 [salt.state       ][INFO    ][27897] Running state [ctl03.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.659516
2018-01-23 09:02:11,659 [salt.state       ][INFO    ][27897] Executing state host.present for ctl03.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,660 [salt.state       ][INFO    ][27897] {'host': 'ctl03.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,661 [salt.state       ][INFO    ][27897] Completed state [ctl03.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.661125 duration_in_ms=1.609
2018-01-23 09:02:11,661 [salt.state       ][INFO    ][27897] Running state [ctl01] at time 09:02:11.661365
2018-01-23 09:02:11,661 [salt.state       ][INFO    ][27897] Executing state host.present for ctl01
2018-01-23 09:02:11,662 [salt.state       ][INFO    ][27897] {'host': 'ctl01'}
2018-01-23 09:02:11,663 [salt.state       ][INFO    ][27897] Completed state [ctl01] at time 09:02:11.662977 duration_in_ms=1.612
2018-01-23 09:02:11,663 [salt.state       ][INFO    ][27897] Running state [ctl01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.663221
2018-01-23 09:02:11,663 [salt.state       ][INFO    ][27897] Executing state host.present for ctl01.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,664 [salt.state       ][INFO    ][27897] {'host': 'ctl01.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,665 [salt.state       ][INFO    ][27897] Completed state [ctl01.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.664944 duration_in_ms=1.722
2018-01-23 09:02:11,665 [salt.state       ][INFO    ][27897] Running state [ctl] at time 09:02:11.665225
2018-01-23 09:02:11,665 [salt.state       ][INFO    ][27897] Executing state host.present for ctl
2018-01-23 09:02:11,666 [salt.state       ][INFO    ][27897] {'host': 'ctl'}
2018-01-23 09:02:11,666 [salt.state       ][INFO    ][27897] Completed state [ctl] at time 09:02:11.666858 duration_in_ms=1.632
2018-01-23 09:02:11,667 [salt.state       ][INFO    ][27897] Running state [ctl.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.667092
2018-01-23 09:02:11,667 [salt.state       ][INFO    ][27897] Executing state host.present for ctl.baremetal-mcp-pike-odl-ha.local
2018-01-23 09:02:11,668 [salt.state       ][INFO    ][27897] {'host': 'ctl.baremetal-mcp-pike-odl-ha.local'}
2018-01-23 09:02:11,668 [salt.state       ][INFO    ][27897] Completed state [ctl.baremetal-mcp-pike-odl-ha.local] at time 09:02:11.668699 duration_in_ms=1.607
2018-01-23 09:02:11,669 [salt.state       ][INFO    ][27897] Running state [/etc/network/interfaces.d/50-cloud-init.cfg] at time 09:02:11.668936
2018-01-23 09:02:11,669 [salt.state       ][INFO    ][27897] Executing state file.absent for /etc/network/interfaces.d/50-cloud-init.cfg
2018-01-23 09:02:11,669 [salt.state       ][INFO    ][27897] {'removed': '/etc/network/interfaces.d/50-cloud-init.cfg'}
2018-01-23 09:02:11,669 [salt.state       ][INFO    ][27897] Completed state [/etc/network/interfaces.d/50-cloud-init.cfg] at time 09:02:11.669794 duration_in_ms=0.858
2018-01-23 09:02:11,679 [salt.state       ][INFO    ][27897] Running state [ens3] at time 09:02:11.679159
2018-01-23 09:02:11,679 [salt.state       ][INFO    ][27897] Executing state network.managed for ens3
2018-01-23 09:02:11,829 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['ifdown', 'ens3'] in directory '/root'
2018-01-23 09:02:12,945 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['ifup', 'ens3'] in directory '/root'
2018-01-23 09:02:13,127 [salt.state       ][INFO    ][27897] {'interface': 'Added network interface.', 'status': 'Interface ens3 restart to validate'}
2018-01-23 09:02:13,128 [salt.state       ][INFO    ][27897] Completed state [ens3] at time 09:02:13.128248 duration_in_ms=1449.087
2018-01-23 09:02:13,129 [salt.state       ][INFO    ][27897] Running state [ens4] at time 09:02:13.129049
2018-01-23 09:02:13,129 [salt.state       ][INFO    ][27897] Executing state network.managed for ens4
2018-01-23 09:02:13,163 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['ifup', 'ens4'] in directory '/root'
2018-01-23 09:02:14,013 [salt.state       ][INFO    ][27897] {'interface': 'Added network interface.', 'status': 'Interface ens4 is up'}
2018-01-23 09:02:14,015 [salt.state       ][INFO    ][27897] Completed state [ens4] at time 09:02:14.015270 duration_in_ms=886.22
2018-01-23 09:02:14,016 [salt.state       ][INFO    ][27897] Running state [ens5] at time 09:02:14.015955
2018-01-23 09:02:14,016 [salt.state       ][INFO    ][27897] Executing state network.managed for ens5
2018-01-23 09:02:14,061 [salt.loaded.int.module.cmdmod][INFO    ][27897] Executing command ['ifup', 'ens5'] in directory '/root'
2018-01-23 09:02:14,829 [salt.state       ][INFO    ][27897] {'interface': 'Added network interface.', 'status': 'Interface ens5 is up'}
2018-01-23 09:02:14,829 [salt.state       ][INFO    ][27897] Completed state [ens5] at time 09:02:14.829492 duration_in_ms=813.538
2018-01-23 09:02:14,829 [salt.state       ][INFO    ][27897] Running state [/etc/profile.d/proxy.sh] at time 09:02:14.829829
2018-01-23 09:02:14,830 [salt.state       ][INFO    ][27897] Executing state file.absent for /etc/profile.d/proxy.sh
2018-01-23 09:02:14,830 [salt.state       ][INFO    ][27897] File /etc/profile.d/proxy.sh is not present
2018-01-23 09:02:14,831 [salt.state       ][INFO    ][27897] Completed state [/etc/profile.d/proxy.sh] at time 09:02:14.831143 duration_in_ms=1.314
2018-01-23 09:02:14,831 [salt.state       ][INFO    ][27897] Running state [/etc/apt/apt.conf.d/95proxies] at time 09:02:14.831387
2018-01-23 09:02:14,831 [salt.state       ][INFO    ][27897] Executing state file.absent for /etc/apt/apt.conf.d/95proxies
2018-01-23 09:02:14,831 [salt.state       ][INFO    ][27897] File /etc/apt/apt.conf.d/95proxies is not present
2018-01-23 09:02:14,832 [salt.state       ][INFO    ][27897] Completed state [/etc/apt/apt.conf.d/95proxies] at time 09:02:14.832164 duration_in_ms=0.777
2018-01-23 09:02:14,836 [salt.minion      ][INFO    ][27897] Returning information for job: 20180123090129346204
2018-01-23 09:03:00,587 [salt.minion      ][INFO    ][27351] User sudo_ubuntu Executing command state.sls with jid 20180123090300573273
2018-01-23 09:03:00,636 [salt.minion      ][INFO    ][31967] Starting a new job with PID 31967
2018-01-23 09:03:03,325 [salt.state       ][INFO    ][31967] Loading fresh modules for state activity
2018-01-23 09:03:03,380 [salt.fileclient  ][INFO    ][31967] Fetching file from saltenv 'base', ** done ** 'ntp/init.sls'
2018-01-23 09:03:03,410 [salt.fileclient  ][INFO    ][31967] Fetching file from saltenv 'base', ** done ** 'ntp/client.sls'
2018-01-23 09:03:03,466 [salt.fileclient  ][INFO    ][31967] Fetching file from saltenv 'base', ** done ** 'ntp/server.sls'
2018-01-23 09:03:04,024 [salt.state       ][INFO    ][31967] Running state [ntp] at time 09:03:04.024864
2018-01-23 09:03:04,025 [salt.state       ][INFO    ][31967] Executing state pkg.installed for ntp
2018-01-23 09:03:04,025 [salt.loaded.int.module.cmdmod][INFO    ][31967] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2018-01-23 09:03:05,515 [salt.loaded.int.module.cmdmod][INFO    ][31967] Executing command ['apt-cache', '-q', 'policy', 'ntp'] in directory '/root'
2018-01-23 09:03:05,590 [salt.loaded.int.module.cmdmod][INFO    ][31967] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-01-23 09:03:07,735 [salt.loaded.int.module.cmdmod][INFO    ][31967] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-01-23 09:03:07,774 [salt.loaded.int.module.cmdmod][INFO    ][31967] 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-01-23 09:03:11,166 [salt.minion      ][INFO    ][27351] User sudo_ubuntu Executing command saltutil.find_job with jid 20180123090311131642
2018-01-23 09:03:11,307 [salt.minion      ][INFO    ][508] Starting a new job with PID 508
2018-01-23 09:03:11,327 [salt.minion      ][INFO    ][508] Returning information for job: 20180123090311131642
2018-01-23 09:03:13,238 [salt.loaded.int.module.cmdmod][INFO    ][31967] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2018-01-23 09:03:13,284 [salt.state       ][INFO    ][31967] Made the following changes:
'ntp' changed from 'absent' to '1:4.2.8p4+dfsg-3ubuntu5.7'
'libopts25' changed from 'absent' to '1:5.18.7-3'

2018-01-23 09:03:13,302 [salt.state       ][INFO    ][31967] Loading fresh modules for state activity
2018-01-23 09:03:13,342 [salt.state       ][INFO    ][31967] Completed state [ntp] at time 09:03:13.342550 duration_in_ms=9317.685
2018-01-23 09:03:13,347 [salt.state       ][INFO    ][31967] Running state [/etc/ntp.conf] at time 09:03:13.347503
2018-01-23 09:03:13,347 [salt.state       ][INFO    ][31967] Executing state file.managed for /etc/ntp.conf
2018-01-23 09:03:13,378 [salt.fileclient  ][INFO    ][31967] Fetching file from saltenv 'base', ** done ** 'ntp/files/ntp.conf'
2018-01-23 09:03:13,429 [salt.state       ][INFO    ][31967] File changed:
--- 
+++ 
@@ -1,66 +1,24 @@
-# /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
-
-# 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 default noquery nopeer
 restrict 127.0.0.1
 restrict ::1
 
-# Needed for adding pool entries
-restrict source notrap nomodify noquery
-
-# Clients from this (example!) subnet have unlimited access, but only if
-# cryptographically authenticated.
-#restrict 192.168.123.0 mask 255.255.255.0 notrust
+# mode7 is required for collectd monitoring
 
 
-# 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
+logfile /var/log/ntp.log
2018-01-23 09:03:13,429 [salt.state       ][INFO    ][31967] Completed state [/etc/ntp.conf] at time 09:03:13.429421 duration_in_ms=81.917
2018-01-23 09:03:13,487 [salt.state       ][INFO    ][31967] Running state [ntp] at time 09:03:13.486996
2018-01-23 09:03:13,487 [salt.state       ][INFO    ][31967] Executing state service.running for ntp
2018-01-23 09:03:13,490 [salt.loaded.int.module.cmdmod][INFO    ][31967] Executing command ['systemctl', 'status', 'ntp.service', '-n', '0'] in directory '/root'
2018-01-23 09:03:13,510 [salt.loaded.int.module.cmdmod][INFO    ][31967] Executing command ['systemctl', 'is-active', 'ntp.service'] in directory '/root'
2018-01-23 09:03:13,524 [salt.loaded.int.module.cmdmod][INFO    ][31967] Executing command ['systemctl', 'is-enabled', 'ntp.service'] in directory '/root'
2018-01-23 09:03:13,544 [salt.state       ][INFO    ][31967] The service ntp is already running
2018-01-23 09:03:13,545 [salt.state       ][INFO    ][31967] Completed state [ntp] at time 09:03:13.545107 duration_in_ms=58.111
2018-01-23 09:03:13,545 [salt.state       ][INFO    ][31967] Running state [ntp] at time 09:03:13.545450
2018-01-23 09:03:13,545 [salt.state       ][INFO    ][31967] Executing state service.mod_watch for ntp
2018-01-23 09:03:13,546 [salt.loaded.int.module.cmdmod][INFO    ][31967] Executing command ['systemctl', 'is-active', 'ntp.service'] in directory '/root'
2018-01-23 09:03:13,564 [salt.loaded.int.module.cmdmod][INFO    ][31967] Executing command ['systemctl', 'is-enabled', 'ntp.service'] in directory '/root'
2018-01-23 09:03:13,584 [salt.loaded.int.module.cmdmod][INFO    ][31967] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'ntp.service'] in directory '/root'
2018-01-23 09:03:13,670 [salt.state       ][INFO    ][31967] {'ntp': True}
2018-01-23 09:03:13,672 [salt.state       ][INFO    ][31967] Completed state [ntp] at time 09:03:13.672105 duration_in_ms=126.655
2018-01-23 09:03:13,674 [salt.minion      ][INFO    ][31967] Returning information for job: 20180123090300573273
2018-01-23 09:50:37,748 [salt.minion      ][INFO    ][27351] User root Executing command x509.sign_remote_certificate with jid 20180123095037737472
2018-01-23 09:50:37,827 [salt.minion      ][INFO    ][5716] Starting a new job with PID 5716
2018-01-23 09:50:37,875 [salt.minion      ][INFO    ][5716] Returning information for job: 20180123095037737472
2018-01-23 09:50:38,775 [salt.minion      ][INFO    ][27351] User root Executing command x509.sign_remote_certificate with jid 20180123095038755719
2018-01-23 09:50:38,800 [salt.minion      ][INFO    ][5725] Starting a new job with PID 5725
2018-01-23 09:50:38,840 [salt.minion      ][INFO    ][5725] Returning information for job: 20180123095038755719
2018-01-23 09:50:39,087 [salt.minion      ][INFO    ][27351] User root Executing command x509.sign_remote_certificate with jid 20180123095039066424
2018-01-23 09:50:39,126 [salt.minion      ][INFO    ][5734] Starting a new job with PID 5734
2018-01-23 09:50:39,201 [salt.minion      ][INFO    ][5734] Returning information for job: 20180123095039066424
2018-01-23 09:50:39,210 [salt.minion      ][INFO    ][27351] User root Executing command x509.sign_remote_certificate with jid 20180123095039194312
2018-01-23 09:50:39,243 [salt.minion      ][INFO    ][5743] Starting a new job with PID 5743
2018-01-23 09:50:39,306 [salt.minion      ][INFO    ][5743] Returning information for job: 20180123095039194312
2018-01-23 10:00:53,937 [salt.utils.schedule][INFO    ][27351] Running scheduled job: __mine_interval
2018-01-23 11:00:53,937 [salt.utils.schedule][INFO    ][27351] Running scheduled job: __mine_interval
2018-01-23 11:04:03,139 [salt.minion      ][INFO    ][27351] User sudo_ubuntu Executing command cp.push_dir with jid 20180123110403125239
2018-01-23 11:04:03,166 [salt.minion      ][INFO    ][11013] Starting a new job with PID 11013
