2019-10-26 13:50:31,074 [salt.utils       :831 ][ERROR   ][1693] DNS lookup or connection check of 'salt' failed.
2019-10-26 13:50:31,075 [salt.minion      :152 ][ERROR   ][1693] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-10-26 13:50:32,902 [salt.utils.parsers:1051][WARNING ][1693] Minion received a SIGTERM. Exiting.
2019-10-26 13:55:57,475 [salt.utils.decorators:613 ][WARNING ][2115] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-26 13:56:13,745 [salt.loaded.int.states.file:2298][WARNING ][2115] 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,936 [salt.utils.decorators:613 ][WARNING ][2115] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-26 13:56:25,028 [salt.loaded.int.states.file:2298][WARNING ][2115] 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:25,030 [salt.loaded.int.states.file:2298][WARNING ][2115] 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,031 [salt.loaded.int.states.file:2298][WARNING ][2115] 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,032 [salt.loaded.int.states.file:2298][WARNING ][2115] 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,033 [salt.loaded.int.states.file:2298][WARNING ][2115] 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,034 [salt.loaded.int.states.file:2298][WARNING ][2115] 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,035 [salt.loaded.int.states.file:2298][WARNING ][2115] 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,036 [salt.loaded.int.states.file:2298][WARNING ][2115] 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,543 [salt.loaded.int.module.debian_ip:1964][WARNING ][2115] The network state sls is requiring a reboot of the system to properly apply network configuration.
2019-10-26 13:56:35,905 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][2115] Command '['umount', '/dev/shm']' failed with return code: 32
2019-10-26 13:56:35,906 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][2115] 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,906 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][2115] retcode: 32
2019-10-26 13:56:37,696 [salt.utils.parsers:1051][WARNING ][1904] Minion received a SIGTERM. Exiting.
2019-10-26 13:57:49,847 [salt.utils.decorators:613 ][WARNING ][2675] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-26 13:57:53,033 [salt.loaded.int.states.file:2298][WARNING ][2675] 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:57,003 [salt.loaded.int.states.file:2298][WARNING ][2675] 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:12,756 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4392] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-10-26 13:58:12,779 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4392] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-10-26 13:58:12,795 [salt.utils.parsers:1051][WARNING ][2498] Minion received a SIGTERM. Exiting.
2019-10-26 13:58:13,673 [salt.cli.daemons :293 ][INFO    ][4445] Setting up the Salt Minion "cmp001.mcp-k8s-calico-noha.local"
2019-10-26 13:58:13,789 [salt.cli.daemons :82  ][INFO    ][4445] Starting up the Salt Minion
2019-10-26 13:58:13,789 [salt.utils.event :1017][INFO    ][4445] Starting pull socket on /var/run/salt/minion/minion_event_23b3bf79fe_pull.ipc
2019-10-26 13:58:14,419 [salt.minion      :976 ][INFO    ][4445] Creating minion process manager
2019-10-26 13:58:16,003 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][4445] Executing command ['date', '+%z'] in directory '/root'
2019-10-26 13:58:16,022 [salt.utils.schedule:568 ][INFO    ][4445] Updating job settings for scheduled job: __mine_interval
2019-10-26 13:58:16,025 [salt.minion      :1108][INFO    ][4445] Added mine.update to scheduler
2019-10-26 13:58:16,031 [salt.minion      :1975][INFO    ][4445] Minion is starting as user 'root'
2019-10-26 13:58:16,044 [salt.minion      :2336][INFO    ][4445] Minion is ready to receive requests!
2019-10-26 13:58:51,455 [salt.minion      :1308][INFO    ][4445] User sudo_ubuntu Executing command state.sls with jid 20191026135851446315
2019-10-26 13:58:51,470 [salt.minion      :1432][INFO    ][4532] Starting a new job with PID 4532
2019-10-26 13:58:52,471 [salt.state       :915 ][INFO    ][4532] Loading fresh modules for state activity
2019-10-26 13:58:52,523 [salt.fileclient  :1219][INFO    ][4532] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/init.sls'
2019-10-26 13:58:52,586 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,600 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,619 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,632 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,664 [salt.fileclient  :1219][INFO    ][4532] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/calico.sls'
2019-10-26 13:58:52,723 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,737 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,752 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,765 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,831 [salt.fileclient  :1219][INFO    ][4532] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/service.sls'
2019-10-26 13:58:52,878 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,892 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,907 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,920 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,954 [salt.fileclient  :1219][INFO    ][4532] Fetching file from saltenv 'base', ** done ** 'kubernetes/_common.sls'
2019-10-26 13:58:53,038 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,054 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,123 [salt.fileclient  :1219][INFO    ][4532] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/kube-proxy.sls'
2019-10-26 13:58:53,173 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,186 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,222 [salt.state       :1780][INFO    ][4532] Running state [/usr/bin/calicoctl] at time 13:58:53.222113
2019-10-26 13:58:53,222 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/usr/bin/calicoctl]
2019-10-26 13:58:54,584 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:58:54,584 [salt.state       :1951][INFO    ][4532] Completed state [/usr/bin/calicoctl] at time 13:58:54.584708 duration_in_ms=1362.596
2019-10-26 13:58:54,585 [salt.state       :1780][INFO    ][4532] Running state [/usr/bin/birdcl] at time 13:58:54.585138
2019-10-26 13:58:54,585 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/usr/bin/birdcl]
2019-10-26 13:58:54,943 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:58:54,943 [salt.state       :1951][INFO    ][4532] Completed state [/usr/bin/birdcl] at time 13:58:54.943805 duration_in_ms=358.664
2019-10-26 13:58:54,944 [salt.state       :1780][INFO    ][4532] Running state [/opt/cni/bin/calico] at time 13:58:54.944363
2019-10-26 13:58:54,945 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/opt/cni/bin/calico]
2019-10-26 13:58:56,043 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:58:56,043 [salt.state       :1951][INFO    ][4532] Completed state [/opt/cni/bin/calico] at time 13:58:56.043916 duration_in_ms=1099.553
2019-10-26 13:58:56,044 [salt.state       :1780][INFO    ][4532] Running state [/opt/cni/bin/calico-ipam] at time 13:58:56.044179
2019-10-26 13:58:56,044 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/opt/cni/bin/calico-ipam]
2019-10-26 13:58:56,980 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:58:56,981 [salt.state       :1951][INFO    ][4532] Completed state [/opt/cni/bin/calico-ipam] at time 13:58:56.981089 duration_in_ms=936.907
2019-10-26 13:58:56,981 [salt.state       :1780][INFO    ][4532] Running state [/etc/cni/net.d/10-calico.conf] at time 13:58:56.981353
2019-10-26 13:58:56,981 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/etc/cni/net.d/10-calico.conf]
2019-10-26 13:58:56,998 [salt.fileclient  :1219][INFO    ][4532] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calico.conf'
2019-10-26 13:58:57,054 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:57,068 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:57,081 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:57,093 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:57,107 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:58:57,107 [salt.state       :1951][INFO    ][4532] Completed state [/etc/cni/net.d/10-calico.conf] at time 13:58:57.107847 duration_in_ms=126.492
2019-10-26 13:58:57,108 [salt.state       :1780][INFO    ][4532] Running state [/etc/calico/network-environment] at time 13:58:57.108133
2019-10-26 13:58:57,108 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/etc/calico/network-environment]
2019-10-26 13:58:57,123 [salt.fileclient  :1219][INFO    ][4532] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/network-environment.pool'
2019-10-26 13:58:57,168 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:57,183 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:57,202 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:58:57,202 [salt.state       :1951][INFO    ][4532] Completed state [/etc/calico/network-environment] at time 13:58:57.202691 duration_in_ms=94.558
2019-10-26 13:58:57,203 [salt.state       :1780][INFO    ][4532] Running state [/etc/calico/calicoctl.cfg] at time 13:58:57.203024
2019-10-26 13:58:57,203 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/etc/calico/calicoctl.cfg]
2019-10-26 13:58:57,224 [salt.fileclient  :1219][INFO    ][4532] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calicoctl.cfg.pool'
2019-10-26 13:58:57,273 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:57,290 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:57,310 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:58:57,311 [salt.state       :1951][INFO    ][4532] Completed state [/etc/calico/calicoctl.cfg] at time 13:58:57.311353 duration_in_ms=108.328
2019-10-26 13:58:57,974 [salt.state       :1780][INFO    ][4532] Running state [containerd=1.2.5-3~u16.04+mcp] at time 13:58:57.974095
2019-10-26 13:58:57,974 [salt.state       :1813][INFO    ][4532] Executing state pkg.installed for [containerd=1.2.5-3~u16.04+mcp]
2019-10-26 13:58:57,975 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-26 13:58:58,375 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['apt-cache', '-q', 'policy', 'containerd=1.2.5-3~u16.04+mcp'] in directory '/root'
2019-10-26 13:58:58,486 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-10-26 13:59:01,160 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-10-26 13:59:01,186 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,584 [salt.minion      :1308][INFO    ][4445] User sudo_ubuntu Executing command saltutil.find_job with jid 20191026135906571083
2019-10-26 13:59:06,604 [salt.minion      :1432][INFO    ][5098] Starting a new job with PID 5098
2019-10-26 13:59:06,622 [salt.minion      :1711][INFO    ][5098] Returning information for job: 20191026135906571083
2019-10-26 13:59:14,484 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-26 13:59:14,521 [salt.state       :300 ][INFO    ][4532] 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,539 [salt.state       :915 ][INFO    ][4532] Loading fresh modules for state activity
2019-10-26 13:59:14,578 [salt.state       :1951][INFO    ][4532] Completed state [containerd=1.2.5-3~u16.04+mcp] at time 13:59:14.578279 duration_in_ms=16604.184
2019-10-26 13:59:14,582 [salt.state       :1780][INFO    ][4532] Running state [/etc/containerd/config.toml] at time 13:59:14.582195
2019-10-26 13:59:14,582 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/etc/containerd/config.toml]
2019-10-26 13:59:14,604 [salt.fileclient  :1219][INFO    ][4532] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/containerd/config.toml'
2019-10-26 13:59:14,649 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,662 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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,684 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:59:14,685 [salt.state       :1951][INFO    ][4532] Completed state [/etc/containerd/config.toml] at time 13:59:14.685195 duration_in_ms=102.998
2019-10-26 13:59:15,154 [salt.state       :1780][INFO    ][4532] Running state [containerd] at time 13:59:15.154859
2019-10-26 13:59:15,155 [salt.state       :1813][INFO    ][4532] Executing state service.running for [containerd]
2019-10-26 13:59:15,155 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'status', 'containerd.service', '-n', '0'] in directory '/root'
2019-10-26 13:59:15,171 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-10-26 13:59:15,183 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-enabled', 'containerd.service'] in directory '/root'
2019-10-26 13:59:15,195 [salt.state       :300 ][INFO    ][4532] The service containerd is already running
2019-10-26 13:59:15,196 [salt.state       :1951][INFO    ][4532] Completed state [containerd] at time 13:59:15.196146 duration_in_ms=41.287
2019-10-26 13:59:15,196 [salt.state       :1780][INFO    ][4532] Running state [containerd] at time 13:59:15.196445
2019-10-26 13:59:15,196 [salt.state       :1813][INFO    ][4532] Executing state service.mod_watch for [containerd]
2019-10-26 13:59:15,197 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-10-26 13:59:15,210 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'containerd.service'] in directory '/root'
2019-10-26 13:59:15,234 [salt.state       :300 ][INFO    ][4532] {'containerd': True}
2019-10-26 13:59:15,235 [salt.state       :1951][INFO    ][4532] Completed state [containerd] at time 13:59:15.234953 duration_in_ms=38.507
2019-10-26 13:59:15,235 [salt.state       :1780][INFO    ][4532] Running state [/etc/systemd/system/calico-node.service] at time 13:59:15.235940
2019-10-26 13:59:15,236 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/etc/systemd/system/calico-node.service]
2019-10-26 13:59:15,254 [salt.fileclient  :1219][INFO    ][4532] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calico-node.service.ctr'
2019-10-26 13:59:15,277 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:59:15,277 [salt.state       :1951][INFO    ][4532] Completed state [/etc/systemd/system/calico-node.service] at time 13:59:15.277755 duration_in_ms=41.814
2019-10-26 13:59:15,278 [salt.state       :1780][INFO    ][4532] Running state [/var/lib/calico] at time 13:59:15.278044
2019-10-26 13:59:15,278 [salt.state       :1813][INFO    ][4532] Executing state file.directory for [/var/lib/calico]
2019-10-26 13:59:15,279 [salt.state       :300 ][INFO    ][4532] {'/var/lib/calico': 'New Dir'}
2019-10-26 13:59:15,279 [salt.state       :1951][INFO    ][4532] Completed state [/var/lib/calico] at time 13:59:15.279664 duration_in_ms=1.62
2019-10-26 13:59:15,279 [salt.state       :1780][INFO    ][4532] Running state [/var/log/calico] at time 13:59:15.279887
2019-10-26 13:59:15,280 [salt.state       :1813][INFO    ][4532] Executing state file.directory for [/var/log/calico]
2019-10-26 13:59:15,281 [salt.state       :300 ][INFO    ][4532] {'/var/log/calico': 'New Dir'}
2019-10-26 13:59:15,281 [salt.state       :1951][INFO    ][4532] Completed state [/var/log/calico] at time 13:59:15.281439 duration_in_ms=1.552
2019-10-26 13:59:15,283 [salt.state       :1780][INFO    ][4532] Running state [calico-node] at time 13:59:15.283618
2019-10-26 13:59:15,283 [salt.state       :1813][INFO    ][4532] Executing state service.running for [calico-node]
2019-10-26 13:59:15,284 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'status', 'calico-node.service', '-n', '0'] in directory '/root'
2019-10-26 13:59:15,300 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:15,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:15,330 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:19,828 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:19,846 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:19,864 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:19,887 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:20,002 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:20,016 [salt.state       :300 ][INFO    ][4532] {'calico-node': True}
2019-10-26 13:59:20,017 [salt.state       :1951][INFO    ][4532] Completed state [calico-node] at time 13:59:20.017205 duration_in_ms=4733.586
2019-10-26 13:59:20,019 [salt.state       :1780][INFO    ][4532] Running state [curl] at time 13:59:20.019281
2019-10-26 13:59:20,019 [salt.state       :1813][INFO    ][4532] Executing state pkg.installed for [curl]
2019-10-26 13:59:20,250 [salt.state       :300 ][INFO    ][4532] All specified packages are already installed
2019-10-26 13:59:20,250 [salt.state       :1951][INFO    ][4532] Completed state [curl] at time 13:59:20.250398 duration_in_ms=231.117
2019-10-26 13:59:20,250 [salt.state       :1780][INFO    ][4532] Running state [git] at time 13:59:20.250719
2019-10-26 13:59:20,250 [salt.state       :1813][INFO    ][4532] Executing state pkg.installed for [git]
2019-10-26 13:59:20,260 [salt.state       :300 ][INFO    ][4532] All specified packages are already installed
2019-10-26 13:59:20,260 [salt.state       :1951][INFO    ][4532] Completed state [git] at time 13:59:20.260302 duration_in_ms=9.583
2019-10-26 13:59:20,260 [salt.state       :1780][INFO    ][4532] Running state [apt-transport-https] at time 13:59:20.260574
2019-10-26 13:59:20,260 [salt.state       :1813][INFO    ][4532] Executing state pkg.installed for [apt-transport-https]
2019-10-26 13:59:20,269 [salt.state       :300 ][INFO    ][4532] All specified packages are already installed
2019-10-26 13:59:20,269 [salt.state       :1951][INFO    ][4532] Completed state [apt-transport-https] at time 13:59:20.269847 duration_in_ms=9.271
2019-10-26 13:59:20,270 [salt.state       :1780][INFO    ][4532] Running state [python-apt] at time 13:59:20.270105
2019-10-26 13:59:20,270 [salt.state       :1813][INFO    ][4532] Executing state pkg.installed for [python-apt]
2019-10-26 13:59:20,279 [salt.state       :300 ][INFO    ][4532] All specified packages are already installed
2019-10-26 13:59:20,279 [salt.state       :1951][INFO    ][4532] Completed state [python-apt] at time 13:59:20.279225 duration_in_ms=9.12
2019-10-26 13:59:20,279 [salt.state       :1780][INFO    ][4532] Running state [socat] at time 13:59:20.279456
2019-10-26 13:59:20,279 [salt.state       :1813][INFO    ][4532] Executing state pkg.installed for [socat]
2019-10-26 13:59:20,305 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-10-26 13:59:20,334 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:22,673 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-26 13:59:22,707 [salt.state       :300 ][INFO    ][4532] Made the following changes:
'socat' changed from 'absent' to '1.7.3.1-1'

2019-10-26 13:59:22,722 [salt.state       :915 ][INFO    ][4532] Loading fresh modules for state activity
2019-10-26 13:59:22,763 [salt.state       :1951][INFO    ][4532] Completed state [socat] at time 13:59:22.763358 duration_in_ms=2483.902
2019-10-26 13:59:22,769 [salt.state       :1780][INFO    ][4532] Running state [openssl] at time 13:59:22.769318
2019-10-26 13:59:22,769 [salt.state       :1813][INFO    ][4532] Executing state pkg.installed for [openssl]
2019-10-26 13:59:23,446 [salt.state       :300 ][INFO    ][4532] All specified packages are already installed
2019-10-26 13:59:23,447 [salt.state       :1951][INFO    ][4532] Completed state [openssl] at time 13:59:23.447215 duration_in_ms=677.896
2019-10-26 13:59:23,447 [salt.state       :1780][INFO    ][4532] Running state [conntrack] at time 13:59:23.447510
2019-10-26 13:59:23,447 [salt.state       :1813][INFO    ][4532] Executing state pkg.installed for [conntrack]
2019-10-26 13:59:23,473 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-10-26 13:59:23,495 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:25,663 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-26 13:59:25,702 [salt.state       :300 ][INFO    ][4532] Made the following changes:
'conntrack' changed from 'absent' to '1:1.4.3-3'

2019-10-26 13:59:25,724 [salt.state       :915 ][INFO    ][4532] Loading fresh modules for state activity
2019-10-26 13:59:25,766 [salt.state       :1951][INFO    ][4532] Completed state [conntrack] at time 13:59:25.766608 duration_in_ms=2319.097
2019-10-26 13:59:25,770 [salt.state       :1780][INFO    ][4532] Running state [/usr/bin/hyperkube] at time 13:59:25.770308
2019-10-26 13:59:25,770 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/usr/bin/hyperkube]
2019-10-26 13:59:35,808 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:59:35,809 [salt.state       :1951][INFO    ][4532] Completed state [/usr/bin/hyperkube] at time 13:59:35.809667 duration_in_ms=10039.358
2019-10-26 13:59:35,811 [salt.state       :1780][INFO    ][4532] Running state [/usr/bin/kubectl] at time 13:59:35.810943
2019-10-26 13:59:35,811 [salt.state       :1813][INFO    ][4532] Executing state file.symlink for [/usr/bin/kubectl]
2019-10-26 13:59:35,821 [salt.state       :300 ][INFO    ][4532] {'new': '/usr/bin/kubectl'}
2019-10-26 13:59:35,834 [salt.state       :915 ][INFO    ][4532] Loading fresh modules for state activity
2019-10-26 13:59:35,885 [salt.state       :1951][INFO    ][4532] Completed state [/usr/bin/kubectl] at time 13:59:35.885774 duration_in_ms=74.83
2019-10-26 13:59:35,890 [salt.state       :1780][INFO    ][4532] Running state [/tmp/crictl] at time 13:59:35.890399
2019-10-26 13:59:35,891 [salt.state       :1813][INFO    ][4532] Executing state archive.extracted for [/tmp/crictl]
2019-10-26 13:59:36,609 [salt.minion      :1308][INFO    ][4445] User sudo_ubuntu Executing command saltutil.find_job with jid 20191026135936602751
2019-10-26 13:59:36,623 [salt.minion      :1432][INFO    ][6864] Starting a new job with PID 6864
2019-10-26 13:59:36,639 [salt.minion      :1711][INFO    ][6864] Returning information for job: 20191026135936602751
2019-10-26 13:59:38,118 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:38,372 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['tar', '--version'] in directory '/root'
2019-10-26 13:59:38,389 [salt.state       :300 ][INFO    ][4532] {'extracted_files': 'no tar output so far', 'directories_created': ['/tmp/crictl/']}
2019-10-26 13:59:38,390 [salt.state       :1951][INFO    ][4532] Completed state [/tmp/crictl] at time 13:59:38.389934 duration_in_ms=2499.531
2019-10-26 13:59:38,391 [salt.state       :1780][INFO    ][4532] Running state [/usr/local/bin/crictl] at time 13:59:38.391415
2019-10-26 13:59:38,392 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/usr/local/bin/crictl]
2019-10-26 13:59:38,392 [salt.loaded.int.states.file:1019][WARNING ][4532] Use of argument owner found, "owner" is invalid, please use "user"
2019-10-26 13:59:38,736 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:59:38,737 [salt.state       :1951][INFO    ][4532] Completed state [/usr/local/bin/crictl] at time 13:59:38.737754 duration_in_ms=346.342
2019-10-26 13:59:38,738 [salt.state       :1780][INFO    ][4532] Running state [/etc/crictl.yaml] at time 13:59:38.738224
2019-10-26 13:59:38,738 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/etc/crictl.yaml]
2019-10-26 13:59:38,741 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:59:38,741 [salt.state       :1951][INFO    ][4532] Completed state [/etc/crictl.yaml] at time 13:59:38.741218 duration_in_ms=2.993
2019-10-26 13:59:38,741 [salt.state       :1780][INFO    ][4532] Running state [/etc/criproxy] at time 13:59:38.741470
2019-10-26 13:59:38,741 [salt.state       :1813][INFO    ][4532] Executing state file.absent for [/etc/criproxy]
2019-10-26 13:59:38,742 [salt.state       :300 ][INFO    ][4532] File /etc/criproxy is not present
2019-10-26 13:59:38,742 [salt.state       :1951][INFO    ][4532] Completed state [/etc/criproxy] at time 13:59:38.742355 duration_in_ms=0.885
2019-10-26 13:59:39,264 [salt.state       :1780][INFO    ][4532] Running state [criproxy] at time 13:59:39.264515
2019-10-26 13:59:39,264 [salt.state       :1813][INFO    ][4532] Executing state service.dead for [criproxy]
2019-10-26 13:59:39,265 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'status', 'criproxy.service', '-n', '0'] in directory '/root'
2019-10-26 13:59:39,280 [salt.state       :300 ][INFO    ][4532] The named service criproxy is not available
2019-10-26 13:59:39,280 [salt.state       :1951][INFO    ][4532] Completed state [criproxy] at time 13:59:39.280353 duration_in_ms=15.839
2019-10-26 13:59:39,280 [salt.state       :1780][INFO    ][4532] Running state [/etc/systemd/system/kubelet.service] at time 13:59:39.280735
2019-10-26 13:59:39,281 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/etc/systemd/system/kubelet.service]
2019-10-26 13:59:39,296 [salt.fileclient  :1219][INFO    ][4532] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kubelet.service'
2019-10-26 13:59:39,345 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:39,491 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:39,633 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:59:39,634 [salt.state       :1951][INFO    ][4532] Completed state [/etc/systemd/system/kubelet.service] at time 13:59:39.634018 duration_in_ms=353.282
2019-10-26 13:59:39,634 [salt.state       :1780][INFO    ][4532] Running state [/etc/kubernetes/config] at time 13:59:39.634357
2019-10-26 13:59:39,634 [salt.state       :1813][INFO    ][4532] Executing state file.absent for [/etc/kubernetes/config]
2019-10-26 13:59:39,635 [salt.state       :300 ][INFO    ][4532] File /etc/kubernetes/config is not present
2019-10-26 13:59:39,635 [salt.state       :1951][INFO    ][4532] Completed state [/etc/kubernetes/config] at time 13:59:39.635342 duration_in_ms=0.985
2019-10-26 13:59:39,635 [salt.state       :1780][INFO    ][4532] Running state [/etc/default/kubelet] at time 13:59:39.635589
2019-10-26 13:59:39,635 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/etc/default/kubelet]
2019-10-26 13:59:39,652 [salt.fileclient  :1219][INFO    ][4532] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubelet/default.pool'
2019-10-26 13:59:39,736 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:39,912 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:40,057 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:40,193 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:40,322 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:40,454 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:40,598 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:59:40,599 [salt.state       :1951][INFO    ][4532] Completed state [/etc/default/kubelet] at time 13:59:40.599197 duration_in_ms=963.606
2019-10-26 13:59:40,599 [salt.state       :1780][INFO    ][4532] Running state [/etc/kubernetes/kubelet.kubeconfig] at time 13:59:40.599717
2019-10-26 13:59:40,600 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/etc/kubernetes/kubelet.kubeconfig]
2019-10-26 13:59:40,622 [salt.fileclient  :1219][INFO    ][4532] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubelet/kubelet.kubeconfig.pool'
2019-10-26 13:59:40,674 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:40,822 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:40,953 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:41,079 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:41,209 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:59:41,209 [salt.state       :1951][INFO    ][4532] Completed state [/etc/kubernetes/kubelet.kubeconfig] at time 13:59:41.209849 duration_in_ms=610.132
2019-10-26 13:59:41,210 [salt.state       :1780][INFO    ][4532] Running state [/etc/kubernetes/manifests] at time 13:59:41.210186
2019-10-26 13:59:41,210 [salt.state       :1813][INFO    ][4532] Executing state file.directory for [/etc/kubernetes/manifests]
2019-10-26 13:59:41,212 [salt.state       :300 ][INFO    ][4532] {'/etc/kubernetes/manifests': 'New Dir'}
2019-10-26 13:59:41,212 [salt.state       :1951][INFO    ][4532] Completed state [/etc/kubernetes/manifests] at time 13:59:41.212185 duration_in_ms=1.999
2019-10-26 13:59:41,214 [salt.state       :1780][INFO    ][4532] Running state [kubelet] at time 13:59:41.214284
2019-10-26 13:59:41,214 [salt.state       :1813][INFO    ][4532] Executing state service.running for [kubelet]
2019-10-26 13:59:41,215 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'status', 'kubelet.service', '-n', '0'] in directory '/root'
2019-10-26 13:59:41,231 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:41,244 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:41,260 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:41,277 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:41,290 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:41,308 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:41,330 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:41,442 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:41,457 [salt.state       :300 ][INFO    ][4532] {'kubelet': True}
2019-10-26 13:59:41,457 [salt.state       :1951][INFO    ][4532] Completed state [kubelet] at time 13:59:41.457445 duration_in_ms=243.161
2019-10-26 13:59:41,457 [salt.state       :1780][INFO    ][4532] Running state [/etc/logrotate.d/kubernetes] at time 13:59:41.457837
2019-10-26 13:59:41,458 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/etc/logrotate.d/kubernetes]
2019-10-26 13:59:41,478 [salt.fileclient  :1219][INFO    ][4532] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/logrotate'
2019-10-26 13:59:41,487 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:59:41,487 [salt.state       :1951][INFO    ][4532] Completed state [/etc/logrotate.d/kubernetes] at time 13:59:41.487694 duration_in_ms=29.856
2019-10-26 13:59:41,487 [salt.state       :1780][INFO    ][4532] Running state [/opt/cni/bin] at time 13:59:41.487938
2019-10-26 13:59:41,488 [salt.state       :1813][INFO    ][4532] Executing state archive.extracted for [/opt/cni/bin]
2019-10-26 13:59:43,826 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:44,422 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['tar', '--version'] in directory '/root'
2019-10-26 13:59:44,437 [salt.state       :300 ][INFO    ][4532] {'extracted_files': 'no tar output so far'}
2019-10-26 13:59:44,437 [salt.state       :1951][INFO    ][4532] Completed state [/opt/cni/bin] at time 13:59:44.437562 duration_in_ms=2949.622
2019-10-26 13:59:44,438 [salt.state       :1780][INFO    ][4532] Running state [/etc/kubernetes/proxy.kubeconfig] at time 13:59:44.437998
2019-10-26 13:59:44,438 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/etc/kubernetes/proxy.kubeconfig]
2019-10-26 13:59:44,461 [salt.fileclient  :1219][INFO    ][4532] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-proxy/proxy.kubeconfig'
2019-10-26 13:59:44,512 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:44,649 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:44,808 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:44,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] 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:45,056 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:59:45,057 [salt.state       :1951][INFO    ][4532] Completed state [/etc/kubernetes/proxy.kubeconfig] at time 13:59:45.056997 duration_in_ms=618.998
2019-10-26 13:59:45,057 [salt.state       :1780][INFO    ][4532] Running state [/etc/systemd/system/kube-proxy.service] at time 13:59:45.057326
2019-10-26 13:59:45,057 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/etc/systemd/system/kube-proxy.service]
2019-10-26 13:59:45,077 [salt.fileclient  :1219][INFO    ][4532] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-proxy.service'
2019-10-26 13:59:45,087 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:59:45,087 [salt.state       :1951][INFO    ][4532] Completed state [/etc/systemd/system/kube-proxy.service] at time 13:59:45.087338 duration_in_ms=30.011
2019-10-26 13:59:45,087 [salt.state       :1780][INFO    ][4532] Running state [/etc/default/kube-proxy] at time 13:59:45.087582
2019-10-26 13:59:45,087 [salt.state       :1813][INFO    ][4532] Executing state file.managed for [/etc/default/kube-proxy]
2019-10-26 13:59:45,089 [salt.state       :300 ][INFO    ][4532] File changed:
New file
2019-10-26 13:59:45,089 [salt.state       :1951][INFO    ][4532] Completed state [/etc/default/kube-proxy] at time 13:59:45.089926 duration_in_ms=2.344
2019-10-26 13:59:45,092 [salt.state       :1780][INFO    ][4532] Running state [kube-proxy] at time 13:59:45.092005
2019-10-26 13:59:45,092 [salt.state       :1813][INFO    ][4532] Executing state service.running for [kube-proxy]
2019-10-26 13:59:45,092 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'status', 'kube-proxy.service', '-n', '0'] in directory '/root'
2019-10-26 13:59:45,108 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:45,121 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:45,137 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:45,156 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:45,175 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:45,193 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:45,216 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:45,318 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4532] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:45,332 [salt.state       :300 ][INFO    ][4532] {'kube-proxy': True}
2019-10-26 13:59:45,332 [salt.state       :1951][INFO    ][4532] Completed state [kube-proxy] at time 13:59:45.332764 duration_in_ms=240.758
2019-10-26 13:59:45,335 [salt.minion      :1711][INFO    ][4532] Returning information for job: 20191026135851446315
2019-10-26 13:59:47,246 [salt.minion      :1308][INFO    ][4445] User sudo_ubuntu Executing command cmd.run with jid 20191026135947236400
2019-10-26 13:59:47,265 [salt.minion      :1432][INFO    ][7399] Starting a new job with PID 7399
2019-10-26 13:59:47,277 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][7399] Executing command 'calicoctl node status' in directory '/root'
2019-10-26 13:59:47,334 [salt.minion      :1711][INFO    ][7399] Returning information for job: 20191026135947236400
2019-10-26 13:59:47,964 [salt.minion      :1308][INFO    ][4445] User sudo_ubuntu Executing command cmd.run with jid 20191026135947953882
2019-10-26 13:59:47,985 [salt.minion      :1432][INFO    ][7417] Starting a new job with PID 7417
2019-10-26 13:59:47,994 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][7417] Executing command 'calicoctl get ippool' in directory '/root'
2019-10-26 13:59:48,098 [salt.minion      :1711][INFO    ][7417] Returning information for job: 20191026135947953882
2019-10-26 14:00:51,457 [salt.minion      :1308][INFO    ][4445] User sudo_ubuntu Executing command cp.push_dir with jid 20191026140051450558
2019-10-26 14:00:51,473 [salt.minion      :1432][INFO    ][7556] Starting a new job with PID 7556
2019-10-26 14:00:51,864 [salt.minion      :1711][INFO    ][7556] Returning information for job: 20191026140051450558
2019-10-26 14:03:47,246 [salt.minion      :1308][INFO    ][4445] User sudo_ubuntu Executing command cp.push_dir with jid 20191026140347233165
2019-10-26 14:03:47,266 [salt.minion      :1432][INFO    ][8234] Starting a new job with PID 8234
