2018-01-26 18:02:08,639 [salt.utils       ][ERROR   ][1282] DNS lookup or connection check of 'salt' failed.
2018-01-26 18:02:08,640 [salt.minion      ][ERROR   ][1282] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2018-01-26 18:02:09,073 [salt.utils.parsers][WARNING ][1282] Minion received a SIGTERM. Exiting.
2018-01-26 18:06:19,008 [py.warnings      ][WARNING ][1550] /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-26 18:06:31,786 [salt.loaded.int.module.cmdmod][ERROR   ][1550] 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-26 18:06:33,965 [salt.loaded.int.module.cmdmod][INFO    ][2060] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2018-01-26 18:06:33,982 [salt.loaded.int.module.cmdmod][INFO    ][2060] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2018-01-26 18:06:34,004 [salt.loaded.int.module.cmdmod][INFO    ][2060] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2018-01-26 18:06:34,029 [salt.utils.parsers][WARNING ][1372] Minion received a SIGTERM. Exiting.
2018-01-26 18:06:34,905 [salt.cli.daemons ][INFO    ][2109] Setting up the Salt Minion "odl01.virtual-mcp-ocata-odl-router-noha.local"
2018-01-26 18:06:35,002 [salt.cli.daemons ][INFO    ][2109] Starting up the Salt Minion
2018-01-26 18:06:35,002 [salt.utils.event ][INFO    ][2109] Starting pull socket on /var/run/salt/minion/minion_event_85dcaba890_pull.ipc
2018-01-26 18:06:35,459 [salt.minion      ][INFO    ][2109] Creating minion process manager
2018-01-26 18:06:36,287 [salt.loader.10.20.0.2.int.module.cmdmod][INFO    ][2109] Executing command ['date', '+%z'] in directory '/root'
2018-01-26 18:06:36,302 [salt.utils.schedule][INFO    ][2109] Updating job settings for scheduled job: __mine_interval
2018-01-26 18:06:36,304 [salt.minion      ][INFO    ][2109] Added mine.update to scheduler
2018-01-26 18:06:36,309 [salt.minion      ][INFO    ][2109] Minion is starting as user 'root'
2018-01-26 18:06:36,323 [salt.minion      ][INFO    ][2109] Minion is ready to receive requests!
2018-01-26 18:06:37,325 [salt.utils.schedule][INFO    ][2109] Running scheduled job: __mine_interval
2018-01-26 18:07:12,684 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command state.sls with jid 20180126180712668307
2018-01-26 18:07:12,710 [salt.minion      ][INFO    ][2200] Starting a new job with PID 2200
2018-01-26 18:07:15,436 [salt.state       ][INFO    ][2200] Loading fresh modules for state activity
2018-01-26 18:07:15,478 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/init.sls'
2018-01-26 18:07:15,508 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/system/init.sls'
2018-01-26 18:07:15,627 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/system/env.sls'
2018-01-26 18:07:15,721 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/system/profile.sls'
2018-01-26 18:07:15,812 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/system/repo.sls'
2018-01-26 18:07:16,007 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/system/package.sls'
2018-01-26 18:07:16,132 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/system/timezone.sls'
2018-01-26 18:07:16,231 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/system/kernel.sls'
2018-01-26 18:07:16,366 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/system/cpu.sls'
2018-01-26 18:07:16,467 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/system/sysfs.sls'
2018-01-26 18:07:16,574 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/system/locale.sls'
2018-01-26 18:07:16,682 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/system/user.sls'
2018-01-26 18:07:16,812 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/system/group.sls'
2018-01-26 18:07:16,918 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/system/limit.sls'
2018-01-26 18:07:17,023 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/system/systemd.sls'
2018-01-26 18:07:17,127 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/system/apt.sls'
2018-01-26 18:07:17,248 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/network/init.sls'
2018-01-26 18:07:17,351 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/network/hostname.sls'
2018-01-26 18:07:17,450 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/network/host.sls'
2018-01-26 18:07:17,592 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/network/interface.sls'
2018-01-26 18:07:17,781 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/network/proxy.sls'
2018-01-26 18:07:17,892 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/storage/init.sls'
2018-01-26 18:07:17,989 [salt.state       ][INFO    ][2200] Running state [/etc/environment] at time 18:07:17.989504
2018-01-26 18:07:17,990 [salt.state       ][INFO    ][2200] Executing state file.blockreplace for /etc/environment
2018-01-26 18:07:17,999 [salt.state       ][INFO    ][2200] 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-26 18:07:18,000 [salt.state       ][INFO    ][2200] Completed state [/etc/environment] at time 18:07:18.000331 duration_in_ms=10.828
2018-01-26 18:07:18,000 [salt.state       ][INFO    ][2200] Running state [/etc/profile.d] at time 18:07:18.000762
2018-01-26 18:07:18,001 [salt.state       ][INFO    ][2200] Executing state file.directory for /etc/profile.d
2018-01-26 18:07:18,002 [salt.state       ][INFO    ][2200] Directory /etc/profile.d is in the correct state
2018-01-26 18:07:18,003 [salt.state       ][INFO    ][2200] Completed state [/etc/profile.d] at time 18:07:18.003352 duration_in_ms=2.59
2018-01-26 18:07:18,478 [salt.state       ][INFO    ][2200] Running state [/etc/apt/apt.conf.d/99compression-workaround-salt] at time 18:07:18.478712
2018-01-26 18:07:18,479 [salt.state       ][INFO    ][2200] Executing state file.managed for /etc/apt/apt.conf.d/99compression-workaround-salt
2018-01-26 18:07:18,501 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/files/apt.conf'
2018-01-26 18:07:18,508 [salt.state       ][INFO    ][2200] File changed:
New file
2018-01-26 18:07:18,510 [salt.state       ][INFO    ][2200] Completed state [/etc/apt/apt.conf.d/99compression-workaround-salt] at time 18:07:18.509444 duration_in_ms=30.732
2018-01-26 18:07:18,510 [salt.state       ][INFO    ][2200] Running state [/etc/apt/apt.conf.d/99prefer_ipv4-salt] at time 18:07:18.510450
2018-01-26 18:07:18,510 [salt.state       ][INFO    ][2200] Executing state file.managed for /etc/apt/apt.conf.d/99prefer_ipv4-salt
2018-01-26 18:07:18,526 [salt.state       ][INFO    ][2200] File changed:
New file
2018-01-26 18:07:18,526 [salt.state       ][INFO    ][2200] Completed state [/etc/apt/apt.conf.d/99prefer_ipv4-salt] at time 18:07:18.526530 duration_in_ms=16.08
2018-01-26 18:07:18,527 [salt.state       ][INFO    ][2200] Running state [linux_repo_prereq_pkgs] at time 18:07:18.527528
2018-01-26 18:07:18,527 [salt.state       ][INFO    ][2200] Executing state pkg.installed for linux_repo_prereq_pkgs
2018-01-26 18:07:18,528 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2018-01-26 18:07:18,862 [salt.state       ][INFO    ][2200] All specified packages are already installed
2018-01-26 18:07:18,863 [salt.state       ][INFO    ][2200] Completed state [linux_repo_prereq_pkgs] at time 18:07:18.863714 duration_in_ms=336.185
2018-01-26 18:07:18,864 [salt.state       ][INFO    ][2200] Running state [/etc/apt/apt.conf.d/99proxies-salt-mk_openstack] at time 18:07:18.864243
2018-01-26 18:07:18,864 [salt.state       ][INFO    ][2200] Executing state file.absent for /etc/apt/apt.conf.d/99proxies-salt-mk_openstack
2018-01-26 18:07:18,865 [salt.state       ][INFO    ][2200] File /etc/apt/apt.conf.d/99proxies-salt-mk_openstack is not present
2018-01-26 18:07:18,865 [salt.state       ][INFO    ][2200] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mk_openstack] at time 18:07:18.865729 duration_in_ms=1.486
2018-01-26 18:07:18,866 [salt.state       ][INFO    ][2200] Running state [/etc/apt/preferences.d/mk_openstack] at time 18:07:18.866144
2018-01-26 18:07:18,866 [salt.state       ][INFO    ][2200] Executing state file.managed for /etc/apt/preferences.d/mk_openstack
2018-01-26 18:07:18,882 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/files/preferences_repo'
2018-01-26 18:07:18,959 [salt.state       ][INFO    ][2200] File changed:
New file
2018-01-26 18:07:18,959 [salt.state       ][INFO    ][2200] Completed state [/etc/apt/preferences.d/mk_openstack] at time 18:07:18.959648 duration_in_ms=93.502
2018-01-26 18:07:18,963 [salt.state       ][INFO    ][2200] Running state [deb [arch=amd64] http://apt.mirantis.com/xenial/ 2017.12 ocata] at time 18:07:18.963182
2018-01-26 18:07:18,963 [salt.state       ][INFO    ][2200] Executing state pkgrepo.managed for deb [arch=amd64] http://apt.mirantis.com/xenial/ 2017.12 ocata
2018-01-26 18:07:19,330 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['apt-key', 'add', '/var/cache/salt/minion/extrn_files/base/apt.mirantis.com/public.gpg'] in directory '/root'
2018-01-26 18:07:19,525 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-01-26 18:07:22,771 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126180722761295
2018-01-26 18:07:22,792 [salt.minion      ][INFO    ][2682] Starting a new job with PID 2682
2018-01-26 18:07:22,812 [salt.minion      ][INFO    ][2682] Returning information for job: 20180126180722761295
2018-01-26 18:07:23,280 [salt.state       ][INFO    ][2200] {'repo': 'deb [arch=amd64] http://apt.mirantis.com/xenial 2017.12 ocata'}
2018-01-26 18:07:23,281 [salt.state       ][INFO    ][2200] Completed state [deb [arch=amd64] http://apt.mirantis.com/xenial/ 2017.12 ocata] at time 18:07:23.280988 duration_in_ms=4317.805
2018-01-26 18:07:23,281 [salt.state       ][INFO    ][2200] Running state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack] at time 18:07:23.281527
2018-01-26 18:07:23,282 [salt.state       ][INFO    ][2200] Executing state file.absent for /etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack
2018-01-26 18:07:23,282 [salt.state       ][INFO    ][2200] File /etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack is not present
2018-01-26 18:07:23,283 [salt.state       ][INFO    ][2200] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mirantis_openstack] at time 18:07:23.283021 duration_in_ms=1.494
2018-01-26 18:07:23,283 [salt.state       ][INFO    ][2200] Running state [/etc/apt/preferences.d/mirantis_openstack] at time 18:07:23.283447
2018-01-26 18:07:23,283 [salt.state       ][INFO    ][2200] Executing state file.managed for /etc/apt/preferences.d/mirantis_openstack
2018-01-26 18:07:23,373 [salt.state       ][INFO    ][2200] File changed:
New file
2018-01-26 18:07:23,374 [salt.state       ][INFO    ][2200] Completed state [/etc/apt/preferences.d/mirantis_openstack] at time 18:07:23.373969 duration_in_ms=90.521
2018-01-26 18:07:23,375 [salt.state       ][INFO    ][2200] Running state [deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata main] at time 18:07:23.374992
2018-01-26 18:07:23,375 [salt.state       ][INFO    ][2200] Executing state pkgrepo.managed for deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata main
2018-01-26 18:07:23,853 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['apt-key', 'add', '/var/cache/salt/minion/extrn_files/base/mirror.fuel-infra.org/mcp-repos/ocata/xenial/archive-mcpocata.key'] in directory '/root'
2018-01-26 18:07:24,003 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-01-26 18:07:27,680 [salt.state       ][INFO    ][2200] {'repo': 'deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata main'}
2018-01-26 18:07:27,680 [salt.state       ][INFO    ][2200] Completed state [deb http://mirror.fuel-infra.org/mcp-repos/ocata/xenial ocata main] at time 18:07:27.680858 duration_in_ms=4305.866
2018-01-26 18:07:27,681 [salt.state       ][INFO    ][2200] Running state [/etc/apt/apt.conf.d/99proxies-salt-uca] at time 18:07:27.681183
2018-01-26 18:07:27,681 [salt.state       ][INFO    ][2200] Executing state file.absent for /etc/apt/apt.conf.d/99proxies-salt-uca
2018-01-26 18:07:27,682 [salt.state       ][INFO    ][2200] File /etc/apt/apt.conf.d/99proxies-salt-uca is not present
2018-01-26 18:07:27,682 [salt.state       ][INFO    ][2200] Completed state [/etc/apt/apt.conf.d/99proxies-salt-uca] at time 18:07:27.682706 duration_in_ms=1.523
2018-01-26 18:07:27,683 [salt.state       ][INFO    ][2200] Running state [/etc/apt/preferences.d/uca] at time 18:07:27.683126
2018-01-26 18:07:27,683 [salt.state       ][INFO    ][2200] Executing state file.absent for /etc/apt/preferences.d/uca
2018-01-26 18:07:27,684 [salt.state       ][INFO    ][2200] File /etc/apt/preferences.d/uca is not present
2018-01-26 18:07:27,684 [salt.state       ][INFO    ][2200] Completed state [/etc/apt/preferences.d/uca] at time 18:07:27.684380 duration_in_ms=1.255
2018-01-26 18:07:27,685 [salt.state       ][INFO    ][2200] Running state [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/ocata main] at time 18:07:27.685344
2018-01-26 18:07:27,685 [salt.state       ][INFO    ][2200] Executing state pkgrepo.managed for deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/ocata main
2018-01-26 18:07:27,825 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['apt-key', 'export', 'EC4926EA'] in directory '/root'
2018-01-26 18:07:27,897 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['apt-key', 'adv', '--keyserver', 'keyserver.ubuntu.com', '--logger-fd', '1', '--recv-keys', 'EC4926EA'] in directory '/root'
2018-01-26 18:07:28,177 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-01-26 18:07:31,911 [salt.state       ][INFO    ][2200] {'repo': 'deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/ocata main'}
2018-01-26 18:07:31,912 [salt.state       ][INFO    ][2200] Completed state [deb http://ubuntu-cloud.archive.canonical.com/ubuntu xenial-updates/ocata main] at time 18:07:31.912204 duration_in_ms=4226.86
2018-01-26 18:07:31,912 [salt.state       ][INFO    ][2200] Running state [/etc/apt/apt.conf.d/99proxies-salt-mcp_extra] at time 18:07:31.912517
2018-01-26 18:07:31,912 [salt.state       ][INFO    ][2200] Executing state file.absent for /etc/apt/apt.conf.d/99proxies-salt-mcp_extra
2018-01-26 18:07:31,913 [salt.state       ][INFO    ][2200] File /etc/apt/apt.conf.d/99proxies-salt-mcp_extra is not present
2018-01-26 18:07:31,914 [salt.state       ][INFO    ][2200] Completed state [/etc/apt/apt.conf.d/99proxies-salt-mcp_extra] at time 18:07:31.913422 duration_in_ms=0.905
2018-01-26 18:07:31,915 [salt.state       ][INFO    ][2200] Running state [/etc/apt/preferences.d/mcp_extra] at time 18:07:31.915043
2018-01-26 18:07:31,915 [salt.state       ][INFO    ][2200] Executing state file.managed for /etc/apt/preferences.d/mcp_extra
2018-01-26 18:07:32,006 [salt.state       ][INFO    ][2200] File changed:
New file
2018-01-26 18:07:32,006 [salt.state       ][INFO    ][2200] Completed state [/etc/apt/preferences.d/mcp_extra] at time 18:07:32.006256 duration_in_ms=91.213
2018-01-26 18:07:32,007 [salt.state       ][INFO    ][2200] Running state [deb [arch=amd64] http://apt.mirantis.com/xenial/ 2017.12 extra] at time 18:07:32.007054
2018-01-26 18:07:32,007 [salt.state       ][INFO    ][2200] Executing state pkgrepo.managed for deb [arch=amd64] http://apt.mirantis.com/xenial/ 2017.12 extra
2018-01-26 18:07:32,100 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['apt-key', 'add', '/var/cache/salt/minion/extrn_files/base/apt.mirantis.com/public.gpg'] in directory '/root'
2018-01-26 18:07:32,238 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-01-26 18:07:32,792 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126180732781644
2018-01-26 18:07:32,812 [salt.minion      ][INFO    ][4135] Starting a new job with PID 4135
2018-01-26 18:07:32,829 [salt.minion      ][INFO    ][4135] Returning information for job: 20180126180732781644
2018-01-26 18:07:36,194 [salt.state       ][INFO    ][2200] {'repo': 'deb [arch=amd64] http://apt.mirantis.com/xenial 2017.12 extra'}
2018-01-26 18:07:36,195 [salt.state       ][INFO    ][2200] Completed state [deb [arch=amd64] http://apt.mirantis.com/xenial/ 2017.12 extra] at time 18:07:36.195749 duration_in_ms=4188.694
2018-01-26 18:07:36,196 [salt.state       ][INFO    ][2200] Running state [linux_extra_packages_purged] at time 18:07:36.196282
2018-01-26 18:07:36,196 [salt.state       ][INFO    ][2200] Executing state pkg.purged for linux_extra_packages_purged
2018-01-26 18:07:36,229 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', 'purge', 'cloud-init', 'unattended-upgrades'] in directory '/root'
2018-01-26 18:07:40,136 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2018-01-26 18:07:40,172 [salt.state       ][INFO    ][2200] {'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-26 18:07:40,189 [salt.state       ][INFO    ][2200] Loading fresh modules for state activity
2018-01-26 18:07:40,219 [salt.state       ][INFO    ][2200] Completed state [linux_extra_packages_purged] at time 18:07:40.219857 duration_in_ms=4023.575
2018-01-26 18:07:40,229 [salt.state       ][INFO    ][2200] Running state [linux_extra_packages_latest] at time 18:07:40.229021
2018-01-26 18:07:40,229 [salt.state       ][INFO    ][2200] Executing state pkg.latest for linux_extra_packages_latest
2018-01-26 18:07:40,592 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['apt-cache', '-q', 'policy', 'mcelog'] in directory '/root'
2018-01-26 18:07:40,646 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-01-26 18:07:40,668 [salt.loaded.int.module.cmdmod][INFO    ][2200] 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-26 18:07:42,807 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126180742794515
2018-01-26 18:07:42,826 [salt.minion      ][INFO    ][5380] Starting a new job with PID 5380
2018-01-26 18:07:42,843 [salt.minion      ][INFO    ][5380] Returning information for job: 20180126180742794515
2018-01-26 18:07:43,331 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2018-01-26 18:07:43,366 [salt.state       ][INFO    ][2200] Made the following changes:
'mcelog' changed from 'absent' to '128+dfsg-1'

2018-01-26 18:07:43,382 [salt.state       ][INFO    ][2200] Loading fresh modules for state activity
2018-01-26 18:07:43,414 [salt.state       ][INFO    ][2200] Completed state [linux_extra_packages_latest] at time 18:07:43.414612 duration_in_ms=3185.59
2018-01-26 18:07:43,418 [salt.state       ][INFO    ][2200] Running state [UTC] at time 18:07:43.418376
2018-01-26 18:07:43,418 [salt.state       ][INFO    ][2200] Executing state timezone.system for UTC
2018-01-26 18:07:43,420 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['timedatectl'] in directory '/root'
2018-01-26 18:07:43,487 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['timedatectl'] in directory '/root'
2018-01-26 18:07:43,501 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'timedatectl set-timezone UTC' in directory '/root'
2018-01-26 18:07:43,515 [salt.state       ][INFO    ][2200] {'timezone': 'UTC'}
2018-01-26 18:07:43,515 [salt.state       ][INFO    ][2200] Completed state [UTC] at time 18:07:43.515727 duration_in_ms=97.35
2018-01-26 18:07:43,519 [salt.state       ][INFO    ][2200] Running state [nf_conntrack] at time 18:07:43.519864
2018-01-26 18:07:43,520 [salt.state       ][INFO    ][2200] Executing state kmod.present for nf_conntrack
2018-01-26 18:07:43,521 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'lsmod' in directory '/root'
2018-01-26 18:07:43,603 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'lsmod' in directory '/root'
2018-01-26 18:07:43,616 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'modprobe nf_conntrack' in directory '/root'
2018-01-26 18:07:43,632 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'lsmod' in directory '/root'
2018-01-26 18:07:43,669 [salt.state       ][INFO    ][2200] {'nf_conntrack': 'loaded'}
2018-01-26 18:07:43,669 [salt.state       ][INFO    ][2200] Completed state [nf_conntrack] at time 18:07:43.669601 duration_in_ms=149.736
2018-01-26 18:07:43,672 [salt.state       ][INFO    ][2200] Running state [kernel.panic] at time 18:07:43.672455
2018-01-26 18:07:43,672 [salt.state       ][INFO    ][2200] Executing state sysctl.present for kernel.panic
2018-01-26 18:07:43,675 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:43,723 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w kernel.panic="60"' in directory '/root'
2018-01-26 18:07:43,733 [salt.state       ][INFO    ][2200] {'kernel.panic': 60}
2018-01-26 18:07:43,734 [salt.state       ][INFO    ][2200] Completed state [kernel.panic] at time 18:07:43.734256 duration_in_ms=61.8
2018-01-26 18:07:43,734 [salt.state       ][INFO    ][2200] Running state [net.ipv4.tcp_keepalive_probes] at time 18:07:43.734808
2018-01-26 18:07:43,735 [salt.state       ][INFO    ][2200] Executing state sysctl.present for net.ipv4.tcp_keepalive_probes
2018-01-26 18:07:43,736 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:43,763 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w net.ipv4.tcp_keepalive_probes="8"' in directory '/root'
2018-01-26 18:07:43,773 [salt.state       ][INFO    ][2200] {'net.ipv4.tcp_keepalive_probes': 8}
2018-01-26 18:07:43,774 [salt.state       ][INFO    ][2200] Completed state [net.ipv4.tcp_keepalive_probes] at time 18:07:43.774116 duration_in_ms=39.308
2018-01-26 18:07:43,774 [salt.state       ][INFO    ][2200] Running state [fs.file-max] at time 18:07:43.774742
2018-01-26 18:07:43,775 [salt.state       ][INFO    ][2200] Executing state sysctl.present for fs.file-max
2018-01-26 18:07:43,775 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:43,801 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w fs.file-max="124165"' in directory '/root'
2018-01-26 18:07:43,812 [salt.state       ][INFO    ][2200] {'fs.file-max': 124165}
2018-01-26 18:07:43,813 [salt.state       ][INFO    ][2200] Completed state [fs.file-max] at time 18:07:43.813066 duration_in_ms=38.324
2018-01-26 18:07:43,813 [salt.state       ][INFO    ][2200] Running state [net.core.somaxconn] at time 18:07:43.813584
2018-01-26 18:07:43,814 [salt.state       ][INFO    ][2200] Executing state sysctl.present for net.core.somaxconn
2018-01-26 18:07:43,815 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:43,844 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w net.core.somaxconn="4096"' in directory '/root'
2018-01-26 18:07:43,856 [salt.state       ][INFO    ][2200] {'net.core.somaxconn': 4096}
2018-01-26 18:07:43,857 [salt.state       ][INFO    ][2200] Completed state [net.core.somaxconn] at time 18:07:43.857176 duration_in_ms=43.591
2018-01-26 18:07:43,858 [salt.state       ][INFO    ][2200] Running state [net.ipv4.tcp_max_syn_backlog] at time 18:07:43.858015
2018-01-26 18:07:43,858 [salt.state       ][INFO    ][2200] Executing state sysctl.present for net.ipv4.tcp_max_syn_backlog
2018-01-26 18:07:43,859 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:43,885 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w net.ipv4.tcp_max_syn_backlog="8192"' in directory '/root'
2018-01-26 18:07:43,896 [salt.state       ][INFO    ][2200] {'net.ipv4.tcp_max_syn_backlog': 8192}
2018-01-26 18:07:43,897 [salt.state       ][INFO    ][2200] Completed state [net.ipv4.tcp_max_syn_backlog] at time 18:07:43.897145 duration_in_ms=39.129
2018-01-26 18:07:43,898 [salt.state       ][INFO    ][2200] Running state [net.ipv4.tcp_tw_reuse] at time 18:07:43.898248
2018-01-26 18:07:43,898 [salt.state       ][INFO    ][2200] Executing state sysctl.present for net.ipv4.tcp_tw_reuse
2018-01-26 18:07:43,899 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:43,927 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w net.ipv4.tcp_tw_reuse="1"' in directory '/root'
2018-01-26 18:07:43,939 [salt.state       ][INFO    ][2200] {'net.ipv4.tcp_tw_reuse': 1}
2018-01-26 18:07:43,940 [salt.state       ][INFO    ][2200] Completed state [net.ipv4.tcp_tw_reuse] at time 18:07:43.939950 duration_in_ms=41.701
2018-01-26 18:07:43,940 [salt.state       ][INFO    ][2200] Running state [net.ipv4.tcp_congestion_control] at time 18:07:43.940707
2018-01-26 18:07:43,941 [salt.state       ][INFO    ][2200] Executing state sysctl.present for net.ipv4.tcp_congestion_control
2018-01-26 18:07:43,942 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:43,969 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w net.ipv4.tcp_congestion_control="yeah"' in directory '/root'
2018-01-26 18:07:43,987 [salt.state       ][INFO    ][2200] {'net.ipv4.tcp_congestion_control': 'yeah'}
2018-01-26 18:07:43,988 [salt.state       ][INFO    ][2200] Completed state [net.ipv4.tcp_congestion_control] at time 18:07:43.988241 duration_in_ms=47.533
2018-01-26 18:07:43,988 [salt.state       ][INFO    ][2200] Running state [net.ipv4.tcp_retries2] at time 18:07:43.988597
2018-01-26 18:07:43,988 [salt.state       ][INFO    ][2200] Executing state sysctl.present for net.ipv4.tcp_retries2
2018-01-26 18:07:43,989 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:44,023 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w net.ipv4.tcp_retries2="5"' in directory '/root'
2018-01-26 18:07:44,036 [salt.state       ][INFO    ][2200] {'net.ipv4.tcp_retries2': 5}
2018-01-26 18:07:44,037 [salt.state       ][INFO    ][2200] Completed state [net.ipv4.tcp_retries2] at time 18:07:44.037314 duration_in_ms=48.716
2018-01-26 18:07:44,038 [salt.state       ][INFO    ][2200] Running state [net.ipv4.tcp_fin_timeout] at time 18:07:44.038409
2018-01-26 18:07:44,038 [salt.state       ][INFO    ][2200] Executing state sysctl.present for net.ipv4.tcp_fin_timeout
2018-01-26 18:07:44,039 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:44,067 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w net.ipv4.tcp_fin_timeout="30"' in directory '/root'
2018-01-26 18:07:44,077 [salt.state       ][INFO    ][2200] {'net.ipv4.tcp_fin_timeout': 30}
2018-01-26 18:07:44,078 [salt.state       ][INFO    ][2200] Completed state [net.ipv4.tcp_fin_timeout] at time 18:07:44.078175 duration_in_ms=39.766
2018-01-26 18:07:44,078 [salt.state       ][INFO    ][2200] Running state [net.ipv4.tcp_slow_start_after_idle] at time 18:07:44.078853
2018-01-26 18:07:44,079 [salt.state       ][INFO    ][2200] Executing state sysctl.present for net.ipv4.tcp_slow_start_after_idle
2018-01-26 18:07:44,079 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:44,106 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w net.ipv4.tcp_slow_start_after_idle="0"' in directory '/root'
2018-01-26 18:07:44,116 [salt.state       ][INFO    ][2200] {'net.ipv4.tcp_slow_start_after_idle': 0}
2018-01-26 18:07:44,116 [salt.state       ][INFO    ][2200] Completed state [net.ipv4.tcp_slow_start_after_idle] at time 18:07:44.116481 duration_in_ms=37.628
2018-01-26 18:07:44,117 [salt.state       ][INFO    ][2200] Running state [vm.swappiness] at time 18:07:44.116980
2018-01-26 18:07:44,117 [salt.state       ][INFO    ][2200] Executing state sysctl.present for vm.swappiness
2018-01-26 18:07:44,119 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:44,152 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w vm.swappiness="10"' in directory '/root'
2018-01-26 18:07:44,162 [salt.state       ][INFO    ][2200] {'vm.swappiness': 10}
2018-01-26 18:07:44,163 [salt.state       ][INFO    ][2200] Completed state [vm.swappiness] at time 18:07:44.163010 duration_in_ms=46.03
2018-01-26 18:07:44,163 [salt.state       ][INFO    ][2200] Running state [net.ipv4.tcp_keepalive_intvl] at time 18:07:44.163527
2018-01-26 18:07:44,164 [salt.state       ][INFO    ][2200] Executing state sysctl.present for net.ipv4.tcp_keepalive_intvl
2018-01-26 18:07:44,164 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:44,190 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w net.ipv4.tcp_keepalive_intvl="3"' in directory '/root'
2018-01-26 18:07:44,200 [salt.state       ][INFO    ][2200] {'net.ipv4.tcp_keepalive_intvl': 3}
2018-01-26 18:07:44,200 [salt.state       ][INFO    ][2200] Completed state [net.ipv4.tcp_keepalive_intvl] at time 18:07:44.200497 duration_in_ms=36.971
2018-01-26 18:07:44,201 [salt.state       ][INFO    ][2200] Running state [net.ipv4.neigh.default.gc_thresh1] at time 18:07:44.201010
2018-01-26 18:07:44,201 [salt.state       ][INFO    ][2200] Executing state sysctl.present for net.ipv4.neigh.default.gc_thresh1
2018-01-26 18:07:44,202 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:44,228 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w net.ipv4.neigh.default.gc_thresh1="4096"' in directory '/root'
2018-01-26 18:07:44,240 [salt.state       ][INFO    ][2200] {'net.ipv4.neigh.default.gc_thresh1': 4096}
2018-01-26 18:07:44,241 [salt.state       ][INFO    ][2200] Completed state [net.ipv4.neigh.default.gc_thresh1] at time 18:07:44.241404 duration_in_ms=40.393
2018-01-26 18:07:44,242 [salt.state       ][INFO    ][2200] Running state [net.ipv4.neigh.default.gc_thresh2] at time 18:07:44.242376
2018-01-26 18:07:44,243 [salt.state       ][INFO    ][2200] Executing state sysctl.present for net.ipv4.neigh.default.gc_thresh2
2018-01-26 18:07:44,244 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:44,272 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w net.ipv4.neigh.default.gc_thresh2="8192"' in directory '/root'
2018-01-26 18:07:44,285 [salt.state       ][INFO    ][2200] {'net.ipv4.neigh.default.gc_thresh2': 8192}
2018-01-26 18:07:44,285 [salt.state       ][INFO    ][2200] Completed state [net.ipv4.neigh.default.gc_thresh2] at time 18:07:44.285418 duration_in_ms=43.042
2018-01-26 18:07:44,287 [salt.state       ][INFO    ][2200] Running state [net.ipv4.neigh.default.gc_thresh3] at time 18:07:44.287436
2018-01-26 18:07:44,287 [salt.state       ][INFO    ][2200] Executing state sysctl.present for net.ipv4.neigh.default.gc_thresh3
2018-01-26 18:07:44,288 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:44,316 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w net.ipv4.neigh.default.gc_thresh3="16384"' in directory '/root'
2018-01-26 18:07:44,330 [salt.state       ][INFO    ][2200] {'net.ipv4.neigh.default.gc_thresh3': 16384}
2018-01-26 18:07:44,330 [salt.state       ][INFO    ][2200] Completed state [net.ipv4.neigh.default.gc_thresh3] at time 18:07:44.330550 duration_in_ms=43.113
2018-01-26 18:07:44,330 [salt.state       ][INFO    ][2200] Running state [net.core.netdev_max_backlog] at time 18:07:44.330884
2018-01-26 18:07:44,331 [salt.state       ][INFO    ][2200] Executing state sysctl.present for net.core.netdev_max_backlog
2018-01-26 18:07:44,331 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:44,359 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w net.core.netdev_max_backlog="261144"' in directory '/root'
2018-01-26 18:07:44,372 [salt.state       ][INFO    ][2200] {'net.core.netdev_max_backlog': 261144}
2018-01-26 18:07:44,372 [salt.state       ][INFO    ][2200] Completed state [net.core.netdev_max_backlog] at time 18:07:44.372764 duration_in_ms=41.88
2018-01-26 18:07:44,373 [salt.state       ][INFO    ][2200] Running state [net.ipv4.tcp_keepalive_time] at time 18:07:44.373095
2018-01-26 18:07:44,373 [salt.state       ][INFO    ][2200] Executing state sysctl.present for net.ipv4.tcp_keepalive_time
2018-01-26 18:07:44,374 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:44,402 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w net.ipv4.tcp_keepalive_time="30"' in directory '/root'
2018-01-26 18:07:44,415 [salt.state       ][INFO    ][2200] {'net.ipv4.tcp_keepalive_time': 30}
2018-01-26 18:07:44,415 [salt.state       ][INFO    ][2200] Completed state [net.ipv4.tcp_keepalive_time] at time 18:07:44.415793 duration_in_ms=42.697
2018-01-26 18:07:44,416 [salt.state       ][INFO    ][2200] Running state [net.nf_conntrack_max] at time 18:07:44.416119
2018-01-26 18:07:44,416 [salt.state       ][INFO    ][2200] Executing state sysctl.present for net.nf_conntrack_max
2018-01-26 18:07:44,417 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -a' in directory '/root'
2018-01-26 18:07:44,447 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'sysctl -w net.nf_conntrack_max="1048576"' in directory '/root'
2018-01-26 18:07:44,460 [salt.state       ][INFO    ][2200] {'net.nf_conntrack_max': 1048576}
2018-01-26 18:07:44,461 [salt.state       ][INFO    ][2200] Completed state [net.nf_conntrack_max] at time 18:07:44.461049 duration_in_ms=44.929
2018-01-26 18:07:44,472 [salt.state       ][INFO    ][2200] Running state [linux_sysfs_package] at time 18:07:44.472075
2018-01-26 18:07:44,472 [salt.state       ][INFO    ][2200] Executing state pkg.installed for linux_sysfs_package
2018-01-26 18:07:44,858 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['apt-cache', '-q', 'policy', 'sysfsutils'] in directory '/root'
2018-01-26 18:07:44,913 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-01-26 18:07:46,756 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-01-26 18:07:46,779 [salt.loaded.int.module.cmdmod][INFO    ][2200] 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-26 18:07:49,764 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2018-01-26 18:07:49,801 [salt.state       ][INFO    ][2200] 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-26 18:07:49,815 [salt.state       ][INFO    ][2200] Loading fresh modules for state activity
2018-01-26 18:07:49,850 [salt.state       ][INFO    ][2200] Completed state [linux_sysfs_package] at time 18:07:49.850852 duration_in_ms=5378.776
2018-01-26 18:07:49,855 [salt.state       ][INFO    ][2200] Running state [/etc/sysfs.d] at time 18:07:49.854981
2018-01-26 18:07:49,855 [salt.state       ][INFO    ][2200] Executing state file.directory for /etc/sysfs.d
2018-01-26 18:07:49,858 [salt.state       ][INFO    ][2200] Directory /etc/sysfs.d is in the correct state
2018-01-26 18:07:49,859 [salt.state       ][INFO    ][2200] Completed state [/etc/sysfs.d] at time 18:07:49.859274 duration_in_ms=4.293
2018-01-26 18:07:49,906 [salt.state       ][INFO    ][2200] Running state [ondemand] at time 18:07:49.906429
2018-01-26 18:07:49,907 [salt.state       ][INFO    ][2200] Executing state service.dead for ondemand
2018-01-26 18:07:49,909 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['systemctl', 'status', 'ondemand.service', '-n', '0'] in directory '/root'
2018-01-26 18:07:49,927 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['systemctl', 'is-active', 'ondemand.service'] in directory '/root'
2018-01-26 18:07:49,945 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-01-26 18:07:49,963 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['systemd-run', '--scope', 'systemctl', 'stop', 'ondemand.service'] in directory '/root'
2018-01-26 18:07:50,027 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['systemctl', 'is-active', 'ondemand.service'] in directory '/root'
2018-01-26 18:07:50,043 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-01-26 18:07:50,062 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-01-26 18:07:50,083 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['systemd-run', '--scope', '/usr/sbin/update-rc.d', '-f', 'ondemand', 'remove'] in directory '/root'
2018-01-26 18:07:50,227 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['systemctl', 'is-enabled', 'ondemand.service'] in directory '/root'
2018-01-26 18:07:50,248 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'runlevel' in directory '/root'
2018-01-26 18:07:50,260 [salt.state       ][INFO    ][2200] {'ondemand': True}
2018-01-26 18:07:50,261 [salt.state       ][INFO    ][2200] Completed state [ondemand] at time 18:07:50.261212 duration_in_ms=354.782
2018-01-26 18:07:50,267 [salt.state       ][INFO    ][2200] Running state [cs_CZ.UTF-8] at time 18:07:50.267042
2018-01-26 18:07:50,267 [salt.state       ][INFO    ][2200] Executing state locale.present for cs_CZ.UTF-8
2018-01-26 18:07:50,267 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'locale -a' in directory '/root'
2018-01-26 18:07:50,287 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['locale-gen', 'cs_CZ.utf8'] in directory '/root'
2018-01-26 18:07:50,991 [salt.state       ][INFO    ][2200] {'locale': 'cs_CZ.UTF-8'}
2018-01-26 18:07:50,992 [salt.state       ][INFO    ][2200] Completed state [cs_CZ.UTF-8] at time 18:07:50.992265 duration_in_ms=725.222
2018-01-26 18:07:50,993 [salt.state       ][INFO    ][2200] Running state [en_US.UTF-8] at time 18:07:50.992992
2018-01-26 18:07:50,993 [salt.state       ][INFO    ][2200] Executing state locale.present for en_US.UTF-8
2018-01-26 18:07:50,996 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'locale -a' in directory '/root'
2018-01-26 18:07:51,010 [salt.state       ][INFO    ][2200] Locale en_US.UTF-8 is already present
2018-01-26 18:07:51,011 [salt.state       ][INFO    ][2200] Completed state [en_US.UTF-8] at time 18:07:51.011121 duration_in_ms=18.13
2018-01-26 18:07:51,012 [salt.state       ][INFO    ][2200] Running state [en_US.UTF-8] at time 18:07:51.012580
2018-01-26 18:07:51,012 [salt.state       ][INFO    ][2200] Executing state locale.system for en_US.UTF-8
2018-01-26 18:07:51,013 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command 'localectl' in directory '/root'
2018-01-26 18:07:51,063 [salt.state       ][INFO    ][2200] System locale en_US.UTF-8 already set
2018-01-26 18:07:51,063 [salt.state       ][INFO    ][2200] Completed state [en_US.UTF-8] at time 18:07:51.063552 duration_in_ms=50.973
2018-01-26 18:07:51,065 [salt.state       ][INFO    ][2200] Running state [root] at time 18:07:51.065608
2018-01-26 18:07:51,067 [salt.state       ][INFO    ][2200] Executing state user.present for root
2018-01-26 18:07:51,072 [salt.state       ][INFO    ][2200] User root is present and up to date
2018-01-26 18:07:51,072 [salt.state       ][INFO    ][2200] Completed state [root] at time 18:07:51.072863 duration_in_ms=7.255
2018-01-26 18:07:51,073 [salt.state       ][INFO    ][2200] Running state [/root] at time 18:07:51.073768
2018-01-26 18:07:51,074 [salt.state       ][INFO    ][2200] Executing state file.directory for /root
2018-01-26 18:07:51,074 [salt.state       ][INFO    ][2200] Directory /root is in the correct state
2018-01-26 18:07:51,074 [salt.state       ][INFO    ][2200] Completed state [/root] at time 18:07:51.074924 duration_in_ms=1.156
2018-01-26 18:07:51,075 [salt.state       ][INFO    ][2200] Running state [/etc/sudoers.d/90-salt-user-root] at time 18:07:51.075147
2018-01-26 18:07:51,075 [salt.state       ][INFO    ][2200] Executing state file.absent for /etc/sudoers.d/90-salt-user-root
2018-01-26 18:07:51,075 [salt.state       ][INFO    ][2200] File /etc/sudoers.d/90-salt-user-root is not present
2018-01-26 18:07:51,075 [salt.state       ][INFO    ][2200] Completed state [/etc/sudoers.d/90-salt-user-root] at time 18:07:51.075845 duration_in_ms=0.698
2018-01-26 18:07:51,076 [salt.state       ][INFO    ][2200] Running state [/etc/security/limits.d/90-salt-default.conf] at time 18:07:51.076068
2018-01-26 18:07:51,076 [salt.state       ][INFO    ][2200] Executing state file.managed for /etc/security/limits.d/90-salt-default.conf
2018-01-26 18:07:51,111 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/files/limits.conf'
2018-01-26 18:07:51,205 [salt.state       ][INFO    ][2200] File changed:
New file
2018-01-26 18:07:51,205 [salt.state       ][INFO    ][2200] Completed state [/etc/security/limits.d/90-salt-default.conf] at time 18:07:51.205377 duration_in_ms=129.309
2018-01-26 18:07:51,205 [salt.state       ][INFO    ][2200] Running state [/etc/systemd/system.conf.d/90-salt.conf] at time 18:07:51.205617
2018-01-26 18:07:51,205 [salt.state       ][INFO    ][2200] Executing state file.managed for /etc/systemd/system.conf.d/90-salt.conf
2018-01-26 18:07:51,225 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/files/systemd.conf'
2018-01-26 18:07:51,309 [salt.state       ][INFO    ][2200] File changed:
New file
2018-01-26 18:07:51,309 [salt.state       ][INFO    ][2200] Completed state [/etc/systemd/system.conf.d/90-salt.conf] at time 18:07:51.309686 duration_in_ms=104.068
2018-01-26 18:07:51,311 [salt.state       ][INFO    ][2200] Running state [service.systemctl_reload] at time 18:07:51.311155
2018-01-26 18:07:51,311 [salt.state       ][INFO    ][2200] Executing state module.wait for service.systemctl_reload
2018-01-26 18:07:51,311 [salt.state       ][INFO    ][2200] No changes made for service.systemctl_reload
2018-01-26 18:07:51,311 [salt.state       ][INFO    ][2200] Completed state [service.systemctl_reload] at time 18:07:51.311832 duration_in_ms=0.677
2018-01-26 18:07:51,312 [salt.state       ][INFO    ][2200] Running state [service.systemctl_reload] at time 18:07:51.312019
2018-01-26 18:07:51,312 [salt.state       ][INFO    ][2200] Executing state module.mod_watch for service.systemctl_reload
2018-01-26 18:07:51,312 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2018-01-26 18:07:51,381 [salt.state       ][INFO    ][2200] {'ret': True}
2018-01-26 18:07:51,381 [salt.state       ][INFO    ][2200] Completed state [service.systemctl_reload] at time 18:07:51.381587 duration_in_ms=69.568
2018-01-26 18:07:51,382 [salt.state       ][INFO    ][2200] Running state [/etc/hostname] at time 18:07:51.382274
2018-01-26 18:07:51,382 [salt.state       ][INFO    ][2200] Executing state file.managed for /etc/hostname
2018-01-26 18:07:51,400 [salt.fileclient  ][INFO    ][2200] Fetching file from saltenv 'base', ** done ** 'linux/files/hostname'
2018-01-26 18:07:51,403 [salt.state       ][INFO    ][2200] File /etc/hostname is in the correct state
2018-01-26 18:07:51,404 [salt.state       ][INFO    ][2200] Completed state [/etc/hostname] at time 18:07:51.404078 duration_in_ms=21.804
2018-01-26 18:07:51,405 [salt.state       ][INFO    ][2200] Running state [hostname odl01] at time 18:07:51.405919
2018-01-26 18:07:51,406 [salt.state       ][INFO    ][2200] Executing state cmd.wait for hostname odl01
2018-01-26 18:07:51,406 [salt.state       ][INFO    ][2200] No changes made for hostname odl01
2018-01-26 18:07:51,406 [salt.state       ][INFO    ][2200] Completed state [hostname odl01] at time 18:07:51.406628 duration_in_ms=0.709
2018-01-26 18:07:51,407 [salt.state       ][INFO    ][2200] Running state [cfg] at time 18:07:51.407721
2018-01-26 18:07:51,407 [salt.state       ][INFO    ][2200] Executing state host.present for cfg
2018-01-26 18:07:51,410 [salt.state       ][INFO    ][2200] {'host': 'cfg'}
2018-01-26 18:07:51,410 [salt.state       ][INFO    ][2200] Completed state [cfg] at time 18:07:51.410308 duration_in_ms=2.587
2018-01-26 18:07:51,410 [salt.state       ][INFO    ][2200] Running state [cfg.virtual-mcp-ocata-odl-router-noha.local] at time 18:07:51.410569
2018-01-26 18:07:51,410 [salt.state       ][INFO    ][2200] Executing state host.present for cfg.virtual-mcp-ocata-odl-router-noha.local
2018-01-26 18:07:51,411 [salt.state       ][INFO    ][2200] {'host': 'cfg.virtual-mcp-ocata-odl-router-noha.local'}
2018-01-26 18:07:51,411 [salt.state       ][INFO    ][2200] Completed state [cfg.virtual-mcp-ocata-odl-router-noha.local] at time 18:07:51.411733 duration_in_ms=1.164
2018-01-26 18:07:51,411 [salt.state       ][INFO    ][2200] Running state [odl01] at time 18:07:51.411956
2018-01-26 18:07:51,412 [salt.state       ][INFO    ][2200] Executing state host.present for odl01
2018-01-26 18:07:51,412 [salt.state       ][INFO    ][2200] {'host': 'odl01'}
2018-01-26 18:07:51,413 [salt.state       ][INFO    ][2200] Completed state [odl01] at time 18:07:51.413110 duration_in_ms=1.154
2018-01-26 18:07:51,413 [salt.state       ][INFO    ][2200] Running state [odl01.virtual-mcp-ocata-odl-router-noha.local] at time 18:07:51.413336
2018-01-26 18:07:51,413 [salt.state       ][INFO    ][2200] Executing state host.present for odl01.virtual-mcp-ocata-odl-router-noha.local
2018-01-26 18:07:51,415 [salt.state       ][INFO    ][2200] {'host': 'odl01.virtual-mcp-ocata-odl-router-noha.local'}
2018-01-26 18:07:51,415 [salt.state       ][INFO    ][2200] Completed state [odl01.virtual-mcp-ocata-odl-router-noha.local] at time 18:07:51.415657 duration_in_ms=2.32
2018-01-26 18:07:51,415 [salt.state       ][INFO    ][2200] Running state [gtw01] at time 18:07:51.415881
2018-01-26 18:07:51,416 [salt.state       ][INFO    ][2200] Executing state host.present for gtw01
2018-01-26 18:07:51,416 [salt.state       ][INFO    ][2200] {'host': 'gtw01'}
2018-01-26 18:07:51,417 [salt.state       ][INFO    ][2200] Completed state [gtw01] at time 18:07:51.417040 duration_in_ms=1.159
2018-01-26 18:07:51,417 [salt.state       ][INFO    ][2200] Running state [gtw01.virtual-mcp-ocata-odl-router-noha.local] at time 18:07:51.417266
2018-01-26 18:07:51,417 [salt.state       ][INFO    ][2200] Executing state host.present for gtw01.virtual-mcp-ocata-odl-router-noha.local
2018-01-26 18:07:51,418 [salt.state       ][INFO    ][2200] {'host': 'gtw01.virtual-mcp-ocata-odl-router-noha.local'}
2018-01-26 18:07:51,418 [salt.state       ][INFO    ][2200] Completed state [gtw01.virtual-mcp-ocata-odl-router-noha.local] at time 18:07:51.418455 duration_in_ms=1.189
2018-01-26 18:07:51,418 [salt.state       ][INFO    ][2200] Running state [ctl] at time 18:07:51.418705
2018-01-26 18:07:51,418 [salt.state       ][INFO    ][2200] Executing state host.present for ctl
2018-01-26 18:07:51,419 [salt.state       ][INFO    ][2200] {'host': 'ctl'}
2018-01-26 18:07:51,419 [salt.state       ][INFO    ][2200] Completed state [ctl] at time 18:07:51.419883 duration_in_ms=1.178
2018-01-26 18:07:51,420 [salt.state       ][INFO    ][2200] Running state [ctl.virtual-mcp-ocata-odl-router-noha.local] at time 18:07:51.420103
2018-01-26 18:07:51,420 [salt.state       ][INFO    ][2200] Executing state host.present for ctl.virtual-mcp-ocata-odl-router-noha.local
2018-01-26 18:07:51,421 [salt.state       ][INFO    ][2200] {'host': 'ctl.virtual-mcp-ocata-odl-router-noha.local'}
2018-01-26 18:07:51,421 [salt.state       ][INFO    ][2200] Completed state [ctl.virtual-mcp-ocata-odl-router-noha.local] at time 18:07:51.421272 duration_in_ms=1.169
2018-01-26 18:07:51,421 [salt.state       ][INFO    ][2200] Running state [ctl01] at time 18:07:51.421494
2018-01-26 18:07:51,421 [salt.state       ][INFO    ][2200] Executing state host.present for ctl01
2018-01-26 18:07:51,422 [salt.state       ][INFO    ][2200] {'host': 'ctl01'}
2018-01-26 18:07:51,422 [salt.state       ][INFO    ][2200] Completed state [ctl01] at time 18:07:51.422694 duration_in_ms=1.2
2018-01-26 18:07:51,422 [salt.state       ][INFO    ][2200] Running state [ctl01.virtual-mcp-ocata-odl-router-noha.local] at time 18:07:51.422909
2018-01-26 18:07:51,423 [salt.state       ][INFO    ][2200] Executing state host.present for ctl01.virtual-mcp-ocata-odl-router-noha.local
2018-01-26 18:07:51,423 [salt.state       ][INFO    ][2200] {'host': 'ctl01.virtual-mcp-ocata-odl-router-noha.local'}
2018-01-26 18:07:51,424 [salt.state       ][INFO    ][2200] Completed state [ctl01.virtual-mcp-ocata-odl-router-noha.local] at time 18:07:51.424096 duration_in_ms=1.187
2018-01-26 18:07:51,424 [salt.state       ][INFO    ][2200] Running state [cmp01] at time 18:07:51.424314
2018-01-26 18:07:51,424 [salt.state       ][INFO    ][2200] Executing state host.present for cmp01
2018-01-26 18:07:51,425 [salt.state       ][INFO    ][2200] {'host': 'cmp01'}
2018-01-26 18:07:51,425 [salt.state       ][INFO    ][2200] Completed state [cmp01] at time 18:07:51.425480 duration_in_ms=1.166
2018-01-26 18:07:51,425 [salt.state       ][INFO    ][2200] Running state [cmp01.virtual-mcp-ocata-odl-router-noha.local] at time 18:07:51.425703
2018-01-26 18:07:51,427 [salt.state       ][INFO    ][2200] Executing state host.present for cmp01.virtual-mcp-ocata-odl-router-noha.local
2018-01-26 18:07:51,427 [salt.state       ][INFO    ][2200] {'host': 'cmp01.virtual-mcp-ocata-odl-router-noha.local'}
2018-01-26 18:07:51,428 [salt.state       ][INFO    ][2200] Completed state [cmp01.virtual-mcp-ocata-odl-router-noha.local] at time 18:07:51.428064 duration_in_ms=2.361
2018-01-26 18:07:51,428 [salt.state       ][INFO    ][2200] Running state [cmp02] at time 18:07:51.428284
2018-01-26 18:07:51,428 [salt.state       ][INFO    ][2200] Executing state host.present for cmp02
2018-01-26 18:07:51,429 [salt.state       ][INFO    ][2200] {'host': 'cmp02'}
2018-01-26 18:07:51,429 [salt.state       ][INFO    ][2200] Completed state [cmp02] at time 18:07:51.429481 duration_in_ms=1.198
2018-01-26 18:07:51,429 [salt.state       ][INFO    ][2200] Running state [cmp02.virtual-mcp-ocata-odl-router-noha.local] at time 18:07:51.429699
2018-01-26 18:07:51,429 [salt.state       ][INFO    ][2200] Executing state host.present for cmp02.virtual-mcp-ocata-odl-router-noha.local
2018-01-26 18:07:51,430 [salt.state       ][INFO    ][2200] {'host': 'cmp02.virtual-mcp-ocata-odl-router-noha.local'}
2018-01-26 18:07:51,430 [salt.state       ][INFO    ][2200] Completed state [cmp02.virtual-mcp-ocata-odl-router-noha.local] at time 18:07:51.430954 duration_in_ms=1.255
2018-01-26 18:07:51,431 [salt.state       ][INFO    ][2200] Running state [cfg01] at time 18:07:51.431177
2018-01-26 18:07:51,431 [salt.state       ][INFO    ][2200] Executing state host.present for cfg01
2018-01-26 18:07:51,432 [salt.state       ][INFO    ][2200] {'host': 'cfg01'}
2018-01-26 18:07:51,432 [salt.state       ][INFO    ][2200] Completed state [cfg01] at time 18:07:51.432383 duration_in_ms=1.206
2018-01-26 18:07:51,432 [salt.state       ][INFO    ][2200] Running state [cfg01.virtual-mcp-ocata-odl-router-noha.local] at time 18:07:51.432601
2018-01-26 18:07:51,432 [salt.state       ][INFO    ][2200] Executing state host.present for cfg01.virtual-mcp-ocata-odl-router-noha.local
2018-01-26 18:07:51,434 [salt.state       ][INFO    ][2200] {'host': 'cfg01.virtual-mcp-ocata-odl-router-noha.local'}
2018-01-26 18:07:51,434 [salt.state       ][INFO    ][2200] Completed state [cfg01.virtual-mcp-ocata-odl-router-noha.local] at time 18:07:51.434327 duration_in_ms=1.726
2018-01-26 18:07:51,439 [salt.state       ][INFO    ][2200] Running state [ens4] at time 18:07:51.439899
2018-01-26 18:07:51,440 [salt.state       ][INFO    ][2200] Executing state network.managed for ens4
2018-01-26 18:07:51,587 [salt.loaded.int.module.cmdmod][INFO    ][2200] Executing command ['ifup', 'ens4'] in directory '/root'
2018-01-26 18:07:52,217 [salt.state       ][INFO    ][2200] {'interface': 'Added network interface.', 'status': 'Interface ens4 is up'}
2018-01-26 18:07:52,218 [salt.state       ][INFO    ][2200] Completed state [ens4] at time 18:07:52.218722 duration_in_ms=778.823
2018-01-26 18:07:52,219 [salt.state       ][INFO    ][2200] Running state [/etc/profile.d/proxy.sh] at time 18:07:52.219033
2018-01-26 18:07:52,219 [salt.state       ][INFO    ][2200] Executing state file.absent for /etc/profile.d/proxy.sh
2018-01-26 18:07:52,219 [salt.state       ][INFO    ][2200] File /etc/profile.d/proxy.sh is not present
2018-01-26 18:07:52,219 [salt.state       ][INFO    ][2200] Completed state [/etc/profile.d/proxy.sh] at time 18:07:52.219886 duration_in_ms=0.853
2018-01-26 18:07:52,220 [salt.state       ][INFO    ][2200] Running state [/etc/apt/apt.conf.d/95proxies] at time 18:07:52.220111
2018-01-26 18:07:52,220 [salt.state       ][INFO    ][2200] Executing state file.absent for /etc/apt/apt.conf.d/95proxies
2018-01-26 18:07:52,220 [salt.state       ][INFO    ][2200] File /etc/apt/apt.conf.d/95proxies is not present
2018-01-26 18:07:52,220 [salt.state       ][INFO    ][2200] Completed state [/etc/apt/apt.conf.d/95proxies] at time 18:07:52.220806 duration_in_ms=0.695
2018-01-26 18:07:52,223 [salt.minion      ][INFO    ][2200] Returning information for job: 20180126180712668307
2018-01-26 18:08:11,295 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command pkg.upgrade with jid 20180126180811278414
2018-01-26 18:08:11,323 [salt.minion      ][INFO    ][6449] Starting a new job with PID 6449
2018-01-26 18:08:11,349 [salt.loader.10.20.0.2.int.module.cmdmod][INFO    ][6449] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2018-01-26 18:08:11,708 [salt.loader.10.20.0.2.int.module.cmdmod][INFO    ][6449] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'upgrade'] in directory '/root'
2018-01-26 18:08:17,321 [salt.loader.10.20.0.2.int.module.cmdmod][INFO    ][6449] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2018-01-26 18:08:17,352 [salt.minion      ][INFO    ][6449] Returning information for job: 20180126180811278414
2018-01-26 18:08:18,580 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command state.sls with jid 20180126180818563055
2018-01-26 18:08:18,605 [salt.minion      ][INFO    ][7243] Starting a new job with PID 7243
2018-01-26 18:08:20,511 [salt.state       ][INFO    ][7243] Loading fresh modules for state activity
2018-01-26 18:08:20,561 [salt.fileclient  ][INFO    ][7243] Fetching file from saltenv 'base', ** done ** 'ntp/init.sls'
2018-01-26 18:08:20,592 [salt.fileclient  ][INFO    ][7243] Fetching file from saltenv 'base', ** done ** 'ntp/client.sls'
2018-01-26 18:08:20,641 [salt.fileclient  ][INFO    ][7243] Fetching file from saltenv 'base', ** done ** 'ntp/server.sls'
2018-01-26 18:08:21,181 [salt.state       ][INFO    ][7243] Running state [ntp] at time 18:08:21.181912
2018-01-26 18:08:21,182 [salt.state       ][INFO    ][7243] Executing state pkg.installed for ntp
2018-01-26 18:08:21,183 [salt.loaded.int.module.cmdmod][INFO    ][7243] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2018-01-26 18:08:21,522 [salt.loaded.int.module.cmdmod][INFO    ][7243] Executing command ['apt-cache', '-q', 'policy', 'ntp'] in directory '/root'
2018-01-26 18:08:21,596 [salt.loaded.int.module.cmdmod][INFO    ][7243] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-01-26 18:08:23,454 [salt.loaded.int.module.cmdmod][INFO    ][7243] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-01-26 18:08:23,482 [salt.loaded.int.module.cmdmod][INFO    ][7243] 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-26 18:08:26,988 [salt.loaded.int.module.cmdmod][INFO    ][7243] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2018-01-26 18:08:27,026 [salt.state       ][INFO    ][7243] 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-26 18:08:27,042 [salt.state       ][INFO    ][7243] Loading fresh modules for state activity
2018-01-26 18:08:27,076 [salt.state       ][INFO    ][7243] Completed state [ntp] at time 18:08:27.076807 duration_in_ms=5894.895
2018-01-26 18:08:27,080 [salt.state       ][INFO    ][7243] Running state [/etc/ntp.conf] at time 18:08:27.080513
2018-01-26 18:08:27,080 [salt.state       ][INFO    ][7243] Executing state file.managed for /etc/ntp.conf
2018-01-26 18:08:27,117 [salt.fileclient  ][INFO    ][7243] Fetching file from saltenv 'base', ** done ** 'ntp/files/ntp.conf'
2018-01-26 18:08:27,168 [salt.state       ][INFO    ][7243] 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.se.pool.ntp.org iburst
+server 0.se.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-26 18:08:27,168 [salt.state       ][INFO    ][7243] Completed state [/etc/ntp.conf] at time 18:08:27.168631 duration_in_ms=88.118
2018-01-26 18:08:27,208 [salt.state       ][INFO    ][7243] Running state [ntp] at time 18:08:27.208512
2018-01-26 18:08:27,208 [salt.state       ][INFO    ][7243] Executing state service.running for ntp
2018-01-26 18:08:27,210 [salt.loaded.int.module.cmdmod][INFO    ][7243] Executing command ['systemctl', 'status', 'ntp.service', '-n', '0'] in directory '/root'
2018-01-26 18:08:27,225 [salt.loaded.int.module.cmdmod][INFO    ][7243] Executing command ['systemctl', 'is-active', 'ntp.service'] in directory '/root'
2018-01-26 18:08:27,241 [salt.loaded.int.module.cmdmod][INFO    ][7243] Executing command ['systemctl', 'is-enabled', 'ntp.service'] in directory '/root'
2018-01-26 18:08:27,257 [salt.state       ][INFO    ][7243] The service ntp is already running
2018-01-26 18:08:27,258 [salt.state       ][INFO    ][7243] Completed state [ntp] at time 18:08:27.258036 duration_in_ms=49.525
2018-01-26 18:08:27,258 [salt.state       ][INFO    ][7243] Running state [ntp] at time 18:08:27.258300
2018-01-26 18:08:27,258 [salt.state       ][INFO    ][7243] Executing state service.mod_watch for ntp
2018-01-26 18:08:27,260 [salt.loaded.int.module.cmdmod][INFO    ][7243] Executing command ['systemctl', 'is-active', 'ntp.service'] in directory '/root'
2018-01-26 18:08:27,275 [salt.loaded.int.module.cmdmod][INFO    ][7243] Executing command ['systemctl', 'is-enabled', 'ntp.service'] in directory '/root'
2018-01-26 18:08:27,293 [salt.loaded.int.module.cmdmod][INFO    ][7243] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'ntp.service'] in directory '/root'
2018-01-26 18:08:27,384 [salt.state       ][INFO    ][7243] {'ntp': True}
2018-01-26 18:08:27,384 [salt.state       ][INFO    ][7243] Completed state [ntp] at time 18:08:27.384620 duration_in_ms=126.32
2018-01-26 18:08:27,385 [salt.minion      ][INFO    ][7243] Returning information for job: 20180126180818563055
2018-01-26 18:08:30,852 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command state.sls with jid 20180126180830839859
2018-01-26 18:08:30,877 [salt.minion      ][INFO    ][8468] Starting a new job with PID 8468
2018-01-26 18:08:31,224 [salt.state       ][INFO    ][8468] Loading fresh modules for state activity
2018-01-26 18:08:31,269 [salt.fileclient  ][INFO    ][8468] Fetching file from saltenv 'base', ** done ** 'opendaylight/init.sls'
2018-01-26 18:08:31,293 [salt.fileclient  ][INFO    ][8468] Fetching file from saltenv 'base', ** done ** 'opendaylight/server.sls'
2018-01-26 18:08:31,326 [salt.fileclient  ][INFO    ][8468] Fetching file from saltenv 'base', ** done ** 'opendaylight/map.jinja'
2018-01-26 18:08:31,428 [salt.state       ][INFO    ][8468] Running state [apt-key adv --keyserver keyserver.ubuntu.com --recv 44C05248] at time 18:08:31.428397
2018-01-26 18:08:31,428 [salt.state       ][INFO    ][8468] Executing state cmd.run for apt-key adv --keyserver keyserver.ubuntu.com --recv 44C05248
2018-01-26 18:08:31,430 [salt.loaded.int.module.cmdmod][INFO    ][8468] Executing command 'apt-key adv --keyserver keyserver.ubuntu.com --recv 44C05248' in directory '/root'
2018-01-26 18:08:31,734 [salt.state       ][INFO    ][8468] {'pid': 8475, 'retcode': 0, 'stderr': 'gpg: requesting key 44C05248 from hkp server keyserver.ubuntu.com\ngpg: key 44C05248: public key "Launchpad PPA for ODL Team" imported\ngpg: Total number processed: 1\ngpg:               imported: 1  (RSA: 1)', 'stdout': 'Executing: /tmp/tmp.gqB6gRRhBA/gpg.1.sh --keyserver\nkeyserver.ubuntu.com\n--recv\n44C05248'}
2018-01-26 18:08:31,734 [salt.state       ][INFO    ][8468] Completed state [apt-key adv --keyserver keyserver.ubuntu.com --recv 44C05248] at time 18:08:31.734467 duration_in_ms=306.07
2018-01-26 18:08:31,756 [salt.state       ][INFO    ][8468] Running state [deb http://ppa.launchpad.net/odl-team/nitrogen/ubuntu xenial main] at time 18:08:31.756805
2018-01-26 18:08:31,757 [salt.state       ][INFO    ][8468] Executing state pkgrepo.managed for deb http://ppa.launchpad.net/odl-team/nitrogen/ubuntu xenial main
2018-01-26 18:08:31,856 [salt.loaded.int.module.cmdmod][INFO    ][8468] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-01-26 18:08:35,804 [salt.state       ][INFO    ][8468] {'repo': 'deb http://ppa.launchpad.net/odl-team/nitrogen/ubuntu xenial main'}
2018-01-26 18:08:35,804 [salt.state       ][INFO    ][8468] Completed state [deb http://ppa.launchpad.net/odl-team/nitrogen/ubuntu xenial main] at time 18:08:35.804737 duration_in_ms=4047.932
2018-01-26 18:08:36,220 [salt.state       ][INFO    ][8468] Running state [opendaylight] at time 18:08:36.220893
2018-01-26 18:08:36,221 [salt.state       ][INFO    ][8468] Executing state pkg.installed for opendaylight
2018-01-26 18:08:36,221 [salt.loaded.int.module.cmdmod][INFO    ][8468] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2018-01-26 18:08:36,563 [salt.loaded.int.module.cmdmod][INFO    ][8468] Executing command ['apt-cache', '-q', 'policy', 'opendaylight'] in directory '/root'
2018-01-26 18:08:36,647 [salt.loaded.int.module.cmdmod][INFO    ][8468] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-01-26 18:08:38,559 [salt.loaded.int.module.cmdmod][INFO    ][8468] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-01-26 18:08:38,592 [salt.loaded.int.module.cmdmod][INFO    ][8468] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'opendaylight'] in directory '/root'
2018-01-26 18:08:40,945 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126180840929103
2018-01-26 18:08:40,968 [salt.minion      ][INFO    ][9487] Starting a new job with PID 9487
2018-01-26 18:08:40,984 [salt.minion      ][INFO    ][9487] Returning information for job: 20180126180840929103
2018-01-26 18:08:51,160 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126180851145270
2018-01-26 18:08:51,187 [salt.minion      ][INFO    ][9492] Starting a new job with PID 9492
2018-01-26 18:08:51,203 [salt.minion      ][INFO    ][9492] Returning information for job: 20180126180851145270
2018-01-26 18:09:01,372 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126180901367586
2018-01-26 18:09:01,393 [salt.minion      ][INFO    ][9497] Starting a new job with PID 9497
2018-01-26 18:09:01,408 [salt.minion      ][INFO    ][9497] Returning information for job: 20180126180901367586
2018-01-26 18:09:11,584 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126180911573935
2018-01-26 18:09:11,612 [salt.minion      ][INFO    ][9502] Starting a new job with PID 9502
2018-01-26 18:09:11,625 [salt.minion      ][INFO    ][9502] Returning information for job: 20180126180911573935
2018-01-26 18:09:21,799 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126180921790921
2018-01-26 18:09:21,819 [salt.minion      ][INFO    ][9507] Starting a new job with PID 9507
2018-01-26 18:09:21,834 [salt.minion      ][INFO    ][9507] Returning information for job: 20180126180921790921
2018-01-26 18:09:32,011 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126180932005486
2018-01-26 18:09:32,034 [salt.minion      ][INFO    ][9512] Starting a new job with PID 9512
2018-01-26 18:09:32,049 [salt.minion      ][INFO    ][9512] Returning information for job: 20180126180932005486
2018-01-26 18:09:42,219 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126180942217056
2018-01-26 18:09:42,242 [salt.minion      ][INFO    ][9517] Starting a new job with PID 9517
2018-01-26 18:09:42,258 [salt.minion      ][INFO    ][9517] Returning information for job: 20180126180942217056
2018-01-26 18:09:52,250 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126180952243203
2018-01-26 18:09:52,273 [salt.minion      ][INFO    ][9522] Starting a new job with PID 9522
2018-01-26 18:09:52,290 [salt.minion      ][INFO    ][9522] Returning information for job: 20180126180952243203
2018-01-26 18:10:02,463 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181002459193
2018-01-26 18:10:02,486 [salt.minion      ][INFO    ][9527] Starting a new job with PID 9527
2018-01-26 18:10:02,502 [salt.minion      ][INFO    ][9527] Returning information for job: 20180126181002459193
2018-01-26 18:10:12,682 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181012674713
2018-01-26 18:10:12,703 [salt.minion      ][INFO    ][9532] Starting a new job with PID 9532
2018-01-26 18:10:12,718 [salt.minion      ][INFO    ][9532] Returning information for job: 20180126181012674713
2018-01-26 18:10:22,910 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181022904897
2018-01-26 18:10:22,934 [salt.minion      ][INFO    ][9537] Starting a new job with PID 9537
2018-01-26 18:10:22,951 [salt.minion      ][INFO    ][9537] Returning information for job: 20180126181022904897
2018-01-26 18:10:33,135 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181033128950
2018-01-26 18:10:33,158 [salt.minion      ][INFO    ][9542] Starting a new job with PID 9542
2018-01-26 18:10:33,175 [salt.minion      ][INFO    ][9542] Returning information for job: 20180126181033128950
2018-01-26 18:10:43,160 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181043148985
2018-01-26 18:10:43,183 [salt.minion      ][INFO    ][9547] Starting a new job with PID 9547
2018-01-26 18:10:43,200 [salt.minion      ][INFO    ][9547] Returning information for job: 20180126181043148985
2018-01-26 18:10:53,189 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181053179906
2018-01-26 18:10:53,211 [salt.minion      ][INFO    ][9552] Starting a new job with PID 9552
2018-01-26 18:10:53,229 [salt.minion      ][INFO    ][9552] Returning information for job: 20180126181053179906
2018-01-26 18:11:03,208 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181103202470
2018-01-26 18:11:03,233 [salt.minion      ][INFO    ][9557] Starting a new job with PID 9557
2018-01-26 18:11:03,250 [salt.minion      ][INFO    ][9557] Returning information for job: 20180126181103202470
2018-01-26 18:11:13,228 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181113224099
2018-01-26 18:11:13,250 [salt.minion      ][INFO    ][9562] Starting a new job with PID 9562
2018-01-26 18:11:13,267 [salt.minion      ][INFO    ][9562] Returning information for job: 20180126181113224099
2018-01-26 18:11:23,251 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181123244462
2018-01-26 18:11:23,275 [salt.minion      ][INFO    ][9567] Starting a new job with PID 9567
2018-01-26 18:11:23,290 [salt.minion      ][INFO    ][9567] Returning information for job: 20180126181123244462
2018-01-26 18:11:33,291 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181133272685
2018-01-26 18:11:33,313 [salt.minion      ][INFO    ][9572] Starting a new job with PID 9572
2018-01-26 18:11:33,331 [salt.minion      ][INFO    ][9572] Returning information for job: 20180126181133272685
2018-01-26 18:11:43,325 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181143314108
2018-01-26 18:11:43,348 [salt.minion      ][INFO    ][9577] Starting a new job with PID 9577
2018-01-26 18:11:43,364 [salt.minion      ][INFO    ][9577] Returning information for job: 20180126181143314108
2018-01-26 18:11:53,359 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181153349338
2018-01-26 18:11:53,381 [salt.minion      ][INFO    ][9582] Starting a new job with PID 9582
2018-01-26 18:11:53,398 [salt.minion      ][INFO    ][9582] Returning information for job: 20180126181153349338
2018-01-26 18:12:03,390 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181203381029
2018-01-26 18:12:03,412 [salt.minion      ][INFO    ][9587] Starting a new job with PID 9587
2018-01-26 18:12:03,430 [salt.minion      ][INFO    ][9587] Returning information for job: 20180126181203381029
2018-01-26 18:12:13,423 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181213417121
2018-01-26 18:12:13,447 [salt.minion      ][INFO    ][9592] Starting a new job with PID 9592
2018-01-26 18:12:13,463 [salt.minion      ][INFO    ][9592] Returning information for job: 20180126181213417121
2018-01-26 18:12:23,460 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181223448808
2018-01-26 18:12:23,482 [salt.minion      ][INFO    ][9597] Starting a new job with PID 9597
2018-01-26 18:12:23,496 [salt.minion      ][INFO    ][9597] Returning information for job: 20180126181223448808
2018-01-26 18:12:33,486 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181233480502
2018-01-26 18:12:33,513 [salt.minion      ][INFO    ][9602] Starting a new job with PID 9602
2018-01-26 18:12:33,530 [salt.minion      ][INFO    ][9602] Returning information for job: 20180126181233480502
2018-01-26 18:12:43,528 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181243518494
2018-01-26 18:12:43,550 [salt.minion      ][INFO    ][9607] Starting a new job with PID 9607
2018-01-26 18:12:43,563 [salt.minion      ][INFO    ][9607] Returning information for job: 20180126181243518494
2018-01-26 18:12:53,565 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181253561515
2018-01-26 18:12:53,586 [salt.minion      ][INFO    ][9612] Starting a new job with PID 9612
2018-01-26 18:12:53,603 [salt.minion      ][INFO    ][9612] Returning information for job: 20180126181253561515
2018-01-26 18:13:03,629 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181303606698
2018-01-26 18:13:03,648 [salt.minion      ][INFO    ][9617] Starting a new job with PID 9617
2018-01-26 18:13:03,662 [salt.minion      ][INFO    ][9617] Returning information for job: 20180126181303606698
2018-01-26 18:13:13,679 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181313673097
2018-01-26 18:13:13,701 [salt.minion      ][INFO    ][9622] Starting a new job with PID 9622
2018-01-26 18:13:13,717 [salt.minion      ][INFO    ][9622] Returning information for job: 20180126181313673097
2018-01-26 18:13:23,739 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181323729386
2018-01-26 18:13:23,758 [salt.minion      ][INFO    ][9627] Starting a new job with PID 9627
2018-01-26 18:13:23,775 [salt.minion      ][INFO    ][9627] Returning information for job: 20180126181323729386
2018-01-26 18:13:33,787 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181333778186
2018-01-26 18:13:33,808 [salt.minion      ][INFO    ][9632] Starting a new job with PID 9632
2018-01-26 18:13:33,824 [salt.minion      ][INFO    ][9632] Returning information for job: 20180126181333778186
2018-01-26 18:13:43,856 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181343848948
2018-01-26 18:13:43,877 [salt.minion      ][INFO    ][9637] Starting a new job with PID 9637
2018-01-26 18:13:43,892 [salt.minion      ][INFO    ][9637] Returning information for job: 20180126181343848948
2018-01-26 18:13:53,929 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181353921937
2018-01-26 18:13:53,950 [salt.minion      ][INFO    ][9642] Starting a new job with PID 9642
2018-01-26 18:13:53,965 [salt.minion      ][INFO    ][9642] Returning information for job: 20180126181353921937
2018-01-26 18:14:04,009 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181404001176
2018-01-26 18:14:04,034 [salt.minion      ][INFO    ][9647] Starting a new job with PID 9647
2018-01-26 18:14:04,048 [salt.minion      ][INFO    ][9647] Returning information for job: 20180126181404001176
2018-01-26 18:14:14,070 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181414060661
2018-01-26 18:14:14,091 [salt.minion      ][INFO    ][9867] Starting a new job with PID 9867
2018-01-26 18:14:14,108 [salt.minion      ][INFO    ][9867] Returning information for job: 20180126181414060661
2018-01-26 18:14:24,124 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181424112139
2018-01-26 18:14:24,147 [salt.minion      ][INFO    ][9872] Starting a new job with PID 9872
2018-01-26 18:14:24,166 [salt.minion      ][INFO    ][9872] Returning information for job: 20180126181424112139
2018-01-26 18:14:34,182 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command saltutil.find_job with jid 20180126181434172819
2018-01-26 18:14:34,204 [salt.minion      ][INFO    ][10835] Starting a new job with PID 10835
2018-01-26 18:14:34,219 [salt.minion      ][INFO    ][10835] Returning information for job: 20180126181434172819
2018-01-26 18:14:38,712 [salt.loaded.int.module.cmdmod][INFO    ][8468] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2018-01-26 18:14:38,761 [salt.state       ][INFO    ][8468] Made the following changes:
'java-common' changed from 'absent' to '0.56ubuntu2'
'libxtst6' changed from 'absent' to '2:1.2.2-1'
'liblcms2-2' changed from 'absent' to '2.6-3ubuntu2'
'openjdk-8-jre-headless' changed from 'absent' to '8u151-b12-0ubuntu0.16.04.2'
'libxrender1' changed from 'absent' to '1:0.9.9-0ubuntu1'
'libavahi-common3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2'
'libnspr4' changed from 'absent' to '2:4.13.1-0ubuntu0.16.04.1'
'libfontconfig' changed from 'absent' to '1'
'fonts-dejavu-core' changed from 'absent' to '2.35-1'
'libnss3-nssdb' changed from 'absent' to '2:3.28.4-0ubuntu0.16.04.3'
'java8-runtime-headless' changed from 'absent' to '1'
'libcups2' changed from 'absent' to '2.1.3-4ubuntu0.3'
'libjpeg-turbo8' changed from 'absent' to '1.4.2-0ubuntu3'
'libavahi-client3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2'
'libnss3' changed from 'absent' to '2:3.28.4-0ubuntu0.16.04.3'
'libpcsclite1' changed from 'absent' to '1.8.14-1ubuntu1.16.04.1'
'java7-runtime-headless' changed from 'absent' to '1'
'ca-certificates-java' changed from 'absent' to '20160321ubuntu1'
'java2-runtime-headless' changed from 'absent' to '1'
'fontconfig-config' changed from 'absent' to '2.11.94-0ubuntu1.1'
'libavahi-common-data' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2'
'libxi6' changed from 'absent' to '2:1.7.6-1'
'java5-runtime-headless' changed from 'absent' to '1'
'java6-runtime-headless' changed from 'absent' to '1'
'x11-common' changed from 'absent' to '1:7.7+13ubuntu3'
'libjpeg8' changed from 'absent' to '8c-2ubuntu8'
'opendaylight' changed from 'absent' to '7.0.0-1ppa2'
'libfontconfig1' changed from 'absent' to '2.11.94-0ubuntu1.1'

2018-01-26 18:14:38,776 [salt.state       ][INFO    ][8468] Loading fresh modules for state activity
2018-01-26 18:14:38,815 [salt.state       ][INFO    ][8468] Completed state [opendaylight] at time 18:14:38.815479 duration_in_ms=362594.586
2018-01-26 18:14:38,866 [salt.state       ][INFO    ][8468] Running state [/opt/opendaylight/etc/jetty.xml] at time 18:14:38.866630
2018-01-26 18:14:38,866 [salt.state       ][INFO    ][8468] Executing state file.managed for /opt/opendaylight/etc/jetty.xml
2018-01-26 18:14:38,898 [salt.fileclient  ][INFO    ][8468] Fetching file from saltenv 'base', ** done ** 'opendaylight/files/jetty.xml'
2018-01-26 18:14:38,940 [salt.state       ][INFO    ][8468] File changed:
--- 
+++ 
@@ -79,7 +79,7 @@
                         </Item>
                     </Array>
                 </Arg>
-                <Set name="host">
+                <Set name="host">172.16.10.111
                     <Property name="jetty.host"/>
                 </Set>
                 <Set name="port">
@@ -110,11 +110,11 @@
                         </Item>
                     </Array>
                 </Arg>
-                <Set name="host">
+                <Set name="host">172.16.10.111
                     <Property name="jetty.host"/>
                 </Set>
                 <Set name="port">
-                    <Property name="jetty.port" default="8080"/>
+                    <Property name="jetty.port" default="8282"/>
                 </Set>
                 <Set name="idleTimeout">
                     <Property name="http.timeout" default="300000"/>

2018-01-26 18:14:38,942 [salt.state       ][INFO    ][8468] Completed state [/opt/opendaylight/etc/jetty.xml] at time 18:14:38.942876 duration_in_ms=76.247
2018-01-26 18:14:38,943 [salt.state       ][INFO    ][8468] Running state [/opt/opendaylight/bin/setenv] at time 18:14:38.943272
2018-01-26 18:14:38,943 [salt.state       ][INFO    ][8468] Executing state file.managed for /opt/opendaylight/bin/setenv
2018-01-26 18:14:38,964 [salt.fileclient  ][INFO    ][8468] Fetching file from saltenv 'base', ** done ** 'opendaylight/files/setenv.shell'
2018-01-26 18:14:39,009 [salt.state       ][INFO    ][8468] File changed:
--- 
+++ 
@@ -1,46 +1,15 @@
-#!/bin/sh
-#
-#    Licensed to the Apache Software Foundation (ASF) under one or more
-#    contributor license agreements.  See the NOTICE file distributed with
-#    this work for additional information regarding copyright ownership.
-#    The ASF licenses this file to You under the Apache License, Version 2.0
-#    (the "License"); you may not use this file except in compliance with
-#    the License.  You may obtain a copy of the License at
-#
-#       http://www.apache.org/licenses/LICENSE-2.0
-#
-#    Unless required by applicable law or agreed to in writing, software
-#    distributed under the License is distributed on an "AS IS" BASIS,
-#    WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
-#    See the License for the specific language governing permissions and
-#    limitations under the License.
-#
-
-#
-# handle specific scripts; the SCRIPT_NAME is exactly the name of the Karaf
-# script: client, instance, shell, start, status, stop, karaf
-#
-# if [ "$KARAF_SCRIPT" == "SCRIPT_NAME" ]; then
-#   Actions go here...
-# fi
-
-#
-# general settings which should be applied for all scripts go here; please keep
-# in mind that it is possible that scripts might be executed more than once, e.g.
-# in example of the start script where the start script is executed first and the
-# karaf script afterwards.
-#
-
-#
-# The following section shows the possible configuration options for the default 
-# karaf scripts
-#
-# export JAVA_HOME # Location of Java installation
-# export JAVA_MIN_MEM # Minimum memory for the JVM
-# export JAVA_MAX_MEM # Maximum memory for the JVM
+##############################################################################
+# Copyright (c) 2017 Mirantis Inc. and others.
+# All rights reserved. This program and the accompanying materials
+# are made available under the terms of the Apache License, Version 2.0
+# which accompanies this distribution, and is available at
+# http://www.apache.org/licenses/LICENSE-2.0
+############################################################################### export JAVA_HOME # Location of Java installation
+export JAVA_MIN_MEM=3g # Minimum memory for the JVM
+export JAVA_MAX_MEM=3g # Maximum memory for the JVM
 # export JAVA_PERM_MEM # Minimum perm memory for the JVM
-# export JAVA_MAX_PERM_MEM # Maximum perm memory for the JVM
-# export EXTRA_JAVA_OPTS # Additional JVM options
+export JAVA_MAX_PERM_MEM=512m # Maximum perm memory for the JVM
+export EXTRA_JAVA_OPTS='-Djava.net.preferIPv4Stack=true -XX:+UseG1GC' # Additional JVM options
 # export KARAF_HOME # Karaf home folder
 # export KARAF_DATA # Karaf data folder
 # export KARAF_BASE # Karaf base folder
@@ -48,7 +17,4 @@
 # export KARAF_OPTS # Additional available Karaf options
 # export KARAF_DEBUG # Enable debug mode
 # export KARAF_REDIRECT # Enable/set the std/err redirection when using bin/start
-# export KARAF_NOROOT # Prevent execution as root if set to true
-if [ "x$JAVA_MAX_MEM" = "x" ]; then
-    export JAVA_MAX_MEM="2048m"
-fi
+

2018-01-26 18:14:39,010 [salt.state       ][INFO    ][8468] Completed state [/opt/opendaylight/bin/setenv] at time 18:14:39.010372 duration_in_ms=67.099
2018-01-26 18:14:39,014 [salt.state       ][INFO    ][8468] Running state [/opt/opendaylight/etc/org.apache.karaf.features.cfg] at time 18:14:39.014863
2018-01-26 18:14:39,015 [salt.state       ][INFO    ][8468] Executing state ini.options_present for /opt/opendaylight/etc/org.apache.karaf.features.cfg
2018-01-26 18:14:39,036 [salt.state       ][INFO    ][8468] {'featuresBoot': {'after': 'standard,wrap,ssh,odl-restconf-all,odl-aaa-authn,odl-dlux-all,odl-mdsal-apidocs,odl-netvirt-openstack', 'before': '\\\n    standard, \\\n    wrap'}}
2018-01-26 18:14:39,036 [salt.state       ][INFO    ][8468] Completed state [/opt/opendaylight/etc/org.apache.karaf.features.cfg] at time 18:14:39.036228 duration_in_ms=21.365
2018-01-26 18:14:39,036 [salt.state       ][INFO    ][8468] Running state [/opt/opendaylight/etc/org.ops4j.pax.web.cfg] at time 18:14:39.036827
2018-01-26 18:14:39,037 [salt.state       ][INFO    ][8468] Executing state ini.options_present for /opt/opendaylight/etc/org.ops4j.pax.web.cfg
2018-01-26 18:14:39,038 [salt.state       ][INFO    ][8468] {'org.ops4j.pax.web.listening.addresses': {'after': '172.16.10.111', 'before': None}}
2018-01-26 18:14:39,038 [salt.state       ][INFO    ][8468] Completed state [/opt/opendaylight/etc/org.ops4j.pax.web.cfg] at time 18:14:39.038872 duration_in_ms=2.045
2018-01-26 18:14:39,039 [salt.state       ][INFO    ][8468] Running state [/opt/opendaylight/etc/custom.properties] at time 18:14:39.039374
2018-01-26 18:14:39,039 [salt.state       ][INFO    ][8468] Executing state ini.options_present for /opt/opendaylight/etc/custom.properties
2018-01-26 18:14:39,067 [salt.state       ][INFO    ][8468] {'ovsdb.l3.fwd.enabled': {'after': 'yes', 'before': None}, 'ovsdb.of.version': {'after': '1.3', 'before': None}}
2018-01-26 18:14:39,068 [salt.state       ][INFO    ][8468] Completed state [/opt/opendaylight/etc/custom.properties] at time 18:14:39.068249 duration_in_ms=28.875
2018-01-26 18:14:39,068 [salt.state       ][INFO    ][8468] Running state [opendaylight] at time 18:14:39.068953
2018-01-26 18:14:39,069 [salt.state       ][INFO    ][8468] Executing state service.running for opendaylight
2018-01-26 18:14:39,072 [salt.loaded.int.module.cmdmod][INFO    ][8468] Executing command ['systemctl', 'status', 'opendaylight.service', '-n', '0'] in directory '/root'
2018-01-26 18:14:39,095 [salt.loaded.int.module.cmdmod][INFO    ][8468] Executing command ['systemctl', 'is-active', 'opendaylight.service'] in directory '/root'
2018-01-26 18:14:39,108 [salt.loaded.int.module.cmdmod][INFO    ][8468] Executing command ['systemctl', 'is-enabled', 'opendaylight.service'] in directory '/root'
2018-01-26 18:14:39,127 [salt.state       ][INFO    ][8468] The service opendaylight is already running
2018-01-26 18:14:39,127 [salt.state       ][INFO    ][8468] Completed state [opendaylight] at time 18:14:39.127662 duration_in_ms=58.707
2018-01-26 18:14:39,128 [salt.state       ][INFO    ][8468] Running state [opendaylight] at time 18:14:39.128115
2018-01-26 18:14:39,128 [salt.state       ][INFO    ][8468] Executing state service.mod_watch for opendaylight
2018-01-26 18:14:39,129 [salt.loaded.int.module.cmdmod][INFO    ][8468] Executing command ['systemctl', 'is-active', 'opendaylight.service'] in directory '/root'
2018-01-26 18:14:39,147 [salt.loaded.int.module.cmdmod][INFO    ][8468] Executing command ['systemctl', 'is-enabled', 'opendaylight.service'] in directory '/root'
2018-01-26 18:14:39,161 [salt.loaded.int.module.cmdmod][INFO    ][8468] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'opendaylight.service'] in directory '/root'
2018-01-26 18:14:39,601 [salt.state       ][INFO    ][8468] {'opendaylight': True}
2018-01-26 18:14:39,602 [salt.state       ][INFO    ][8468] Completed state [opendaylight] at time 18:14:39.602695 duration_in_ms=474.579
2018-01-26 18:14:39,604 [salt.minion      ][INFO    ][8468] Returning information for job: 20180126180830839859
2018-01-26 18:34:08,910 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command cp.push_dir with jid 20180126183408900328
2018-01-26 18:34:08,938 [salt.minion      ][INFO    ][13570] Starting a new job with PID 13570
2018-01-26 18:34:09,309 [salt.minion      ][INFO    ][13570] Returning information for job: 20180126183408900328
2018-01-26 19:06:37,325 [salt.utils.schedule][INFO    ][2109] Running scheduled job: __mine_interval
2018-01-26 20:06:37,325 [salt.utils.schedule][INFO    ][2109] Running scheduled job: __mine_interval
2018-01-26 21:06:37,325 [salt.utils.schedule][INFO    ][2109] Running scheduled job: __mine_interval
2018-01-26 22:06:37,325 [salt.utils.schedule][INFO    ][2109] Running scheduled job: __mine_interval
2018-01-26 23:06:37,325 [salt.utils.schedule][INFO    ][2109] Running scheduled job: __mine_interval
2018-01-26 23:13:11,239 [salt.minion      ][INFO    ][2109] User sudo_ubuntu Executing command cp.push_dir with jid 20180126231311233461
2018-01-26 23:13:11,263 [salt.minion      ][INFO    ][17038] Starting a new job with PID 17038
