2019-10-26 13:50:38,723 [salt.utils       :831 ][ERROR   ][1642] DNS lookup or connection check of 'salt' failed.
2019-10-26 13:50:38,723 [salt.minion      :152 ][ERROR   ][1642] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-10-26 13:50:40,803 [salt.utils.parsers:1051][WARNING ][1642] Minion received a SIGTERM. Exiting.
2019-10-26 13:55:57,553 [salt.utils.decorators:613 ][WARNING ][2072] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-26 13:56:13,845 [salt.loaded.int.states.file:2298][WARNING ][2072] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:24,899 [salt.utils.decorators:613 ][WARNING ][2072] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-26 13:56:24,997 [salt.loaded.int.states.file:2298][WARNING ][2072] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:24,998 [salt.loaded.int.states.file:2298][WARNING ][2072] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:25,000 [salt.loaded.int.states.file:2298][WARNING ][2072] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:25,001 [salt.loaded.int.states.file:2298][WARNING ][2072] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:25,002 [salt.loaded.int.states.file:2298][WARNING ][2072] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:25,003 [salt.loaded.int.states.file:2298][WARNING ][2072] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:25,004 [salt.loaded.int.states.file:2298][WARNING ][2072] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:25,005 [salt.loaded.int.states.file:2298][WARNING ][2072] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:34,332 [salt.loaded.int.module.debian_ip:1964][WARNING ][2072] The network state sls is requiring a reboot of the system to properly apply network configuration.
2019-10-26 13:56:35,740 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][2072] Command '['umount', '/dev/shm']' failed with return code: 32
2019-10-26 13:56:35,741 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][2072] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-10-26 13:56:35,741 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][2072] retcode: 32
2019-10-26 13:56:37,611 [salt.utils.parsers:1051][WARNING ][1822] Minion received a SIGTERM. Exiting.
2019-10-26 13:57:49,698 [salt.utils.decorators:613 ][WARNING ][2683] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-26 13:57:52,851 [salt.loaded.int.states.file:2298][WARNING ][2683] State for file: /etc/kubernetes/ssl/ca-kubernetes.crt - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:57:56,451 [salt.loaded.int.states.file:2298][WARNING ][2683] State for file: /var/lib/etcd/ca.pem - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:58:13,691 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4415] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-10-26 13:58:13,721 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4415] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-10-26 13:58:13,738 [salt.utils.parsers:1051][WARNING ][2498] Minion received a SIGTERM. Exiting.
2019-10-26 13:58:14,589 [salt.cli.daemons :293 ][INFO    ][4468] Setting up the Salt Minion "cmp002.mcp-k8s-calico-noha.local"
2019-10-26 13:58:14,688 [salt.cli.daemons :82  ][INFO    ][4468] Starting up the Salt Minion
2019-10-26 13:58:14,689 [salt.utils.event :1017][INFO    ][4468] Starting pull socket on /var/run/salt/minion/minion_event_d677558cdd_pull.ipc
2019-10-26 13:58:15,295 [salt.minion      :976 ][INFO    ][4468] Creating minion process manager
2019-10-26 13:58:16,670 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][4468] Executing command ['date', '+%z'] in directory '/root'
2019-10-26 13:58:16,686 [salt.utils.schedule:568 ][INFO    ][4468] Updating job settings for scheduled job: __mine_interval
2019-10-26 13:58:16,688 [salt.minion      :1108][INFO    ][4468] Added mine.update to scheduler
2019-10-26 13:58:16,693 [salt.minion      :1975][INFO    ][4468] Minion is starting as user 'root'
2019-10-26 13:58:16,707 [salt.minion      :2336][INFO    ][4468] Minion is ready to receive requests!
2019-10-26 13:58:51,454 [salt.minion      :1308][INFO    ][4468] User sudo_ubuntu Executing command state.sls with jid 20191026135851446315
2019-10-26 13:58:51,467 [salt.minion      :1432][INFO    ][4556] Starting a new job with PID 4556
2019-10-26 13:58:52,520 [salt.state       :915 ][INFO    ][4556] Loading fresh modules for state activity
2019-10-26 13:58:52,572 [salt.fileclient  :1219][INFO    ][4556] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/init.sls'
2019-10-26 13:58:52,637 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:52,652 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:52,669 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:52,681 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:52,715 [salt.fileclient  :1219][INFO    ][4556] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/calico.sls'
2019-10-26 13:58:52,773 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:52,786 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:52,801 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:52,812 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:52,873 [salt.fileclient  :1219][INFO    ][4556] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/service.sls'
2019-10-26 13:58:52,920 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:52,932 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:52,944 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:52,959 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:52,994 [salt.fileclient  :1219][INFO    ][4556] Fetching file from saltenv 'base', ** done ** 'kubernetes/_common.sls'
2019-10-26 13:58:53,076 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:53,089 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:53,150 [salt.fileclient  :1219][INFO    ][4556] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/kube-proxy.sls'
2019-10-26 13:58:53,200 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:53,217 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:53,249 [salt.state       :1780][INFO    ][4556] Running state [/usr/bin/calicoctl] at time 13:58:53.248948
2019-10-26 13:58:53,249 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/usr/bin/calicoctl]
2019-10-26 13:58:54,743 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:58:54,743 [salt.state       :1951][INFO    ][4556] Completed state [/usr/bin/calicoctl] at time 13:58:54.743818 duration_in_ms=1494.87
2019-10-26 13:58:54,744 [salt.state       :1780][INFO    ][4556] Running state [/usr/bin/birdcl] at time 13:58:54.744330
2019-10-26 13:58:54,744 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/usr/bin/birdcl]
2019-10-26 13:58:55,076 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:58:55,076 [salt.state       :1951][INFO    ][4556] Completed state [/usr/bin/birdcl] at time 13:58:55.076709 duration_in_ms=332.378
2019-10-26 13:58:55,077 [salt.state       :1780][INFO    ][4556] Running state [/opt/cni/bin/calico] at time 13:58:55.077129
2019-10-26 13:58:55,077 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/opt/cni/bin/calico]
2019-10-26 13:58:56,543 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:58:56,544 [salt.state       :1951][INFO    ][4556] Completed state [/opt/cni/bin/calico] at time 13:58:56.544208 duration_in_ms=1467.077
2019-10-26 13:58:56,544 [salt.state       :1780][INFO    ][4556] Running state [/opt/cni/bin/calico-ipam] at time 13:58:56.544764
2019-10-26 13:58:56,545 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/opt/cni/bin/calico-ipam]
2019-10-26 13:58:58,598 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:58:58,599 [salt.state       :1951][INFO    ][4556] Completed state [/opt/cni/bin/calico-ipam] at time 13:58:58.599281 duration_in_ms=2054.517
2019-10-26 13:58:58,599 [salt.state       :1780][INFO    ][4556] Running state [/etc/cni/net.d/10-calico.conf] at time 13:58:58.599572
2019-10-26 13:58:58,599 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/etc/cni/net.d/10-calico.conf]
2019-10-26 13:58:58,618 [salt.fileclient  :1219][INFO    ][4556] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calico.conf'
2019-10-26 13:58:58,678 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:58,693 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:58,709 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:58,726 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:58,744 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:58:58,745 [salt.state       :1951][INFO    ][4556] Completed state [/etc/cni/net.d/10-calico.conf] at time 13:58:58.745004 duration_in_ms=145.431
2019-10-26 13:58:58,745 [salt.state       :1780][INFO    ][4556] Running state [/etc/calico/network-environment] at time 13:58:58.745310
2019-10-26 13:58:58,745 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/etc/calico/network-environment]
2019-10-26 13:58:58,765 [salt.fileclient  :1219][INFO    ][4556] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/network-environment.pool'
2019-10-26 13:58:58,818 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:58,833 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:58,853 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:58:58,854 [salt.state       :1951][INFO    ][4556] Completed state [/etc/calico/network-environment] at time 13:58:58.853939 duration_in_ms=108.627
2019-10-26 13:58:58,854 [salt.state       :1780][INFO    ][4556] Running state [/etc/calico/calicoctl.cfg] at time 13:58:58.854317
2019-10-26 13:58:58,854 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/etc/calico/calicoctl.cfg]
2019-10-26 13:58:58,874 [salt.fileclient  :1219][INFO    ][4556] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calicoctl.cfg.pool'
2019-10-26 13:58:58,924 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:58,938 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:58,952 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:58:58,953 [salt.state       :1951][INFO    ][4556] Completed state [/etc/calico/calicoctl.cfg] at time 13:58:58.953257 duration_in_ms=98.938
2019-10-26 13:58:59,600 [salt.state       :1780][INFO    ][4556] Running state [containerd=1.2.5-3~u16.04+mcp] at time 13:58:59.600412
2019-10-26 13:58:59,600 [salt.state       :1813][INFO    ][4556] Executing state pkg.installed for [containerd=1.2.5-3~u16.04+mcp]
2019-10-26 13:58:59,601 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-26 13:59:00,008 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['apt-cache', '-q', 'policy', 'containerd=1.2.5-3~u16.04+mcp'] in directory '/root'
2019-10-26 13:59:00,110 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-10-26 13:59:02,097 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-10-26 13:59:02,118 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'containerd=1.2.5-3~u16.04+mcp'] in directory '/root'
2019-10-26 13:59:06,582 [salt.minion      :1308][INFO    ][4468] User sudo_ubuntu Executing command saltutil.find_job with jid 20191026135906571083
2019-10-26 13:59:06,600 [salt.minion      :1432][INFO    ][5121] Starting a new job with PID 5121
2019-10-26 13:59:06,618 [salt.minion      :1711][INFO    ][5121] Returning information for job: 20191026135906571083
2019-10-26 13:59:14,147 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-26 13:59:14,180 [salt.state       :300 ][INFO    ][4556] Made the following changes:
'containerd' changed from 'absent' to '1.2.5-3~u16.04+mcp'
'runc' changed from 'absent' to '1.0.0~rc7+git20190403.029124da-0ubuntu1~16.04.4'

2019-10-26 13:59:14,198 [salt.state       :915 ][INFO    ][4556] Loading fresh modules for state activity
2019-10-26 13:59:14,238 [salt.state       :1951][INFO    ][4556] Completed state [containerd=1.2.5-3~u16.04+mcp] at time 13:59:14.238000 duration_in_ms=14637.59
2019-10-26 13:59:14,241 [salt.state       :1780][INFO    ][4556] Running state [/etc/containerd/config.toml] at time 13:59:14.241837
2019-10-26 13:59:14,242 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/etc/containerd/config.toml]
2019-10-26 13:59:14,265 [salt.fileclient  :1219][INFO    ][4556] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/containerd/config.toml'
2019-10-26 13:59:14,312 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:14,324 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:14,343 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:59:14,344 [salt.state       :1951][INFO    ][4556] Completed state [/etc/containerd/config.toml] at time 13:59:14.344207 duration_in_ms=102.369
2019-10-26 13:59:14,802 [salt.state       :1780][INFO    ][4556] Running state [containerd] at time 13:59:14.802111
2019-10-26 13:59:14,802 [salt.state       :1813][INFO    ][4556] Executing state service.running for [containerd]
2019-10-26 13:59:14,803 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'status', 'containerd.service', '-n', '0'] in directory '/root'
2019-10-26 13:59:14,820 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-10-26 13:59:14,836 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-enabled', 'containerd.service'] in directory '/root'
2019-10-26 13:59:14,851 [salt.state       :300 ][INFO    ][4556] The service containerd is already running
2019-10-26 13:59:14,852 [salt.state       :1951][INFO    ][4556] Completed state [containerd] at time 13:59:14.852303 duration_in_ms=50.193
2019-10-26 13:59:14,852 [salt.state       :1780][INFO    ][4556] Running state [containerd] at time 13:59:14.852635
2019-10-26 13:59:14,853 [salt.state       :1813][INFO    ][4556] Executing state service.mod_watch for [containerd]
2019-10-26 13:59:14,853 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-10-26 13:59:14,870 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'containerd.service'] in directory '/root'
2019-10-26 13:59:14,900 [salt.state       :300 ][INFO    ][4556] {'containerd': True}
2019-10-26 13:59:14,901 [salt.state       :1951][INFO    ][4556] Completed state [containerd] at time 13:59:14.901322 duration_in_ms=48.687
2019-10-26 13:59:14,902 [salt.state       :1780][INFO    ][4556] Running state [/etc/systemd/system/calico-node.service] at time 13:59:14.902303
2019-10-26 13:59:14,902 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/etc/systemd/system/calico-node.service]
2019-10-26 13:59:14,919 [salt.fileclient  :1219][INFO    ][4556] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calico-node.service.ctr'
2019-10-26 13:59:14,947 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:59:14,947 [salt.state       :1951][INFO    ][4556] Completed state [/etc/systemd/system/calico-node.service] at time 13:59:14.947232 duration_in_ms=44.928
2019-10-26 13:59:14,947 [salt.state       :1780][INFO    ][4556] Running state [/var/lib/calico] at time 13:59:14.947497
2019-10-26 13:59:14,947 [salt.state       :1813][INFO    ][4556] Executing state file.directory for [/var/lib/calico]
2019-10-26 13:59:14,948 [salt.state       :300 ][INFO    ][4556] {'/var/lib/calico': 'New Dir'}
2019-10-26 13:59:14,949 [salt.state       :1951][INFO    ][4556] Completed state [/var/lib/calico] at time 13:59:14.949113 duration_in_ms=1.616
2019-10-26 13:59:14,949 [salt.state       :1780][INFO    ][4556] Running state [/var/log/calico] at time 13:59:14.949337
2019-10-26 13:59:14,949 [salt.state       :1813][INFO    ][4556] Executing state file.directory for [/var/log/calico]
2019-10-26 13:59:14,950 [salt.state       :300 ][INFO    ][4556] {'/var/log/calico': 'New Dir'}
2019-10-26 13:59:14,950 [salt.state       :1951][INFO    ][4556] Completed state [/var/log/calico] at time 13:59:14.950900 duration_in_ms=1.563
2019-10-26 13:59:14,953 [salt.state       :1780][INFO    ][4556] Running state [calico-node] at time 13:59:14.953300
2019-10-26 13:59:14,953 [salt.state       :1813][INFO    ][4556] Executing state service.running for [calico-node]
2019-10-26 13:59:14,954 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'status', 'calico-node.service', '-n', '0'] in directory '/root'
2019-10-26 13:59:14,973 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:14,989 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:15,011 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:19,045 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:19,058 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:19,074 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:19,095 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:19,209 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:19,222 [salt.state       :300 ][INFO    ][4556] {'calico-node': True}
2019-10-26 13:59:19,222 [salt.state       :1951][INFO    ][4556] Completed state [calico-node] at time 13:59:19.222804 duration_in_ms=4269.504
2019-10-26 13:59:19,224 [salt.state       :1780][INFO    ][4556] Running state [curl] at time 13:59:19.224798
2019-10-26 13:59:19,225 [salt.state       :1813][INFO    ][4556] Executing state pkg.installed for [curl]
2019-10-26 13:59:19,437 [salt.state       :300 ][INFO    ][4556] All specified packages are already installed
2019-10-26 13:59:19,438 [salt.state       :1951][INFO    ][4556] Completed state [curl] at time 13:59:19.438138 duration_in_ms=213.339
2019-10-26 13:59:19,438 [salt.state       :1780][INFO    ][4556] Running state [git] at time 13:59:19.438433
2019-10-26 13:59:19,438 [salt.state       :1813][INFO    ][4556] Executing state pkg.installed for [git]
2019-10-26 13:59:19,447 [salt.state       :300 ][INFO    ][4556] All specified packages are already installed
2019-10-26 13:59:19,448 [salt.state       :1951][INFO    ][4556] Completed state [git] at time 13:59:19.448014 duration_in_ms=9.581
2019-10-26 13:59:19,448 [salt.state       :1780][INFO    ][4556] Running state [apt-transport-https] at time 13:59:19.448278
2019-10-26 13:59:19,448 [salt.state       :1813][INFO    ][4556] Executing state pkg.installed for [apt-transport-https]
2019-10-26 13:59:19,457 [salt.state       :300 ][INFO    ][4556] All specified packages are already installed
2019-10-26 13:59:19,457 [salt.state       :1951][INFO    ][4556] Completed state [apt-transport-https] at time 13:59:19.457683 duration_in_ms=9.405
2019-10-26 13:59:19,457 [salt.state       :1780][INFO    ][4556] Running state [python-apt] at time 13:59:19.457911
2019-10-26 13:59:19,458 [salt.state       :1813][INFO    ][4556] Executing state pkg.installed for [python-apt]
2019-10-26 13:59:19,466 [salt.state       :300 ][INFO    ][4556] All specified packages are already installed
2019-10-26 13:59:19,467 [salt.state       :1951][INFO    ][4556] Completed state [python-apt] at time 13:59:19.467013 duration_in_ms=9.102
2019-10-26 13:59:19,467 [salt.state       :1780][INFO    ][4556] Running state [socat] at time 13:59:19.467344
2019-10-26 13:59:19,467 [salt.state       :1813][INFO    ][4556] Executing state pkg.installed for [socat]
2019-10-26 13:59:19,493 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-10-26 13:59:19,513 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'socat'] in directory '/root'
2019-10-26 13:59:21,733 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-26 13:59:21,769 [salt.state       :300 ][INFO    ][4556] Made the following changes:
'socat' changed from 'absent' to '1.7.3.1-1'

2019-10-26 13:59:21,785 [salt.state       :915 ][INFO    ][4556] Loading fresh modules for state activity
2019-10-26 13:59:21,836 [salt.state       :1951][INFO    ][4556] Completed state [socat] at time 13:59:21.836519 duration_in_ms=2369.174
2019-10-26 13:59:21,842 [salt.state       :1780][INFO    ][4556] Running state [openssl] at time 13:59:21.842783
2019-10-26 13:59:21,843 [salt.state       :1813][INFO    ][4556] Executing state pkg.installed for [openssl]
2019-10-26 13:59:22,516 [salt.state       :300 ][INFO    ][4556] All specified packages are already installed
2019-10-26 13:59:22,516 [salt.state       :1951][INFO    ][4556] Completed state [openssl] at time 13:59:22.516428 duration_in_ms=673.644
2019-10-26 13:59:22,516 [salt.state       :1780][INFO    ][4556] Running state [conntrack] at time 13:59:22.516883
2019-10-26 13:59:22,517 [salt.state       :1813][INFO    ][4556] Executing state pkg.installed for [conntrack]
2019-10-26 13:59:22,543 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-10-26 13:59:22,563 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'conntrack'] in directory '/root'
2019-10-26 13:59:24,673 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-26 13:59:24,707 [salt.state       :300 ][INFO    ][4556] Made the following changes:
'conntrack' changed from 'absent' to '1:1.4.3-3'

2019-10-26 13:59:24,723 [salt.state       :915 ][INFO    ][4556] Loading fresh modules for state activity
2019-10-26 13:59:24,766 [salt.state       :1951][INFO    ][4556] Completed state [conntrack] at time 13:59:24.766261 duration_in_ms=2249.378
2019-10-26 13:59:24,769 [salt.state       :1780][INFO    ][4556] Running state [/usr/bin/hyperkube] at time 13:59:24.769914
2019-10-26 13:59:24,770 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/usr/bin/hyperkube]
2019-10-26 13:59:32,993 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:59:32,993 [salt.state       :1951][INFO    ][4556] Completed state [/usr/bin/hyperkube] at time 13:59:32.993649 duration_in_ms=8223.733
2019-10-26 13:59:32,994 [salt.state       :1780][INFO    ][4556] Running state [/usr/bin/kubectl] at time 13:59:32.994936
2019-10-26 13:59:32,995 [salt.state       :1813][INFO    ][4556] Executing state file.symlink for [/usr/bin/kubectl]
2019-10-26 13:59:33,004 [salt.state       :300 ][INFO    ][4556] {'new': '/usr/bin/kubectl'}
2019-10-26 13:59:33,015 [salt.state       :915 ][INFO    ][4556] Loading fresh modules for state activity
2019-10-26 13:59:33,055 [salt.state       :1951][INFO    ][4556] Completed state [/usr/bin/kubectl] at time 13:59:33.055819 duration_in_ms=60.881
2019-10-26 13:59:33,059 [salt.state       :1780][INFO    ][4556] Running state [/tmp/crictl] at time 13:59:33.059883
2019-10-26 13:59:33,060 [salt.state       :1813][INFO    ][4556] Executing state archive.extracted for [/tmp/crictl]
2019-10-26 13:59:35,315 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['tar', 'xz', '-f', '/var/cache/salt/minion/extrn_files/base/github.com/kubernetes-sigs/cri-tools/releases/download/v1.12.0/crictl-v1.12.0-linux-amd64.tar.gz'] in directory '/tmp/crictl/'
2019-10-26 13:59:35,549 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['tar', '--version'] in directory '/root'
2019-10-26 13:59:35,563 [salt.state       :300 ][INFO    ][4556] {'extracted_files': 'no tar output so far', 'directories_created': ['/tmp/crictl/']}
2019-10-26 13:59:35,563 [salt.state       :1951][INFO    ][4556] Completed state [/tmp/crictl] at time 13:59:35.563681 duration_in_ms=2503.796
2019-10-26 13:59:35,564 [salt.state       :1780][INFO    ][4556] Running state [/usr/local/bin/crictl] at time 13:59:35.564908
2019-10-26 13:59:35,565 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/usr/local/bin/crictl]
2019-10-26 13:59:35,566 [salt.loaded.int.states.file:1019][WARNING ][4556] Use of argument owner found, "owner" is invalid, please use "user"
2019-10-26 13:59:35,954 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:59:35,955 [salt.state       :1951][INFO    ][4556] Completed state [/usr/local/bin/crictl] at time 13:59:35.955083 duration_in_ms=390.176
2019-10-26 13:59:35,956 [salt.state       :1780][INFO    ][4556] Running state [/etc/crictl.yaml] at time 13:59:35.956107
2019-10-26 13:59:35,956 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/etc/crictl.yaml]
2019-10-26 13:59:35,960 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:59:35,961 [salt.state       :1951][INFO    ][4556] Completed state [/etc/crictl.yaml] at time 13:59:35.961084 duration_in_ms=4.977
2019-10-26 13:59:35,961 [salt.state       :1780][INFO    ][4556] Running state [/etc/criproxy] at time 13:59:35.961711
2019-10-26 13:59:35,962 [salt.state       :1813][INFO    ][4556] Executing state file.absent for [/etc/criproxy]
2019-10-26 13:59:35,963 [salt.state       :300 ][INFO    ][4556] File /etc/criproxy is not present
2019-10-26 13:59:35,963 [salt.state       :1951][INFO    ][4556] Completed state [/etc/criproxy] at time 13:59:35.963712 duration_in_ms=2.001
2019-10-26 13:59:36,484 [salt.state       :1780][INFO    ][4556] Running state [criproxy] at time 13:59:36.484551
2019-10-26 13:59:36,485 [salt.state       :1813][INFO    ][4556] Executing state service.dead for [criproxy]
2019-10-26 13:59:36,486 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'status', 'criproxy.service', '-n', '0'] in directory '/root'
2019-10-26 13:59:36,501 [salt.state       :300 ][INFO    ][4556] The named service criproxy is not available
2019-10-26 13:59:36,501 [salt.state       :1951][INFO    ][4556] Completed state [criproxy] at time 13:59:36.501646 duration_in_ms=17.095
2019-10-26 13:59:36,502 [salt.state       :1780][INFO    ][4556] Running state [/etc/systemd/system/kubelet.service] at time 13:59:36.502240
2019-10-26 13:59:36,502 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/etc/systemd/system/kubelet.service]
2019-10-26 13:59:36,521 [salt.fileclient  :1219][INFO    ][4556] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kubelet.service'
2019-10-26 13:59:36,569 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:36,606 [salt.minion      :1308][INFO    ][4468] User sudo_ubuntu Executing command saltutil.find_job with jid 20191026135936602751
2019-10-26 13:59:36,623 [salt.minion      :1432][INFO    ][6915] Starting a new job with PID 6915
2019-10-26 13:59:36,642 [salt.minion      :1711][INFO    ][6915] Returning information for job: 20191026135936602751
2019-10-26 13:59:36,700 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:36,852 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:59:36,854 [salt.state       :1951][INFO    ][4556] Completed state [/etc/systemd/system/kubelet.service] at time 13:59:36.854139 duration_in_ms=351.897
2019-10-26 13:59:36,855 [salt.state       :1780][INFO    ][4556] Running state [/etc/kubernetes/config] at time 13:59:36.854973
2019-10-26 13:59:36,855 [salt.state       :1813][INFO    ][4556] Executing state file.absent for [/etc/kubernetes/config]
2019-10-26 13:59:36,856 [salt.state       :300 ][INFO    ][4556] File /etc/kubernetes/config is not present
2019-10-26 13:59:36,857 [salt.state       :1951][INFO    ][4556] Completed state [/etc/kubernetes/config] at time 13:59:36.857549 duration_in_ms=2.576
2019-10-26 13:59:36,858 [salt.state       :1780][INFO    ][4556] Running state [/etc/default/kubelet] at time 13:59:36.858160
2019-10-26 13:59:36,858 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/etc/default/kubelet]
2019-10-26 13:59:36,878 [salt.fileclient  :1219][INFO    ][4556] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubelet/default.pool'
2019-10-26 13:59:36,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:37,103 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:37,241 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:37,396 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:37,531 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:37,677 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:37,822 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:59:37,823 [salt.state       :1951][INFO    ][4556] Completed state [/etc/default/kubelet] at time 13:59:37.823534 duration_in_ms=965.373
2019-10-26 13:59:37,824 [salt.state       :1780][INFO    ][4556] Running state [/etc/kubernetes/kubelet.kubeconfig] at time 13:59:37.824054
2019-10-26 13:59:37,824 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/etc/kubernetes/kubelet.kubeconfig]
2019-10-26 13:59:37,843 [salt.fileclient  :1219][INFO    ][4556] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubelet/kubelet.kubeconfig.pool'
2019-10-26 13:59:37,896 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:38,029 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:38,182 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:38,315 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:38,444 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:59:38,445 [salt.state       :1951][INFO    ][4556] Completed state [/etc/kubernetes/kubelet.kubeconfig] at time 13:59:38.445317 duration_in_ms=621.262
2019-10-26 13:59:38,445 [salt.state       :1780][INFO    ][4556] Running state [/etc/kubernetes/manifests] at time 13:59:38.445931
2019-10-26 13:59:38,446 [salt.state       :1813][INFO    ][4556] Executing state file.directory for [/etc/kubernetes/manifests]
2019-10-26 13:59:38,448 [salt.state       :300 ][INFO    ][4556] {'/etc/kubernetes/manifests': 'New Dir'}
2019-10-26 13:59:38,448 [salt.state       :1951][INFO    ][4556] Completed state [/etc/kubernetes/manifests] at time 13:59:38.448717 duration_in_ms=2.786
2019-10-26 13:59:38,451 [salt.state       :1780][INFO    ][4556] Running state [kubelet] at time 13:59:38.451186
2019-10-26 13:59:38,451 [salt.state       :1813][INFO    ][4556] Executing state service.running for [kubelet]
2019-10-26 13:59:38,452 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'status', 'kubelet.service', '-n', '0'] in directory '/root'
2019-10-26 13:59:38,474 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:38,494 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:38,520 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:38,543 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:38,558 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:38,575 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:38,605 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:38,733 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:38,748 [salt.state       :300 ][INFO    ][4556] {'kubelet': True}
2019-10-26 13:59:38,748 [salt.state       :1951][INFO    ][4556] Completed state [kubelet] at time 13:59:38.748550 duration_in_ms=297.362
2019-10-26 13:59:38,749 [salt.state       :1780][INFO    ][4556] Running state [/etc/logrotate.d/kubernetes] at time 13:59:38.749083
2019-10-26 13:59:38,749 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/etc/logrotate.d/kubernetes]
2019-10-26 13:59:38,768 [salt.fileclient  :1219][INFO    ][4556] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/logrotate'
2019-10-26 13:59:38,779 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:59:38,779 [salt.state       :1951][INFO    ][4556] Completed state [/etc/logrotate.d/kubernetes] at time 13:59:38.779316 duration_in_ms=30.232
2019-10-26 13:59:38,779 [salt.state       :1780][INFO    ][4556] Running state [/opt/cni/bin] at time 13:59:38.779705
2019-10-26 13:59:38,780 [salt.state       :1813][INFO    ][4556] Executing state archive.extracted for [/opt/cni/bin]
2019-10-26 13:59:41,611 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['tar', 'xz', '-f', '/var/cache/salt/minion/extrn_files/base/docker-prod-local.artifactory.mirantis.com/artifactory/binary-prod-local/mirantis/kubernetes/containernetworking-plugins/containernetworking-plugins_v0.7.2-173-g8db2808.tar.gz'] in directory '/opt/cni/bin/'
2019-10-26 13:59:42,188 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['tar', '--version'] in directory '/root'
2019-10-26 13:59:42,201 [salt.state       :300 ][INFO    ][4556] {'extracted_files': 'no tar output so far'}
2019-10-26 13:59:42,201 [salt.state       :1951][INFO    ][4556] Completed state [/opt/cni/bin] at time 13:59:42.201443 duration_in_ms=3421.736
2019-10-26 13:59:42,201 [salt.state       :1780][INFO    ][4556] Running state [/etc/kubernetes/proxy.kubeconfig] at time 13:59:42.201802
2019-10-26 13:59:42,202 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/etc/kubernetes/proxy.kubeconfig]
2019-10-26 13:59:42,225 [salt.fileclient  :1219][INFO    ][4556] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-proxy/proxy.kubeconfig'
2019-10-26 13:59:42,275 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:42,420 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:42,559 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:42,679 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:42,813 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:59:42,813 [salt.state       :1951][INFO    ][4556] Completed state [/etc/kubernetes/proxy.kubeconfig] at time 13:59:42.813459 duration_in_ms=611.656
2019-10-26 13:59:42,813 [salt.state       :1780][INFO    ][4556] Running state [/etc/systemd/system/kube-proxy.service] at time 13:59:42.813802
2019-10-26 13:59:42,814 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/etc/systemd/system/kube-proxy.service]
2019-10-26 13:59:42,832 [salt.fileclient  :1219][INFO    ][4556] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-proxy.service'
2019-10-26 13:59:42,842 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:59:42,842 [salt.state       :1951][INFO    ][4556] Completed state [/etc/systemd/system/kube-proxy.service] at time 13:59:42.842485 duration_in_ms=28.683
2019-10-26 13:59:42,842 [salt.state       :1780][INFO    ][4556] Running state [/etc/default/kube-proxy] at time 13:59:42.842723
2019-10-26 13:59:42,842 [salt.state       :1813][INFO    ][4556] Executing state file.managed for [/etc/default/kube-proxy]
2019-10-26 13:59:42,844 [salt.state       :300 ][INFO    ][4556] File changed:
New file
2019-10-26 13:59:42,844 [salt.state       :1951][INFO    ][4556] Completed state [/etc/default/kube-proxy] at time 13:59:42.844883 duration_in_ms=2.16
2019-10-26 13:59:42,846 [salt.state       :1780][INFO    ][4556] Running state [kube-proxy] at time 13:59:42.846521
2019-10-26 13:59:42,846 [salt.state       :1813][INFO    ][4556] Executing state service.running for [kube-proxy]
2019-10-26 13:59:42,847 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'status', 'kube-proxy.service', '-n', '0'] in directory '/root'
2019-10-26 13:59:42,865 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:42,877 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:42,899 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:42,919 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:42,932 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:42,949 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:42,973 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:43,083 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4556] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:43,099 [salt.state       :300 ][INFO    ][4556] {'kube-proxy': True}
2019-10-26 13:59:43,100 [salt.state       :1951][INFO    ][4556] Completed state [kube-proxy] at time 13:59:43.100203 duration_in_ms=253.681
2019-10-26 13:59:43,103 [salt.minion      :1711][INFO    ][4556] Returning information for job: 20191026135851446315
2019-10-26 13:59:47,243 [salt.minion      :1308][INFO    ][4468] User sudo_ubuntu Executing command cmd.run with jid 20191026135947236400
2019-10-26 13:59:47,259 [salt.minion      :1432][INFO    ][7433] Starting a new job with PID 7433
2019-10-26 13:59:47,267 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][7433] Executing command 'calicoctl node status' in directory '/root'
2019-10-26 13:59:47,317 [salt.minion      :1711][INFO    ][7433] Returning information for job: 20191026135947236400
2019-10-26 13:59:47,960 [salt.minion      :1308][INFO    ][4468] User sudo_ubuntu Executing command cmd.run with jid 20191026135947953882
2019-10-26 13:59:47,981 [salt.minion      :1432][INFO    ][7450] Starting a new job with PID 7450
2019-10-26 13:59:47,991 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][7450] Executing command 'calicoctl get ippool' in directory '/root'
2019-10-26 13:59:48,099 [salt.minion      :1711][INFO    ][7450] Returning information for job: 20191026135947953882
2019-10-26 14:00:51,453 [salt.minion      :1308][INFO    ][4468] User sudo_ubuntu Executing command cp.push_dir with jid 20191026140051450558
2019-10-26 14:00:51,470 [salt.minion      :1432][INFO    ][7588] Starting a new job with PID 7588
2019-10-26 14:00:51,843 [salt.minion      :1711][INFO    ][7588] Returning information for job: 20191026140051450558
2019-10-26 14:03:47,239 [salt.minion      :1308][INFO    ][4468] User sudo_ubuntu Executing command cp.push_dir with jid 20191026140347233165
2019-10-26 14:03:47,256 [salt.minion      :1432][INFO    ][8576] Starting a new job with PID 8576
