2019-05-18 16:28:33,084 [salt.utils       :831 ][ERROR   ][1656] DNS lookup or connection check of 'salt' failed.
2019-05-18 16:28:33,084 [salt.minion      :152 ][ERROR   ][1656] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-05-18 16:28:34,670 [salt.utils.parsers:1051][WARNING ][1656] Minion received a SIGTERM. Exiting.
2019-05-18 16:29:16,768 [salt.minion      :870 ][ERROR   ][1853] Error while bringing up minion for multi-master. Is master at 192.168.11.2 responding?
2019-05-18 16:34:24,930 [salt.utils.decorators:613 ][WARNING ][2083] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 16:34:39,194 [salt.loaded.int.states.file:2298][WARNING ][2083] 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-05-18 16:34:49,833 [salt.utils.decorators:613 ][WARNING ][2083] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 16:34:49,949 [salt.loaded.int.states.file:2298][WARNING ][2083] 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-05-18 16:34:49,951 [salt.loaded.int.states.file:2298][WARNING ][2083] 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-05-18 16:34:49,953 [salt.loaded.int.states.file:2298][WARNING ][2083] 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-05-18 16:34:49,955 [salt.loaded.int.states.file:2298][WARNING ][2083] 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-05-18 16:34:49,956 [salt.loaded.int.states.file:2298][WARNING ][2083] 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-05-18 16:34:49,958 [salt.loaded.int.states.file:2298][WARNING ][2083] 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-05-18 16:34:49,960 [salt.loaded.int.states.file:2298][WARNING ][2083] 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-05-18 16:34:49,961 [salt.loaded.int.states.file:2298][WARNING ][2083] 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-05-18 16:34:58,855 [salt.loaded.int.module.debian_ip:1964][WARNING ][2083] The network state sls is requiring a reboot of the system to properly apply network configuration.
2019-05-18 16:35:00,138 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][2083] Command '['umount', '/dev/shm']' failed with return code: 32
2019-05-18 16:35:00,139 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][2083] 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-05-18 16:35:00,141 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][2083] retcode: 32
2019-05-18 16:35:01,399 [salt.utils.parsers:1051][WARNING ][1853] Minion received a SIGTERM. Exiting.
2019-05-18 16:36:19,827 [salt.utils.decorators:613 ][WARNING ][3080] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 16:36:23,220 [salt.loaded.int.states.file:2298][WARNING ][3080] 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-05-18 16:36:26,029 [salt.loaded.int.states.file:2298][WARNING ][3080] 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-05-18 16:36:39,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4903] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-18 16:36:39,984 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4903] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-18 16:36:39,998 [salt.utils.parsers:1051][WARNING ][2469] Minion received a SIGTERM. Exiting.
2019-05-18 16:36:40,899 [salt.cli.daemons :293 ][INFO    ][4956] Setting up the Salt Minion "cmp001.mcp-k8s-calico-noha.local"
2019-05-18 16:36:41,003 [salt.cli.daemons :82  ][INFO    ][4956] Starting up the Salt Minion
2019-05-18 16:36:41,004 [salt.utils.event :1017][INFO    ][4956] Starting pull socket on /var/run/salt/minion/minion_event_23b3bf79fe_pull.ipc
2019-05-18 16:36:41,600 [salt.minion      :976 ][INFO    ][4956] Creating minion process manager
2019-05-18 16:36:43,028 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][4956] Executing command ['date', '+%z'] in directory '/root'
2019-05-18 16:36:43,044 [salt.utils.schedule:568 ][INFO    ][4956] Updating job settings for scheduled job: __mine_interval
2019-05-18 16:36:43,049 [salt.minion      :1108][INFO    ][4956] Added mine.update to scheduler
2019-05-18 16:36:43,054 [salt.minion      :1975][INFO    ][4956] Minion is starting as user 'root'
2019-05-18 16:36:43,066 [salt.minion      :2336][INFO    ][4956] Minion is ready to receive requests!
2019-05-18 16:37:18,886 [salt.minion      :1308][INFO    ][4956] User sudo_ubuntu Executing command state.sls with jid 20190518163718874370
2019-05-18 16:37:18,904 [salt.minion      :1432][INFO    ][5044] Starting a new job with PID 5044
2019-05-18 16:37:19,973 [salt.state       :915 ][INFO    ][5044] Loading fresh modules for state activity
2019-05-18 16:37:20,028 [salt.fileclient  :1219][INFO    ][5044] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/init.sls'
2019-05-18 16:37:20,088 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:20,101 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:20,118 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:20,129 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:20,164 [salt.fileclient  :1219][INFO    ][5044] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/calico.sls'
2019-05-18 16:37:20,225 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:20,242 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:20,254 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:20,267 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:20,329 [salt.fileclient  :1219][INFO    ][5044] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/service.sls'
2019-05-18 16:37:20,374 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:20,392 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:20,405 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:20,419 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:20,451 [salt.fileclient  :1219][INFO    ][5044] Fetching file from saltenv 'base', ** done ** 'kubernetes/_common.sls'
2019-05-18 16:37:20,533 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:20,550 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:20,613 [salt.fileclient  :1219][INFO    ][5044] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/kube-proxy.sls'
2019-05-18 16:37:20,666 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:20,683 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:20,719 [salt.state       :1780][INFO    ][5044] Running state [/usr/bin/calicoctl] at time 16:37:20.719847
2019-05-18 16:37:20,720 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/usr/bin/calicoctl]
2019-05-18 16:37:29,137 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:37:29,137 [salt.state       :1951][INFO    ][5044] Completed state [/usr/bin/calicoctl] at time 16:37:29.137544 duration_in_ms=8417.697
2019-05-18 16:37:29,137 [salt.state       :1780][INFO    ][5044] Running state [/usr/bin/birdcl] at time 16:37:29.137825
2019-05-18 16:37:29,138 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/usr/bin/birdcl]
2019-05-18 16:37:29,519 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:37:29,519 [salt.state       :1951][INFO    ][5044] Completed state [/usr/bin/birdcl] at time 16:37:29.519752 duration_in_ms=381.925
2019-05-18 16:37:29,520 [salt.state       :1780][INFO    ][5044] Running state [/opt/cni/bin/calico] at time 16:37:29.520124
2019-05-18 16:37:29,520 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/opt/cni/bin/calico]
2019-05-18 16:37:33,944 [salt.minion      :1308][INFO    ][4956] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518163733934997
2019-05-18 16:37:33,960 [salt.minion      :1432][INFO    ][5119] Starting a new job with PID 5119
2019-05-18 16:37:33,977 [salt.minion      :1711][INFO    ][5119] Returning information for job: 20190518163733934997
2019-05-18 16:37:37,102 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:37:37,102 [salt.state       :1951][INFO    ][5044] Completed state [/opt/cni/bin/calico] at time 16:37:37.102654 duration_in_ms=7582.527
2019-05-18 16:37:37,103 [salt.state       :1780][INFO    ][5044] Running state [/opt/cni/bin/calico-ipam] at time 16:37:37.103173
2019-05-18 16:37:37,104 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/opt/cni/bin/calico-ipam]
2019-05-18 16:37:44,421 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:37:44,421 [salt.state       :1951][INFO    ][5044] Completed state [/opt/cni/bin/calico-ipam] at time 16:37:44.421530 duration_in_ms=7318.355
2019-05-18 16:37:44,421 [salt.state       :1780][INFO    ][5044] Running state [/etc/cni/net.d/10-calico.conf] at time 16:37:44.421931
2019-05-18 16:37:44,422 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/etc/cni/net.d/10-calico.conf]
2019-05-18 16:37:44,440 [salt.fileclient  :1219][INFO    ][5044] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calico.conf'
2019-05-18 16:37:44,494 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:44,507 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:44,524 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:44,538 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:44,553 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:37:44,553 [salt.state       :1951][INFO    ][5044] Completed state [/etc/cni/net.d/10-calico.conf] at time 16:37:44.553877 duration_in_ms=131.946
2019-05-18 16:37:44,554 [salt.state       :1780][INFO    ][5044] Running state [/etc/calico/network-environment] at time 16:37:44.554164
2019-05-18 16:37:44,554 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/etc/calico/network-environment]
2019-05-18 16:37:44,572 [salt.fileclient  :1219][INFO    ][5044] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/network-environment.pool'
2019-05-18 16:37:44,617 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:44,629 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:44,646 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:37:44,647 [salt.state       :1951][INFO    ][5044] Completed state [/etc/calico/network-environment] at time 16:37:44.647078 duration_in_ms=92.914
2019-05-18 16:37:44,647 [salt.state       :1780][INFO    ][5044] Running state [/etc/calico/calicoctl.cfg] at time 16:37:44.647482
2019-05-18 16:37:44,647 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/etc/calico/calicoctl.cfg]
2019-05-18 16:37:44,666 [salt.fileclient  :1219][INFO    ][5044] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calicoctl.cfg.pool'
2019-05-18 16:37:44,714 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:44,724 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:44,736 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:37:44,737 [salt.state       :1951][INFO    ][5044] Completed state [/etc/calico/calicoctl.cfg] at time 16:37:44.737261 duration_in_ms=89.779
2019-05-18 16:37:45,366 [salt.state       :1780][INFO    ][5044] Running state [containerd=1.2.5-3~u16.04+mcp] at time 16:37:45.366710
2019-05-18 16:37:45,367 [salt.state       :1813][INFO    ][5044] Executing state pkg.installed for [containerd=1.2.5-3~u16.04+mcp]
2019-05-18 16:37:45,368 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-18 16:37:45,732 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['apt-cache', '-q', 'policy', 'containerd=1.2.5-3~u16.04+mcp'] in directory '/root'
2019-05-18 16:37:45,852 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-05-18 16:37:48,146 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-05-18 16:37:48,168 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:56,455 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-18 16:37:56,487 [salt.state       :300 ][INFO    ][5044] 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-2~u16.04+mcp'

2019-05-18 16:37:56,503 [salt.state       :915 ][INFO    ][5044] Loading fresh modules for state activity
2019-05-18 16:37:56,544 [salt.state       :1951][INFO    ][5044] Completed state [containerd=1.2.5-3~u16.04+mcp] at time 16:37:56.543965 duration_in_ms=11177.256
2019-05-18 16:37:56,547 [salt.state       :1780][INFO    ][5044] Running state [/etc/containerd/config.toml] at time 16:37:56.547653
2019-05-18 16:37:56,547 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/etc/containerd/config.toml]
2019-05-18 16:37:56,568 [salt.fileclient  :1219][INFO    ][5044] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/containerd/config.toml'
2019-05-18 16:37:56,611 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:56,623 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:37:56,642 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:37:56,642 [salt.state       :1951][INFO    ][5044] Completed state [/etc/containerd/config.toml] at time 16:37:56.642717 duration_in_ms=95.064
2019-05-18 16:37:57,088 [salt.state       :1780][INFO    ][5044] Running state [containerd] at time 16:37:57.088829
2019-05-18 16:37:57,089 [salt.state       :1813][INFO    ][5044] Executing state service.running for [containerd]
2019-05-18 16:37:57,089 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'status', 'containerd.service', '-n', '0'] in directory '/root'
2019-05-18 16:37:57,107 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-05-18 16:37:57,123 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-enabled', 'containerd.service'] in directory '/root'
2019-05-18 16:37:57,138 [salt.state       :300 ][INFO    ][5044] The service containerd is already running
2019-05-18 16:37:57,139 [salt.state       :1951][INFO    ][5044] Completed state [containerd] at time 16:37:57.139237 duration_in_ms=50.408
2019-05-18 16:37:57,139 [salt.state       :1780][INFO    ][5044] Running state [containerd] at time 16:37:57.139678
2019-05-18 16:37:57,140 [salt.state       :1813][INFO    ][5044] Executing state service.mod_watch for [containerd]
2019-05-18 16:37:57,141 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-05-18 16:37:57,158 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'containerd.service'] in directory '/root'
2019-05-18 16:37:57,193 [salt.state       :300 ][INFO    ][5044] {'containerd': True}
2019-05-18 16:37:57,193 [salt.state       :1951][INFO    ][5044] Completed state [containerd] at time 16:37:57.193607 duration_in_ms=53.929
2019-05-18 16:37:57,194 [salt.state       :1780][INFO    ][5044] Running state [/etc/systemd/system/calico-node.service] at time 16:37:57.194594
2019-05-18 16:37:57,195 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/etc/systemd/system/calico-node.service]
2019-05-18 16:37:57,214 [salt.fileclient  :1219][INFO    ][5044] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calico-node.service.ctr'
2019-05-18 16:37:57,248 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:37:57,248 [salt.state       :1951][INFO    ][5044] Completed state [/etc/systemd/system/calico-node.service] at time 16:37:57.248415 duration_in_ms=53.821
2019-05-18 16:37:57,248 [salt.state       :1780][INFO    ][5044] Running state [/var/lib/calico] at time 16:37:57.248689
2019-05-18 16:37:57,248 [salt.state       :1813][INFO    ][5044] Executing state file.directory for [/var/lib/calico]
2019-05-18 16:37:57,250 [salt.state       :300 ][INFO    ][5044] {'/var/lib/calico': 'New Dir'}
2019-05-18 16:37:57,250 [salt.state       :1951][INFO    ][5044] Completed state [/var/lib/calico] at time 16:37:57.250340 duration_in_ms=1.651
2019-05-18 16:37:57,250 [salt.state       :1780][INFO    ][5044] Running state [/var/log/calico] at time 16:37:57.250562
2019-05-18 16:37:57,250 [salt.state       :1813][INFO    ][5044] Executing state file.directory for [/var/log/calico]
2019-05-18 16:37:57,251 [salt.state       :300 ][INFO    ][5044] {'/var/log/calico': 'New Dir'}
2019-05-18 16:37:57,252 [salt.state       :1951][INFO    ][5044] Completed state [/var/log/calico] at time 16:37:57.252079 duration_in_ms=1.517
2019-05-18 16:37:57,254 [salt.state       :1780][INFO    ][5044] Running state [calico-node] at time 16:37:57.254288
2019-05-18 16:37:57,254 [salt.state       :1813][INFO    ][5044] Executing state service.running for [calico-node]
2019-05-18 16:37:57,255 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'status', 'calico-node.service', '-n', '0'] in directory '/root'
2019-05-18 16:37:57,274 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-05-18 16:37:57,290 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-05-18 16:37:57,314 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'calico-node.service'] in directory '/root'
2019-05-18 16:38:04,169 [salt.minion      :1308][INFO    ][4956] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518163804157635
2019-05-18 16:38:04,190 [salt.minion      :1432][INFO    ][6223] Starting a new job with PID 6223
2019-05-18 16:38:04,218 [salt.minion      :1711][INFO    ][6223] Returning information for job: 20190518163804157635
2019-05-18 16:38:05,328 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-05-18 16:38:05,341 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-05-18 16:38:05,356 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-05-18 16:38:05,376 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'calico-node.service'] in directory '/root'
2019-05-18 16:38:05,479 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-05-18 16:38:05,492 [salt.state       :300 ][INFO    ][5044] {'calico-node': True}
2019-05-18 16:38:05,493 [salt.state       :1951][INFO    ][5044] Completed state [calico-node] at time 16:38:05.493075 duration_in_ms=8238.787
2019-05-18 16:38:05,495 [salt.state       :1780][INFO    ][5044] Running state [curl] at time 16:38:05.495139
2019-05-18 16:38:05,495 [salt.state       :1813][INFO    ][5044] Executing state pkg.installed for [curl]
2019-05-18 16:38:05,718 [salt.state       :300 ][INFO    ][5044] All specified packages are already installed
2019-05-18 16:38:05,719 [salt.state       :1951][INFO    ][5044] Completed state [curl] at time 16:38:05.719189 duration_in_ms=224.05
2019-05-18 16:38:05,719 [salt.state       :1780][INFO    ][5044] Running state [git] at time 16:38:05.719473
2019-05-18 16:38:05,719 [salt.state       :1813][INFO    ][5044] Executing state pkg.installed for [git]
2019-05-18 16:38:05,728 [salt.state       :300 ][INFO    ][5044] All specified packages are already installed
2019-05-18 16:38:05,729 [salt.state       :1951][INFO    ][5044] Completed state [git] at time 16:38:05.728983 duration_in_ms=9.51
2019-05-18 16:38:05,729 [salt.state       :1780][INFO    ][5044] Running state [apt-transport-https] at time 16:38:05.729210
2019-05-18 16:38:05,729 [salt.state       :1813][INFO    ][5044] Executing state pkg.installed for [apt-transport-https]
2019-05-18 16:38:05,738 [salt.state       :300 ][INFO    ][5044] All specified packages are already installed
2019-05-18 16:38:05,738 [salt.state       :1951][INFO    ][5044] Completed state [apt-transport-https] at time 16:38:05.738548 duration_in_ms=9.339
2019-05-18 16:38:05,738 [salt.state       :1780][INFO    ][5044] Running state [python-apt] at time 16:38:05.738782
2019-05-18 16:38:05,739 [salt.state       :1813][INFO    ][5044] Executing state pkg.installed for [python-apt]
2019-05-18 16:38:05,747 [salt.state       :300 ][INFO    ][5044] All specified packages are already installed
2019-05-18 16:38:05,748 [salt.state       :1951][INFO    ][5044] Completed state [python-apt] at time 16:38:05.748009 duration_in_ms=9.226
2019-05-18 16:38:05,748 [salt.state       :1780][INFO    ][5044] Running state [socat] at time 16:38:05.748233
2019-05-18 16:38:05,748 [salt.state       :1813][INFO    ][5044] Executing state pkg.installed for [socat]
2019-05-18 16:38:05,774 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-05-18 16:38:05,799 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:38:08,031 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-18 16:38:08,067 [salt.state       :300 ][INFO    ][5044] Made the following changes:
'socat' changed from 'absent' to '1.7.3.1-1'

2019-05-18 16:38:08,081 [salt.state       :915 ][INFO    ][5044] Loading fresh modules for state activity
2019-05-18 16:38:08,122 [salt.state       :1951][INFO    ][5044] Completed state [socat] at time 16:38:08.122596 duration_in_ms=2374.362
2019-05-18 16:38:08,128 [salt.state       :1780][INFO    ][5044] Running state [openssl] at time 16:38:08.128334
2019-05-18 16:38:08,128 [salt.state       :1813][INFO    ][5044] Executing state pkg.installed for [openssl]
2019-05-18 16:38:08,783 [salt.state       :300 ][INFO    ][5044] All specified packages are already installed
2019-05-18 16:38:08,783 [salt.state       :1951][INFO    ][5044] Completed state [openssl] at time 16:38:08.783865 duration_in_ms=655.53
2019-05-18 16:38:08,784 [salt.state       :1780][INFO    ][5044] Running state [conntrack] at time 16:38:08.784152
2019-05-18 16:38:08,784 [salt.state       :1813][INFO    ][5044] Executing state pkg.installed for [conntrack]
2019-05-18 16:38:08,810 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-05-18 16:38:08,829 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:38:10,897 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-18 16:38:10,937 [salt.state       :300 ][INFO    ][5044] Made the following changes:
'conntrack' changed from 'absent' to '1:1.4.3-3'

2019-05-18 16:38:10,951 [salt.state       :915 ][INFO    ][5044] Loading fresh modules for state activity
2019-05-18 16:38:10,990 [salt.state       :1951][INFO    ][5044] Completed state [conntrack] at time 16:38:10.990091 duration_in_ms=2205.937
2019-05-18 16:38:10,993 [salt.state       :1780][INFO    ][5044] Running state [/usr/bin/hyperkube] at time 16:38:10.993610
2019-05-18 16:38:10,993 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/usr/bin/hyperkube]
2019-05-18 16:38:34,247 [salt.minion      :1308][INFO    ][4956] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518163834237263
2019-05-18 16:38:34,264 [salt.minion      :1432][INFO    ][7323] Starting a new job with PID 7323
2019-05-18 16:38:34,282 [salt.minion      :1711][INFO    ][7323] Returning information for job: 20190518163834237263
2019-05-18 16:39:02,543 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:39:02,543 [salt.state       :1951][INFO    ][5044] Completed state [/usr/bin/hyperkube] at time 16:39:02.543649 duration_in_ms=51550.036
2019-05-18 16:39:02,544 [salt.state       :1780][INFO    ][5044] Running state [/usr/bin/kubectl] at time 16:39:02.544835
2019-05-18 16:39:02,545 [salt.state       :1813][INFO    ][5044] Executing state file.symlink for [/usr/bin/kubectl]
2019-05-18 16:39:02,554 [salt.state       :300 ][INFO    ][5044] {'new': '/usr/bin/kubectl'}
2019-05-18 16:39:02,566 [salt.state       :915 ][INFO    ][5044] Loading fresh modules for state activity
2019-05-18 16:39:02,604 [salt.state       :1951][INFO    ][5044] Completed state [/usr/bin/kubectl] at time 16:39:02.604274 duration_in_ms=59.438
2019-05-18 16:39:02,607 [salt.state       :1780][INFO    ][5044] Running state [/tmp/crictl] at time 16:39:02.607394
2019-05-18 16:39:02,607 [salt.state       :1813][INFO    ][5044] Executing state archive.extracted for [/tmp/crictl]
2019-05-18 16:39:04,309 [salt.minion      :1308][INFO    ][4956] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518163904299050
2019-05-18 16:39:04,325 [salt.minion      :1432][INFO    ][7393] Starting a new job with PID 7393
2019-05-18 16:39:04,340 [salt.minion      :1711][INFO    ][7393] Returning information for job: 20190518163904299050
2019-05-18 16:39:04,616 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:04,838 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['tar', '--version'] in directory '/root'
2019-05-18 16:39:04,850 [salt.state       :300 ][INFO    ][5044] {'extracted_files': 'no tar output so far', 'directories_created': ['/tmp/crictl/']}
2019-05-18 16:39:04,850 [salt.state       :1951][INFO    ][5044] Completed state [/tmp/crictl] at time 16:39:04.850520 duration_in_ms=2243.121
2019-05-18 16:39:04,851 [salt.state       :1780][INFO    ][5044] Running state [/usr/local/bin/crictl] at time 16:39:04.851580
2019-05-18 16:39:04,852 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/usr/local/bin/crictl]
2019-05-18 16:39:04,852 [salt.loaded.int.states.file:1019][WARNING ][5044] Use of argument owner found, "owner" is invalid, please use "user"
2019-05-18 16:39:05,242 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:39:05,242 [salt.state       :1951][INFO    ][5044] Completed state [/usr/local/bin/crictl] at time 16:39:05.242621 duration_in_ms=391.042
2019-05-18 16:39:05,242 [salt.state       :1780][INFO    ][5044] Running state [/etc/crictl.yaml] at time 16:39:05.242896
2019-05-18 16:39:05,243 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/etc/crictl.yaml]
2019-05-18 16:39:05,273 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:39:05,274 [salt.state       :1951][INFO    ][5044] Completed state [/etc/crictl.yaml] at time 16:39:05.273947 duration_in_ms=31.05
2019-05-18 16:39:05,274 [salt.state       :1780][INFO    ][5044] Running state [/etc/criproxy] at time 16:39:05.274365
2019-05-18 16:39:05,274 [salt.state       :1813][INFO    ][5044] Executing state file.absent for [/etc/criproxy]
2019-05-18 16:39:05,275 [salt.state       :300 ][INFO    ][5044] File /etc/criproxy is not present
2019-05-18 16:39:05,275 [salt.state       :1951][INFO    ][5044] Completed state [/etc/criproxy] at time 16:39:05.275788 duration_in_ms=1.424
2019-05-18 16:39:05,811 [salt.state       :1780][INFO    ][5044] Running state [criproxy] at time 16:39:05.811085
2019-05-18 16:39:05,811 [salt.state       :1813][INFO    ][5044] Executing state service.dead for [criproxy]
2019-05-18 16:39:05,812 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'status', 'criproxy.service', '-n', '0'] in directory '/root'
2019-05-18 16:39:05,825 [salt.state       :300 ][INFO    ][5044] The named service criproxy is not available
2019-05-18 16:39:05,825 [salt.state       :1951][INFO    ][5044] Completed state [criproxy] at time 16:39:05.825774 duration_in_ms=14.69
2019-05-18 16:39:05,826 [salt.state       :1780][INFO    ][5044] Running state [/etc/systemd/system/kubelet.service] at time 16:39:05.826119
2019-05-18 16:39:05,826 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/etc/systemd/system/kubelet.service]
2019-05-18 16:39:05,844 [salt.fileclient  :1219][INFO    ][5044] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kubelet.service'
2019-05-18 16:39:05,890 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:06,037 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:06,187 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:39:06,188 [salt.state       :1951][INFO    ][5044] Completed state [/etc/systemd/system/kubelet.service] at time 16:39:06.188361 duration_in_ms=362.241
2019-05-18 16:39:06,188 [salt.state       :1780][INFO    ][5044] Running state [/etc/kubernetes/config] at time 16:39:06.188834
2019-05-18 16:39:06,189 [salt.state       :1813][INFO    ][5044] Executing state file.absent for [/etc/kubernetes/config]
2019-05-18 16:39:06,189 [salt.state       :300 ][INFO    ][5044] File /etc/kubernetes/config is not present
2019-05-18 16:39:06,189 [salt.state       :1951][INFO    ][5044] Completed state [/etc/kubernetes/config] at time 16:39:06.189890 duration_in_ms=1.056
2019-05-18 16:39:06,190 [salt.state       :1780][INFO    ][5044] Running state [/etc/default/kubelet] at time 16:39:06.190131
2019-05-18 16:39:06,190 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/etc/default/kubelet]
2019-05-18 16:39:06,208 [salt.fileclient  :1219][INFO    ][5044] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubelet/default.pool'
2019-05-18 16:39:06,290 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:06,424 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:06,557 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:06,701 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:06,851 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:07,006 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:07,162 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:39:07,163 [salt.state       :1951][INFO    ][5044] Completed state [/etc/default/kubelet] at time 16:39:07.163433 duration_in_ms=973.3
2019-05-18 16:39:07,163 [salt.state       :1780][INFO    ][5044] Running state [/etc/kubernetes/kubelet.kubeconfig] at time 16:39:07.163927
2019-05-18 16:39:07,164 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/etc/kubernetes/kubelet.kubeconfig]
2019-05-18 16:39:07,184 [salt.fileclient  :1219][INFO    ][5044] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubelet/kubelet.kubeconfig.pool'
2019-05-18 16:39:07,235 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:07,372 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:07,515 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:07,661 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:07,813 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:39:07,814 [salt.state       :1951][INFO    ][5044] Completed state [/etc/kubernetes/kubelet.kubeconfig] at time 16:39:07.813961 duration_in_ms=650.033
2019-05-18 16:39:07,814 [salt.state       :1780][INFO    ][5044] Running state [/etc/kubernetes/manifests] at time 16:39:07.814290
2019-05-18 16:39:07,814 [salt.state       :1813][INFO    ][5044] Executing state file.directory for [/etc/kubernetes/manifests]
2019-05-18 16:39:07,816 [salt.state       :300 ][INFO    ][5044] {'/etc/kubernetes/manifests': 'New Dir'}
2019-05-18 16:39:07,816 [salt.state       :1951][INFO    ][5044] Completed state [/etc/kubernetes/manifests] at time 16:39:07.816234 duration_in_ms=1.944
2019-05-18 16:39:07,818 [salt.state       :1780][INFO    ][5044] Running state [kubelet] at time 16:39:07.818409
2019-05-18 16:39:07,818 [salt.state       :1813][INFO    ][5044] Executing state service.running for [kubelet]
2019-05-18 16:39:07,819 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'status', 'kubelet.service', '-n', '0'] in directory '/root'
2019-05-18 16:39:07,840 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-05-18 16:39:07,854 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-05-18 16:39:07,883 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kubelet.service'] in directory '/root'
2019-05-18 16:39:07,910 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-05-18 16:39:07,923 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-05-18 16:39:07,942 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-05-18 16:39:07,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kubelet.service'] in directory '/root'
2019-05-18 16:39:08,060 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-05-18 16:39:08,073 [salt.state       :300 ][INFO    ][5044] {'kubelet': True}
2019-05-18 16:39:08,074 [salt.state       :1951][INFO    ][5044] Completed state [kubelet] at time 16:39:08.074092 duration_in_ms=255.683
2019-05-18 16:39:08,074 [salt.state       :1780][INFO    ][5044] Running state [/etc/logrotate.d/kubernetes] at time 16:39:08.074446
2019-05-18 16:39:08,074 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/etc/logrotate.d/kubernetes]
2019-05-18 16:39:08,091 [salt.fileclient  :1219][INFO    ][5044] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/logrotate'
2019-05-18 16:39:08,100 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:39:08,100 [salt.state       :1951][INFO    ][5044] Completed state [/etc/logrotate.d/kubernetes] at time 16:39:08.100893 duration_in_ms=26.447
2019-05-18 16:39:08,101 [salt.state       :1780][INFO    ][5044] Running state [/opt/cni/bin] at time 16:39:08.101160
2019-05-18 16:39:08,101 [salt.state       :1813][INFO    ][5044] Executing state archive.extracted for [/opt/cni/bin]
2019-05-18 16:39:12,277 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:12,965 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['tar', '--version'] in directory '/root'
2019-05-18 16:39:12,982 [salt.state       :300 ][INFO    ][5044] {'extracted_files': 'no tar output so far'}
2019-05-18 16:39:12,983 [salt.state       :1951][INFO    ][5044] Completed state [/opt/cni/bin] at time 16:39:12.982906 duration_in_ms=4881.739
2019-05-18 16:39:12,983 [salt.state       :1780][INFO    ][5044] Running state [/etc/kubernetes/proxy.kubeconfig] at time 16:39:12.983421
2019-05-18 16:39:12,983 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/etc/kubernetes/proxy.kubeconfig]
2019-05-18 16:39:13,007 [salt.fileclient  :1219][INFO    ][5044] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-proxy/proxy.kubeconfig'
2019-05-18 16:39:13,060 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:13,198 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:13,351 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:13,488 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] 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-05-18 16:39:13,619 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:39:13,620 [salt.state       :1951][INFO    ][5044] Completed state [/etc/kubernetes/proxy.kubeconfig] at time 16:39:13.620463 duration_in_ms=637.041
2019-05-18 16:39:13,620 [salt.state       :1780][INFO    ][5044] Running state [/etc/systemd/system/kube-proxy.service] at time 16:39:13.620893
2019-05-18 16:39:13,621 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/etc/systemd/system/kube-proxy.service]
2019-05-18 16:39:13,644 [salt.fileclient  :1219][INFO    ][5044] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-proxy.service'
2019-05-18 16:39:13,655 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:39:13,655 [salt.state       :1951][INFO    ][5044] Completed state [/etc/systemd/system/kube-proxy.service] at time 16:39:13.655826 duration_in_ms=34.933
2019-05-18 16:39:13,656 [salt.state       :1780][INFO    ][5044] Running state [/etc/default/kube-proxy] at time 16:39:13.656064
2019-05-18 16:39:13,656 [salt.state       :1813][INFO    ][5044] Executing state file.managed for [/etc/default/kube-proxy]
2019-05-18 16:39:13,658 [salt.state       :300 ][INFO    ][5044] File changed:
New file
2019-05-18 16:39:13,658 [salt.state       :1951][INFO    ][5044] Completed state [/etc/default/kube-proxy] at time 16:39:13.658259 duration_in_ms=2.195
2019-05-18 16:39:13,659 [salt.state       :1780][INFO    ][5044] Running state [kube-proxy] at time 16:39:13.659904
2019-05-18 16:39:13,660 [salt.state       :1813][INFO    ][5044] Executing state service.running for [kube-proxy]
2019-05-18 16:39:13,660 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'status', 'kube-proxy.service', '-n', '0'] in directory '/root'
2019-05-18 16:39:13,679 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:39:13,697 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:39:13,715 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:39:13,737 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:39:13,750 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:39:13,768 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:39:13,792 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:39:13,890 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:39:13,909 [salt.state       :300 ][INFO    ][5044] {'kube-proxy': True}
2019-05-18 16:39:13,909 [salt.state       :1951][INFO    ][5044] Completed state [kube-proxy] at time 16:39:13.909889 duration_in_ms=249.985
2019-05-18 16:39:13,913 [salt.minion      :1711][INFO    ][5044] Returning information for job: 20190518163718874370
2019-05-18 16:39:14,667 [salt.minion      :1308][INFO    ][4956] User sudo_ubuntu Executing command cmd.run with jid 20190518163914657623
2019-05-18 16:39:14,685 [salt.minion      :1432][INFO    ][7938] Starting a new job with PID 7938
2019-05-18 16:39:14,694 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][7938] Executing command 'calicoctl node status' in directory '/root'
2019-05-18 16:39:14,745 [salt.minion      :1711][INFO    ][7938] Returning information for job: 20190518163914657623
2019-05-18 16:39:15,388 [salt.minion      :1308][INFO    ][4956] User sudo_ubuntu Executing command cmd.run with jid 20190518163915381705
2019-05-18 16:39:15,403 [salt.minion      :1432][INFO    ][7955] Starting a new job with PID 7955
2019-05-18 16:39:15,413 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][7955] Executing command 'calicoctl get ippool' in directory '/root'
2019-05-18 16:39:15,556 [salt.minion      :1711][INFO    ][7955] Returning information for job: 20190518163915381705
2019-05-18 16:40:15,253 [salt.minion      :1308][INFO    ][4956] User sudo_ubuntu Executing command cp.push_dir with jid 20190518164015245335
2019-05-18 16:40:15,271 [salt.minion      :1432][INFO    ][8074] Starting a new job with PID 8074
2019-05-18 16:40:15,645 [salt.minion      :1711][INFO    ][8074] Returning information for job: 20190518164015245335
2019-05-18 16:42:57,826 [salt.minion      :1308][INFO    ][4956] User sudo_ubuntu Executing command cp.push_dir with jid 20190518164257807017
2019-05-18 16:42:57,844 [salt.minion      :1432][INFO    ][8711] Starting a new job with PID 8711
