2019-11-16 16:51:21,643 [salt.utils       :831 ][ERROR   ][1432] DNS lookup or connection check of 'salt' failed.
2019-11-16 16:51:21,644 [salt.minion      :152 ][ERROR   ][1432] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-11-16 16:51:24,722 [salt.utils.parsers:1051][WARNING ][1432] Minion received a SIGTERM. Exiting.
2019-11-16 16:53:58,937 [salt.utils.decorators:613 ][WARNING ][2020] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-16 16:54:44,754 [salt.loaded.int.states.file:2298][WARNING ][2020] 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-11-16 16:54:57,315 [salt.utils.decorators:613 ][WARNING ][2020] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-16 16:54:57,638 [salt.loaded.int.states.file:2298][WARNING ][2020] 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-11-16 16:54:57,640 [salt.loaded.int.states.file:2298][WARNING ][2020] 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-11-16 16:54:57,641 [salt.loaded.int.states.file:2298][WARNING ][2020] 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-11-16 16:54:57,642 [salt.loaded.int.states.file:2298][WARNING ][2020] 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-11-16 16:54:57,644 [salt.loaded.int.states.file:2298][WARNING ][2020] 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-11-16 16:54:57,646 [salt.loaded.int.states.file:2298][WARNING ][2020] 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-11-16 16:54:57,647 [salt.loaded.int.states.file:2298][WARNING ][2020] 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-11-16 16:54:57,649 [salt.loaded.int.states.file:2298][WARNING ][2020] 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-11-16 16:55:09,682 [salt.loaded.int.module.debian_ip:1964][WARNING ][2020] The network state sls is requiring a reboot of the system to properly apply network configuration.
2019-11-16 16:55:11,764 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][2020] Command '['umount', '/dev/shm']' failed with return code: 32
2019-11-16 16:55:11,764 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][2020] stderr: umount: /dev/shm: target is busy.
2019-11-16 16:55:11,765 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][2020] retcode: 32
2019-11-16 16:55:26,848 [salt.utils.parsers:1051][WARNING ][1814] Minion received a SIGTERM. Exiting.
2019-11-16 16:56:41,584 [salt.utils.decorators:613 ][WARNING ][2783] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-16 16:56:44,424 [salt.loaded.int.states.file:2298][WARNING ][2783] 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-11-16 16:56:48,987 [salt.loaded.int.states.file:2298][WARNING ][2783] 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-11-16 16:57:11,750 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4476] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-11-16 16:57:11,793 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4476] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-11-16 16:57:11,814 [salt.utils.parsers:1051][WARNING ][2626] Minion received a SIGTERM. Exiting.
2019-11-16 16:57:12,707 [salt.cli.daemons :293 ][INFO    ][4528] Setting up the Salt Minion "ctl01.mcp-k8s-calico-noha.local"
2019-11-16 16:57:12,861 [salt.cli.daemons :82  ][INFO    ][4528] Starting up the Salt Minion
2019-11-16 16:57:12,862 [salt.utils.event :1017][INFO    ][4528] Starting pull socket on /var/run/salt/minion/minion_event_1899fe9592_pull.ipc
2019-11-16 16:57:13,713 [salt.minion      :976 ][INFO    ][4528] Creating minion process manager
2019-11-16 16:57:15,213 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][4528] Executing command ['date', '+%z'] in directory '/root'
2019-11-16 16:57:15,232 [salt.utils.schedule:568 ][INFO    ][4528] Updating job settings for scheduled job: __mine_interval
2019-11-16 16:57:15,234 [salt.minion      :1108][INFO    ][4528] Added mine.update to scheduler
2019-11-16 16:57:15,240 [salt.minion      :1975][INFO    ][4528] Minion is starting as user 'root'
2019-11-16 16:57:15,255 [salt.minion      :2336][INFO    ][4528] Minion is ready to receive requests!
2019-11-16 16:57:23,329 [salt.minion      :1308][INFO    ][4528] User sudo_ubuntu Executing command state.sls with jid 20191116165723320648
2019-11-16 16:57:23,345 [salt.minion      :1432][INFO    ][4616] Starting a new job with PID 4616
2019-11-16 16:57:24,428 [salt.state       :915 ][INFO    ][4616] Loading fresh modules for state activity
2019-11-16 16:57:24,479 [salt.fileclient  :1219][INFO    ][4616] Fetching file from saltenv 'base', ** done ** 'etcd/server/service.sls'
2019-11-16 16:57:24,534 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command '. /var/lib/etcd/configenv; etcdctl cluster-health > /dev/null 2>&1; echo $?' in directory '/root'
2019-11-16 16:57:24,551 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4616] Command '. /var/lib/etcd/configenv; etcdctl cluster-health > /dev/null 2>&1; echo $?' failed with return code: 2
2019-11-16 16:57:24,551 [salt.loaded.int.module.cmdmod:732 ][ERROR   ][4616] stdout: /bin/sh: 1: .: Can't open /var/lib/etcd/configenv
2019-11-16 16:57:24,552 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4616] retcode: 2
2019-11-16 16:57:25,270 [salt.state       :1780][INFO    ][4616] Running state [etcd_support_packages] at time 16:57:25.270504
2019-11-16 16:57:25,270 [salt.state       :1813][INFO    ][4616] Executing state pkg.installed for [etcd_support_packages]
2019-11-16 16:57:25,271 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-16 16:57:26,178 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command ['apt-cache', '-q', 'policy', 'python-etcd'] in directory '/root'
2019-11-16 16:57:26,645 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-11-16 16:57:29,550 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-11-16 16:57:29,582 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'python-etcd'] in directory '/root'
2019-11-16 16:57:33,736 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-16 16:57:33,786 [salt.state       :300 ][INFO    ][4616] Made the following changes:
'python-etcd' changed from 'absent' to '0.4.3-2'
'python-dnspython' changed from 'absent' to '1.15.0-1'

2019-11-16 16:57:33,804 [salt.state       :915 ][INFO    ][4616] Loading fresh modules for state activity
2019-11-16 16:57:33,843 [salt.state       :1951][INFO    ][4616] Completed state [etcd_support_packages] at time 16:57:33.843125 duration_in_ms=8572.62
2019-11-16 16:57:33,846 [salt.state       :1780][INFO    ][4616] Running state [/tmp/etcd/bin] at time 16:57:33.846247
2019-11-16 16:57:33,846 [salt.state       :1813][INFO    ][4616] Executing state archive.extracted for [/tmp/etcd/bin]
2019-11-16 16:57:36,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command ['tar', 'x', '--strip=1', '-f', '/var/cache/salt/minion/extrn_files/base/github.com/etcd-io/etcd/releases/download/v3.3.12/etcd-v3.3.12-linux-amd64.tar.gz'] in directory '/tmp/etcd/bin/'
2019-11-16 16:57:36,714 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command ['tar', '--version'] in directory '/root'
2019-11-16 16:57:36,728 [salt.state       :300 ][INFO    ][4616] {'extracted_files': 'no tar output so far', 'directories_created': ['/tmp/etcd/bin/']}
2019-11-16 16:57:36,728 [salt.state       :1951][INFO    ][4616] Completed state [/tmp/etcd/bin] at time 16:57:36.728506 duration_in_ms=2882.253
2019-11-16 16:57:36,729 [salt.state       :1780][INFO    ][4616] Running state [/usr/local/bin/etcd] at time 16:57:36.729418
2019-11-16 16:57:36,729 [salt.state       :1813][INFO    ][4616] Executing state file.managed for [/usr/local/bin/etcd]
2019-11-16 16:57:37,090 [salt.state       :300 ][INFO    ][4616] File changed:
New file
2019-11-16 16:57:37,091 [salt.state       :1951][INFO    ][4616] Completed state [/usr/local/bin/etcd] at time 16:57:37.091500 duration_in_ms=362.081
2019-11-16 16:57:37,092 [salt.state       :1780][INFO    ][4616] Running state [/usr/local/bin/etcdctl] at time 16:57:37.092113
2019-11-16 16:57:37,092 [salt.state       :1813][INFO    ][4616] Executing state file.managed for [/usr/local/bin/etcdctl]
2019-11-16 16:57:37,255 [salt.state       :300 ][INFO    ][4616] File changed:
New file
2019-11-16 16:57:37,255 [salt.state       :1951][INFO    ][4616] Completed state [/usr/local/bin/etcdctl] at time 16:57:37.255640 duration_in_ms=163.526
2019-11-16 16:57:37,257 [salt.state       :1780][INFO    ][4616] Running state [etcd] at time 16:57:37.257377
2019-11-16 16:57:37,257 [salt.state       :1813][INFO    ][4616] Executing state user.present for [etcd]
2019-11-16 16:57:37,267 [salt.state       :300 ][INFO    ][4616] User etcd is present and up to date
2019-11-16 16:57:37,267 [salt.state       :1951][INFO    ][4616] Completed state [etcd] at time 16:57:37.267611 duration_in_ms=10.234
2019-11-16 16:57:37,267 [salt.state       :1780][INFO    ][4616] Running state [/etc/systemd/system/etcd.service] at time 16:57:37.267835
2019-11-16 16:57:37,268 [salt.state       :1813][INFO    ][4616] Executing state file.managed for [/etc/systemd/system/etcd.service]
2019-11-16 16:57:37,292 [salt.fileclient  :1219][INFO    ][4616] Fetching file from saltenv 'base', ** done ** 'etcd/files/systemd/etcd.service'
2019-11-16 16:57:37,304 [salt.state       :300 ][INFO    ][4616] File changed:
New file
2019-11-16 16:57:37,305 [salt.state       :1951][INFO    ][4616] Completed state [/etc/systemd/system/etcd.service] at time 16:57:37.305013 duration_in_ms=37.178
2019-11-16 16:57:37,305 [salt.state       :1780][INFO    ][4616] Running state [/etc/default/etcd] at time 16:57:37.305253
2019-11-16 16:57:37,305 [salt.state       :1813][INFO    ][4616] Executing state file.managed for [/etc/default/etcd]
2019-11-16 16:57:37,327 [salt.fileclient  :1219][INFO    ][4616] Fetching file from saltenv 'base', ** done ** 'etcd/files/default'
2019-11-16 16:57:37,393 [salt.state       :300 ][INFO    ][4616] File changed:
New file
2019-11-16 16:57:37,393 [salt.state       :1951][INFO    ][4616] Completed state [/etc/default/etcd] at time 16:57:37.393303 duration_in_ms=88.05
2019-11-16 16:57:37,393 [salt.state       :1780][INFO    ][4616] Running state [/var/lib/etcd/] at time 16:57:37.393542
2019-11-16 16:57:37,393 [salt.state       :1813][INFO    ][4616] Executing state file.directory for [/var/lib/etcd/]
2019-11-16 16:57:37,398 [salt.state       :300 ][INFO    ][4616] Directory /var/lib/etcd is in the correct state
Directory /var/lib/etcd updated
2019-11-16 16:57:37,398 [salt.state       :1951][INFO    ][4616] Completed state [/var/lib/etcd/] at time 16:57:37.398309 duration_in_ms=4.767
2019-11-16 16:57:37,398 [salt.state       :1780][INFO    ][4616] Running state [/var/lib/etcd/configenv] at time 16:57:37.398864
2019-11-16 16:57:37,399 [salt.state       :1813][INFO    ][4616] Executing state file.managed for [/var/lib/etcd/configenv]
2019-11-16 16:57:37,421 [salt.fileclient  :1219][INFO    ][4616] Fetching file from saltenv 'base', ** done ** 'etcd/files/configenv'
2019-11-16 16:57:37,452 [salt.state       :300 ][INFO    ][4616] File changed:
New file
2019-11-16 16:57:37,452 [salt.state       :1951][INFO    ][4616] Completed state [/var/lib/etcd/configenv] at time 16:57:37.452559 duration_in_ms=53.695
2019-11-16 16:57:37,453 [salt.state       :1780][INFO    ][4616] Running state [/var/lib/etcd/configenvv3] at time 16:57:37.453056
2019-11-16 16:57:37,453 [salt.state       :1813][INFO    ][4616] Executing state file.managed for [/var/lib/etcd/configenvv3]
2019-11-16 16:57:37,471 [salt.fileclient  :1219][INFO    ][4616] Fetching file from saltenv 'base', ** done ** 'etcd/files/configenvv3'
2019-11-16 16:57:37,502 [salt.state       :300 ][INFO    ][4616] File changed:
New file
2019-11-16 16:57:37,503 [salt.state       :1951][INFO    ][4616] Completed state [/var/lib/etcd/configenvv3] at time 16:57:37.503058 duration_in_ms=50.002
2019-11-16 16:57:37,999 [salt.state       :1780][INFO    ][4616] Running state [etcd] at time 16:57:37.998962
2019-11-16 16:57:37,999 [salt.state       :1813][INFO    ][4616] Executing state service.running for [etcd]
2019-11-16 16:57:38,000 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command ['systemctl', 'status', 'etcd.service', '-n', '0'] in directory '/root'
2019-11-16 16:57:38,027 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command ['systemctl', 'is-active', 'etcd.service'] in directory '/root'
2019-11-16 16:57:38,051 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command ['systemctl', 'is-enabled', 'etcd.service'] in directory '/root'
2019-11-16 16:57:38,082 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'etcd.service'] in directory '/root'
2019-11-16 16:57:38,368 [salt.minion      :1308][INFO    ][4528] User sudo_ubuntu Executing command saltutil.find_job with jid 20191116165738353727
2019-11-16 16:57:38,390 [salt.minion      :1432][INFO    ][5413] Starting a new job with PID 5413
2019-11-16 16:57:38,411 [salt.minion      :1711][INFO    ][5413] Returning information for job: 20191116165738353727
2019-11-16 16:57:43,154 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command ['systemctl', 'is-active', 'etcd.service'] in directory '/root'
2019-11-16 16:57:43,169 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command ['systemctl', 'is-enabled', 'etcd.service'] in directory '/root'
2019-11-16 16:57:43,189 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command ['systemctl', 'is-enabled', 'etcd.service'] in directory '/root'
2019-11-16 16:57:43,212 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'etcd.service'] in directory '/root'
2019-11-16 16:57:43,399 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4616] Executing command ['systemctl', 'is-enabled', 'etcd.service'] in directory '/root'
2019-11-16 16:57:43,422 [salt.state       :300 ][INFO    ][4616] {'etcd': True}
2019-11-16 16:57:43,423 [salt.state       :1951][INFO    ][4616] Completed state [etcd] at time 16:57:43.423225 duration_in_ms=5424.262
2019-11-16 16:57:43,426 [salt.minion      :1711][INFO    ][4616] Returning information for job: 20191116165723320648
2019-11-16 16:57:44,116 [salt.minion      :1308][INFO    ][4528] User sudo_ubuntu Executing command cmd.run with jid 20191116165744105309
2019-11-16 16:57:44,133 [salt.minion      :1432][INFO    ][5470] Starting a new job with PID 5470
2019-11-16 16:57:44,142 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][5470] Executing command '. /var/lib/etcd/configenv && etcdctl cluster-health' in directory '/root'
2019-11-16 16:57:44,297 [salt.minion      :1711][INFO    ][5470] Returning information for job: 20191116165744105309
2019-11-16 16:57:44,990 [salt.minion      :1308][INFO    ][4528] User sudo_ubuntu Executing command state.sls with jid 20191116165744978789
2019-11-16 16:57:45,012 [salt.minion      :1432][INFO    ][5483] Starting a new job with PID 5483
2019-11-16 16:57:51,473 [salt.state       :915 ][INFO    ][5483] Loading fresh modules for state activity
2019-11-16 16:57:51,546 [salt.fileclient  :1219][INFO    ][5483] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/kube-addons.sls'
2019-11-16 16:57:51,672 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:51,689 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:51,715 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:51,732 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:51,757 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:51,776 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:51,849 [salt.state       :1780][INFO    ][5483] Running state [/etc/kubernetes/addons] at time 16:57:51.849029
2019-11-16 16:57:51,849 [salt.state       :1813][INFO    ][5483] Executing state file.directory for [/etc/kubernetes/addons]
2019-11-16 16:57:51,855 [salt.state       :300 ][INFO    ][5483] {'/etc/kubernetes/addons': 'New Dir'}
2019-11-16 16:57:51,855 [salt.state       :1951][INFO    ][5483] Completed state [/etc/kubernetes/addons] at time 16:57:51.855581 duration_in_ms=6.553
2019-11-16 16:57:51,855 [salt.state       :1780][INFO    ][5483] Running state [/etc/kubernetes/addons/calico/calico-kube-controllers.yml] at time 16:57:51.855854
2019-11-16 16:57:51,856 [salt.state       :1813][INFO    ][5483] Executing state file.managed for [/etc/kubernetes/addons/calico/calico-kube-controllers.yml]
2019-11-16 16:57:51,887 [salt.fileclient  :1219][INFO    ][5483] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/calico/calico-kube-controllers.yml'
2019-11-16 16:57:51,939 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:51,959 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:51,985 [salt.state       :300 ][INFO    ][5483] File changed:
New file
2019-11-16 16:57:51,985 [salt.state       :1951][INFO    ][5483] Completed state [/etc/kubernetes/addons/calico/calico-kube-controllers.yml] at time 16:57:51.985821 duration_in_ms=129.965
2019-11-16 16:57:51,986 [salt.state       :1780][INFO    ][5483] Running state [/etc/kubernetes/addons/calico/calico-rbac.yml] at time 16:57:51.986360
2019-11-16 16:57:51,986 [salt.state       :1813][INFO    ][5483] Executing state file.managed for [/etc/kubernetes/addons/calico/calico-rbac.yml]
2019-11-16 16:57:52,014 [salt.fileclient  :1219][INFO    ][5483] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/calico/calico-rbac.yml'
2019-11-16 16:57:52,047 [salt.state       :300 ][INFO    ][5483] File changed:
New file
2019-11-16 16:57:52,048 [salt.state       :1951][INFO    ][5483] Completed state [/etc/kubernetes/addons/calico/calico-rbac.yml] at time 16:57:52.048092 duration_in_ms=61.731
2019-11-16 16:57:52,048 [salt.state       :1780][INFO    ][5483] Running state [/etc/kubernetes/addons/netchecker/netchecker-svc.yml] at time 16:57:52.048491
2019-11-16 16:57:52,048 [salt.state       :1813][INFO    ][5483] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-svc.yml]
2019-11-16 16:57:52,075 [salt.fileclient  :1219][INFO    ][5483] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-svc.yml'
2019-11-16 16:57:52,135 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:52,158 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:52,182 [salt.state       :300 ][INFO    ][5483] File changed:
New file
2019-11-16 16:57:52,183 [salt.state       :1951][INFO    ][5483] Completed state [/etc/kubernetes/addons/netchecker/netchecker-svc.yml] at time 16:57:52.183042 duration_in_ms=134.551
2019-11-16 16:57:52,183 [salt.state       :1780][INFO    ][5483] Running state [/etc/kubernetes/addons/netchecker/netchecker-server.yml] at time 16:57:52.183647
2019-11-16 16:57:52,184 [salt.state       :1813][INFO    ][5483] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-server.yml]
2019-11-16 16:57:52,207 [salt.fileclient  :1219][INFO    ][5483] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-server.yml'
2019-11-16 16:57:52,269 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:52,283 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:52,306 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:52,323 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:52,343 [salt.state       :300 ][INFO    ][5483] File changed:
New file
2019-11-16 16:57:52,344 [salt.state       :1951][INFO    ][5483] Completed state [/etc/kubernetes/addons/netchecker/netchecker-server.yml] at time 16:57:52.344271 duration_in_ms=160.625
2019-11-16 16:57:52,344 [salt.state       :1780][INFO    ][5483] Running state [/etc/kubernetes/addons/netchecker/netchecker-agent.yml] at time 16:57:52.344797
2019-11-16 16:57:52,345 [salt.state       :1813][INFO    ][5483] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-agent.yml]
2019-11-16 16:57:52,369 [salt.fileclient  :1219][INFO    ][5483] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-agent.yml'
2019-11-16 16:57:52,428 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:52,450 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:52,475 [salt.state       :300 ][INFO    ][5483] File changed:
New file
2019-11-16 16:57:52,475 [salt.state       :1951][INFO    ][5483] Completed state [/etc/kubernetes/addons/netchecker/netchecker-agent.yml] at time 16:57:52.475480 duration_in_ms=130.683
2019-11-16 16:57:52,475 [salt.state       :1780][INFO    ][5483] Running state [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml] at time 16:57:52.475904
2019-11-16 16:57:52,476 [salt.state       :1813][INFO    ][5483] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml]
2019-11-16 16:57:52,501 [salt.fileclient  :1219][INFO    ][5483] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-serviceaccount.yml'
2019-11-16 16:57:52,510 [salt.state       :300 ][INFO    ][5483] File changed:
New file
2019-11-16 16:57:52,511 [salt.state       :1951][INFO    ][5483] Completed state [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml] at time 16:57:52.511005 duration_in_ms=35.099
2019-11-16 16:57:52,511 [salt.state       :1780][INFO    ][5483] Running state [/etc/kubernetes/addons/netchecker/netchecker-roles.yml] at time 16:57:52.511281
2019-11-16 16:57:52,511 [salt.state       :1813][INFO    ][5483] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-roles.yml]
2019-11-16 16:57:52,532 [salt.fileclient  :1219][INFO    ][5483] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-roles.yml'
2019-11-16 16:57:52,543 [salt.state       :300 ][INFO    ][5483] File changed:
New file
2019-11-16 16:57:52,543 [salt.state       :1951][INFO    ][5483] Completed state [/etc/kubernetes/addons/netchecker/netchecker-roles.yml] at time 16:57:52.543399 duration_in_ms=32.118
2019-11-16 16:57:52,543 [salt.state       :1780][INFO    ][5483] Running state [/etc/kubernetes/addons/prometheus/prometheus-roles.yml] at time 16:57:52.543678
2019-11-16 16:57:52,543 [salt.state       :1813][INFO    ][5483] Executing state file.managed for [/etc/kubernetes/addons/prometheus/prometheus-roles.yml]
2019-11-16 16:57:52,565 [salt.fileclient  :1219][INFO    ][5483] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/prometheus/prometheus-roles.yml'
2019-11-16 16:57:52,575 [salt.state       :300 ][INFO    ][5483] File changed:
New file
2019-11-16 16:57:52,575 [salt.state       :1951][INFO    ][5483] Completed state [/etc/kubernetes/addons/prometheus/prometheus-roles.yml] at time 16:57:52.575197 duration_in_ms=31.519
2019-11-16 16:57:52,575 [salt.state       :1780][INFO    ][5483] Running state [/etc/kubernetes/addons/coredns] at time 16:57:52.575440
2019-11-16 16:57:52,575 [salt.state       :1813][INFO    ][5483] Executing state file.absent for [/etc/kubernetes/addons/coredns]
2019-11-16 16:57:52,576 [salt.state       :300 ][INFO    ][5483] File /etc/kubernetes/addons/coredns is not present
2019-11-16 16:57:52,576 [salt.state       :1951][INFO    ][5483] Completed state [/etc/kubernetes/addons/coredns] at time 16:57:52.576318 duration_in_ms=0.879
2019-11-16 16:57:52,577 [salt.state       :1780][INFO    ][5483] Running state [kubectl -n kube-system delete svc coredns > /dev/null || echo "coredns is absent. OK" && true] at time 16:57:52.577426
2019-11-16 16:57:52,577 [salt.state       :1813][INFO    ][5483] Executing state cmd.run for [kubectl -n kube-system delete svc coredns > /dev/null || echo "coredns is absent. OK" && true]
2019-11-16 16:57:52,578 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] Executing command 'kubectl -n kube-system delete svc coredns > /dev/null || echo "coredns is absent. OK" && true' in directory '/root'
2019-11-16 16:57:52,591 [salt.state       :300 ][INFO    ][5483] {'pid': 5564, 'retcode': 0, 'stderr': '/bin/sh: 1: kubectl: not found', 'stdout': 'coredns is absent. OK'}
2019-11-16 16:57:52,591 [salt.state       :1951][INFO    ][5483] Completed state [kubectl -n kube-system delete svc coredns > /dev/null || echo "coredns is absent. OK" && true] at time 16:57:52.591525 duration_in_ms=14.099
2019-11-16 16:57:52,591 [salt.state       :1780][INFO    ][5483] Running state [/etc/kubernetes/addons/dns/kubedns-svc.yaml] at time 16:57:52.591927
2019-11-16 16:57:52,592 [salt.state       :1813][INFO    ][5483] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-svc.yaml]
2019-11-16 16:57:52,620 [salt.fileclient  :1219][INFO    ][5483] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-svc.yaml'
2019-11-16 16:57:52,667 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:52,680 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:52,704 [salt.state       :300 ][INFO    ][5483] File changed:
New file
2019-11-16 16:57:52,704 [salt.state       :1951][INFO    ][5483] Completed state [/etc/kubernetes/addons/dns/kubedns-svc.yaml] at time 16:57:52.704645 duration_in_ms=112.717
2019-11-16 16:57:52,705 [salt.state       :1780][INFO    ][5483] Running state [/etc/kubernetes/addons/dns/kubedns-rc.yaml] at time 16:57:52.705156
2019-11-16 16:57:52,705 [salt.state       :1813][INFO    ][5483] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-rc.yaml]
2019-11-16 16:57:52,727 [salt.fileclient  :1219][INFO    ][5483] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-rc.yaml'
2019-11-16 16:57:52,800 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:52,822 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:52,847 [salt.state       :300 ][INFO    ][5483] File changed:
New file
2019-11-16 16:57:52,847 [salt.state       :1951][INFO    ][5483] Completed state [/etc/kubernetes/addons/dns/kubedns-rc.yaml] at time 16:57:52.847822 duration_in_ms=142.665
2019-11-16 16:57:52,848 [salt.state       :1780][INFO    ][5483] Running state [/etc/kubernetes/addons/dns/kubedns-sa.yaml] at time 16:57:52.848342
2019-11-16 16:57:52,848 [salt.state       :1813][INFO    ][5483] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-sa.yaml]
2019-11-16 16:57:52,868 [salt.fileclient  :1219][INFO    ][5483] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-sa.yaml'
2019-11-16 16:57:52,879 [salt.state       :300 ][INFO    ][5483] File changed:
New file
2019-11-16 16:57:52,879 [salt.state       :1951][INFO    ][5483] Completed state [/etc/kubernetes/addons/dns/kubedns-sa.yaml] at time 16:57:52.879693 duration_in_ms=31.352
2019-11-16 16:57:52,879 [salt.state       :1780][INFO    ][5483] Running state [/etc/kubernetes/addons/dns/kubedns-autoscaler.yaml] at time 16:57:52.879948
2019-11-16 16:57:52,880 [salt.state       :1813][INFO    ][5483] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-autoscaler.yaml]
2019-11-16 16:57:52,899 [salt.fileclient  :1219][INFO    ][5483] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-autoscaler.yaml'
2019-11-16 16:57:52,951 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:52,973 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5483] 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-11-16 16:57:52,998 [salt.state       :300 ][INFO    ][5483] File changed:
New file
2019-11-16 16:57:52,998 [salt.state       :1951][INFO    ][5483] Completed state [/etc/kubernetes/addons/dns/kubedns-autoscaler.yaml] at time 16:57:52.998801 duration_in_ms=118.851
2019-11-16 16:57:52,999 [salt.state       :1780][INFO    ][5483] Running state [/etc/kubernetes/addons/dns/kubedns-autoscaler-rbac.yaml] at time 16:57:52.999337
2019-11-16 16:57:52,999 [salt.state       :1813][INFO    ][5483] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-autoscaler-rbac.yaml]
2019-11-16 16:57:53,023 [salt.fileclient  :1219][INFO    ][5483] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-autoscaler-rbac.yaml'
2019-11-16 16:57:53,033 [salt.state       :300 ][INFO    ][5483] File changed:
New file
2019-11-16 16:57:53,033 [salt.state       :1951][INFO    ][5483] Completed state [/etc/kubernetes/addons/dns/kubedns-autoscaler-rbac.yaml] at time 16:57:53.033909 duration_in_ms=34.573
2019-11-16 16:57:53,034 [salt.state       :1780][INFO    ][5483] Running state [/etc/kubernetes/addons/dns/kubedns-clusterrole.yaml] at time 16:57:53.034149
2019-11-16 16:57:53,034 [salt.state       :1813][INFO    ][5483] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-clusterrole.yaml]
2019-11-16 16:57:53,055 [salt.fileclient  :1219][INFO    ][5483] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-clusterrole.yaml'
2019-11-16 16:57:53,072 [salt.state       :300 ][INFO    ][5483] File changed:
New file
2019-11-16 16:57:53,072 [salt.state       :1951][INFO    ][5483] Completed state [/etc/kubernetes/addons/dns/kubedns-clusterrole.yaml] at time 16:57:53.072404 duration_in_ms=38.254
2019-11-16 16:57:53,072 [salt.state       :1780][INFO    ][5483] Running state [/etc/kubernetes/addons/metrics-server] at time 16:57:53.072640
2019-11-16 16:57:53,072 [salt.state       :1813][INFO    ][5483] Executing state file.absent for [/etc/kubernetes/addons/metrics-server]
2019-11-16 16:57:53,073 [salt.state       :300 ][INFO    ][5483] File /etc/kubernetes/addons/metrics-server is not present
2019-11-16 16:57:53,073 [salt.state       :1951][INFO    ][5483] Completed state [/etc/kubernetes/addons/metrics-server] at time 16:57:53.073409 duration_in_ms=0.769
2019-11-16 16:57:53,076 [salt.minion      :1711][INFO    ][5483] Returning information for job: 20191116165744978789
2019-11-16 16:57:53,779 [salt.minion      :1308][INFO    ][4528] User sudo_ubuntu Executing command state.sls with jid 20191116165753766584
2019-11-16 16:57:53,796 [salt.minion      :1432][INFO    ][5602] Starting a new job with PID 5602
2019-11-16 16:57:54,901 [salt.state       :915 ][INFO    ][5602] Loading fresh modules for state activity
2019-11-16 16:57:54,955 [salt.fileclient  :1219][INFO    ][5602] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/init.sls'
2019-11-16 16:57:55,022 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:57:55,046 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:57:55,067 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:57:55,082 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:57:55,112 [salt.fileclient  :1219][INFO    ][5602] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/calico.sls'
2019-11-16 16:57:55,181 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:57:55,201 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:57:55,219 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:57:55,236 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:57:55,305 [salt.fileclient  :1219][INFO    ][5602] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/service.sls'
2019-11-16 16:57:55,351 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:57:55,374 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:57:55,393 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:57:55,411 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:57:55,452 [salt.fileclient  :1219][INFO    ][5602] Fetching file from saltenv 'base', ** done ** 'kubernetes/_common.sls'
2019-11-16 16:57:55,536 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:57:55,557 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:57:55,639 [salt.fileclient  :1219][INFO    ][5602] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/kube-proxy.sls'
2019-11-16 16:57:55,692 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:57:55,714 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:57:55,759 [salt.state       :1780][INFO    ][5602] Running state [/usr/bin/calicoctl] at time 16:57:55.759351
2019-11-16 16:57:55,759 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/usr/bin/calicoctl]
2019-11-16 16:57:57,190 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:57:57,190 [salt.state       :1951][INFO    ][5602] Completed state [/usr/bin/calicoctl] at time 16:57:57.190331 duration_in_ms=1430.98
2019-11-16 16:57:57,190 [salt.state       :1780][INFO    ][5602] Running state [/usr/bin/birdcl] at time 16:57:57.190606
2019-11-16 16:57:57,190 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/usr/bin/birdcl]
2019-11-16 16:57:57,604 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:57:57,604 [salt.state       :1951][INFO    ][5602] Completed state [/usr/bin/birdcl] at time 16:57:57.604433 duration_in_ms=413.826
2019-11-16 16:57:57,604 [salt.state       :1780][INFO    ][5602] Running state [/opt/cni/bin/calico] at time 16:57:57.604663
2019-11-16 16:57:57,604 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/opt/cni/bin/calico]
2019-11-16 16:57:58,828 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:57:58,828 [salt.state       :1951][INFO    ][5602] Completed state [/opt/cni/bin/calico] at time 16:57:58.828672 duration_in_ms=1224.008
2019-11-16 16:57:58,828 [salt.state       :1780][INFO    ][5602] Running state [/opt/cni/bin/calico-ipam] at time 16:57:58.828944
2019-11-16 16:57:58,829 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/opt/cni/bin/calico-ipam]
2019-11-16 16:57:59,978 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:57:59,978 [salt.state       :1951][INFO    ][5602] Completed state [/opt/cni/bin/calico-ipam] at time 16:57:59.978840 duration_in_ms=1149.893
2019-11-16 16:57:59,979 [salt.state       :1780][INFO    ][5602] Running state [/etc/cni/net.d/10-calico.conf] at time 16:57:59.979109
2019-11-16 16:57:59,979 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/etc/cni/net.d/10-calico.conf]
2019-11-16 16:57:59,998 [salt.fileclient  :1219][INFO    ][5602] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calico.conf'
2019-11-16 16:58:00,051 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:58:00,065 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:58:00,079 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:58:00,093 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:58:00,113 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:58:00,114 [salt.state       :1951][INFO    ][5602] Completed state [/etc/cni/net.d/10-calico.conf] at time 16:58:00.113914 duration_in_ms=134.803
2019-11-16 16:58:00,114 [salt.state       :1780][INFO    ][5602] Running state [/etc/calico/network-environment] at time 16:58:00.114410
2019-11-16 16:58:00,114 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/etc/calico/network-environment]
2019-11-16 16:58:00,136 [salt.fileclient  :1219][INFO    ][5602] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/network-environment.pool'
2019-11-16 16:58:00,184 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:58:00,197 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:58:00,217 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:58:00,217 [salt.state       :1951][INFO    ][5602] Completed state [/etc/calico/network-environment] at time 16:58:00.217898 duration_in_ms=103.487
2019-11-16 16:58:00,218 [salt.state       :1780][INFO    ][5602] Running state [/etc/calico/calicoctl.cfg] at time 16:58:00.218430
2019-11-16 16:58:00,218 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/etc/calico/calicoctl.cfg]
2019-11-16 16:58:00,237 [salt.fileclient  :1219][INFO    ][5602] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calicoctl.cfg.pool'
2019-11-16 16:58:00,303 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:58:00,318 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:58:00,337 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:58:00,337 [salt.state       :1951][INFO    ][5602] Completed state [/etc/calico/calicoctl.cfg] at time 16:58:00.337634 duration_in_ms=119.203
2019-11-16 16:58:01,019 [salt.state       :1780][INFO    ][5602] Running state [containerd] at time 16:58:01.019229
2019-11-16 16:58:01,019 [salt.state       :1813][INFO    ][5602] Executing state pkg.installed for [containerd]
2019-11-16 16:58:01,020 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-16 16:58:01,867 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['apt-cache', '-q', 'policy', 'containerd'] in directory '/root'
2019-11-16 16:58:02,288 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-11-16 16:58:05,135 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-11-16 16:58:05,163 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'containerd'] in directory '/root'
2019-11-16 16:58:08,829 [salt.minion      :1308][INFO    ][4528] User sudo_ubuntu Executing command saltutil.find_job with jid 20191116165808820551
2019-11-16 16:58:08,842 [salt.minion      :1432][INFO    ][6351] Starting a new job with PID 6351
2019-11-16 16:58:08,857 [salt.minion      :1711][INFO    ][6351] Returning information for job: 20191116165808820551
2019-11-16 16:58:13,232 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-16 16:58:13,270 [salt.state       :300 ][INFO    ][5602] Made the following changes:
'containerd' changed from 'absent' to '1.2.6-0ubuntu1~18.04.2'
'runc' changed from 'absent' to '1.0.0~rc7+git20190403.029124da-0ubuntu1~18.04.2'

2019-11-16 16:58:13,290 [salt.state       :915 ][INFO    ][5602] Loading fresh modules for state activity
2019-11-16 16:58:13,330 [salt.state       :1951][INFO    ][5602] Completed state [containerd] at time 16:58:13.330378 duration_in_ms=12311.149
2019-11-16 16:58:13,334 [salt.state       :1780][INFO    ][5602] Running state [/etc/containerd/config.toml] at time 16:58:13.334237
2019-11-16 16:58:13,334 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/etc/containerd/config.toml]
2019-11-16 16:58:13,355 [salt.fileclient  :1219][INFO    ][5602] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/containerd/config.toml'
2019-11-16 16:58:13,401 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:58:13,423 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:58:13,448 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:58:13,448 [salt.state       :1951][INFO    ][5602] Completed state [/etc/containerd/config.toml] at time 16:58:13.448644 duration_in_ms=114.406
2019-11-16 16:58:14,002 [salt.state       :1780][INFO    ][5602] Running state [containerd] at time 16:58:14.002066
2019-11-16 16:58:14,002 [salt.state       :1813][INFO    ][5602] Executing state service.running for [containerd]
2019-11-16 16:58:14,003 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'status', 'containerd.service', '-n', '0'] in directory '/root'
2019-11-16 16:58:14,020 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-11-16 16:58:14,037 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-enabled', 'containerd.service'] in directory '/root'
2019-11-16 16:58:14,057 [salt.state       :300 ][INFO    ][5602] The service containerd is already running
2019-11-16 16:58:14,058 [salt.state       :1951][INFO    ][5602] Completed state [containerd] at time 16:58:14.057981 duration_in_ms=55.915
2019-11-16 16:58:14,058 [salt.state       :1780][INFO    ][5602] Running state [containerd] at time 16:58:14.058511
2019-11-16 16:58:14,059 [salt.state       :1813][INFO    ][5602] Executing state service.mod_watch for [containerd]
2019-11-16 16:58:14,060 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-11-16 16:58:14,080 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'containerd.service'] in directory '/root'
2019-11-16 16:58:14,143 [salt.state       :300 ][INFO    ][5602] {'containerd': True}
2019-11-16 16:58:14,144 [salt.state       :1951][INFO    ][5602] Completed state [containerd] at time 16:58:14.144243 duration_in_ms=85.733
2019-11-16 16:58:14,145 [salt.state       :1780][INFO    ][5602] Running state [/etc/systemd/system/calico-node.service] at time 16:58:14.145098
2019-11-16 16:58:14,145 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/etc/systemd/system/calico-node.service]
2019-11-16 16:58:14,167 [salt.fileclient  :1219][INFO    ][5602] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calico-node.service.ctr'
2019-11-16 16:58:14,189 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:58:14,189 [salt.state       :1951][INFO    ][5602] Completed state [/etc/systemd/system/calico-node.service] at time 16:58:14.189856 duration_in_ms=44.757
2019-11-16 16:58:14,190 [salt.state       :1780][INFO    ][5602] Running state [/var/lib/calico] at time 16:58:14.190144
2019-11-16 16:58:14,191 [salt.state       :1813][INFO    ][5602] Executing state file.directory for [/var/lib/calico]
2019-11-16 16:58:14,192 [salt.state       :300 ][INFO    ][5602] {'/var/lib/calico': 'New Dir'}
2019-11-16 16:58:14,192 [salt.state       :1951][INFO    ][5602] Completed state [/var/lib/calico] at time 16:58:14.192409 duration_in_ms=2.265
2019-11-16 16:58:14,192 [salt.state       :1780][INFO    ][5602] Running state [/var/log/calico] at time 16:58:14.192644
2019-11-16 16:58:14,192 [salt.state       :1813][INFO    ][5602] Executing state file.directory for [/var/log/calico]
2019-11-16 16:58:14,194 [salt.state       :300 ][INFO    ][5602] {'/var/log/calico': 'New Dir'}
2019-11-16 16:58:14,194 [salt.state       :1951][INFO    ][5602] Completed state [/var/log/calico] at time 16:58:14.194226 duration_in_ms=1.582
2019-11-16 16:58:14,196 [salt.state       :1780][INFO    ][5602] Running state [calico-node] at time 16:58:14.196500
2019-11-16 16:58:14,196 [salt.state       :1813][INFO    ][5602] Executing state service.running for [calico-node]
2019-11-16 16:58:14,197 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'status', 'calico-node.service', '-n', '0'] in directory '/root'
2019-11-16 16:58:14,221 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-11-16 16:58:14,235 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-11-16 16:58:14,257 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'calico-node.service'] in directory '/root'
2019-11-16 16:58:19,000 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-11-16 16:58:19,015 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-11-16 16:58:19,035 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-11-16 16:58:19,061 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'calico-node.service'] in directory '/root'
2019-11-16 16:58:19,229 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-11-16 16:58:19,248 [salt.state       :300 ][INFO    ][5602] {'calico-node': True}
2019-11-16 16:58:19,248 [salt.state       :1951][INFO    ][5602] Completed state [calico-node] at time 16:58:19.248369 duration_in_ms=5051.868
2019-11-16 16:58:19,250 [salt.state       :1780][INFO    ][5602] Running state [curl] at time 16:58:19.250156
2019-11-16 16:58:19,250 [salt.state       :1813][INFO    ][5602] Executing state pkg.installed for [curl]
2019-11-16 16:58:19,467 [salt.state       :300 ][INFO    ][5602] All specified packages are already installed
2019-11-16 16:58:19,467 [salt.state       :1951][INFO    ][5602] Completed state [curl] at time 16:58:19.467497 duration_in_ms=217.341
2019-11-16 16:58:19,467 [salt.state       :1780][INFO    ][5602] Running state [git] at time 16:58:19.467795
2019-11-16 16:58:19,468 [salt.state       :1813][INFO    ][5602] Executing state pkg.installed for [git]
2019-11-16 16:58:19,476 [salt.state       :300 ][INFO    ][5602] All specified packages are already installed
2019-11-16 16:58:19,477 [salt.state       :1951][INFO    ][5602] Completed state [git] at time 16:58:19.477037 duration_in_ms=9.242
2019-11-16 16:58:19,477 [salt.state       :1780][INFO    ][5602] Running state [apt-transport-https] at time 16:58:19.477275
2019-11-16 16:58:19,477 [salt.state       :1813][INFO    ][5602] Executing state pkg.installed for [apt-transport-https]
2019-11-16 16:58:19,485 [salt.state       :300 ][INFO    ][5602] All specified packages are already installed
2019-11-16 16:58:19,486 [salt.state       :1951][INFO    ][5602] Completed state [apt-transport-https] at time 16:58:19.486156 duration_in_ms=8.881
2019-11-16 16:58:19,486 [salt.state       :1780][INFO    ][5602] Running state [python-apt] at time 16:58:19.486412
2019-11-16 16:58:19,486 [salt.state       :1813][INFO    ][5602] Executing state pkg.installed for [python-apt]
2019-11-16 16:58:19,496 [salt.state       :300 ][INFO    ][5602] All specified packages are already installed
2019-11-16 16:58:19,496 [salt.state       :1951][INFO    ][5602] Completed state [python-apt] at time 16:58:19.496291 duration_in_ms=9.879
2019-11-16 16:58:19,496 [salt.state       :1780][INFO    ][5602] Running state [socat] at time 16:58:19.496573
2019-11-16 16:58:19,496 [salt.state       :1813][INFO    ][5602] Executing state pkg.installed for [socat]
2019-11-16 16:58:19,527 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-11-16 16:58:19,553 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:58:23,826 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-16 16:58:23,865 [salt.state       :300 ][INFO    ][5602] Made the following changes:
'socat' changed from 'absent' to '1.7.3.2-2ubuntu2'

2019-11-16 16:58:23,892 [salt.state       :915 ][INFO    ][5602] Loading fresh modules for state activity
2019-11-16 16:58:23,956 [salt.state       :1951][INFO    ][5602] Completed state [socat] at time 16:58:23.956710 duration_in_ms=4460.135
2019-11-16 16:58:23,966 [salt.state       :1780][INFO    ][5602] Running state [openssl] at time 16:58:23.966059
2019-11-16 16:58:23,966 [salt.state       :1813][INFO    ][5602] Executing state pkg.installed for [openssl]
2019-11-16 16:58:24,628 [salt.state       :300 ][INFO    ][5602] All specified packages are already installed
2019-11-16 16:58:24,629 [salt.state       :1951][INFO    ][5602] Completed state [openssl] at time 16:58:24.629163 duration_in_ms=663.104
2019-11-16 16:58:24,629 [salt.state       :1780][INFO    ][5602] Running state [conntrack] at time 16:58:24.629482
2019-11-16 16:58:24,629 [salt.state       :1813][INFO    ][5602] Executing state pkg.installed for [conntrack]
2019-11-16 16:58:24,658 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-11-16 16:58:24,686 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:58:28,643 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-16 16:58:28,681 [salt.state       :300 ][INFO    ][5602] Made the following changes:
'conntrack' changed from 'absent' to '1:1.4.4+snapshot20161117-6ubuntu2'

2019-11-16 16:58:28,696 [salt.state       :915 ][INFO    ][5602] Loading fresh modules for state activity
2019-11-16 16:58:28,735 [salt.state       :1951][INFO    ][5602] Completed state [conntrack] at time 16:58:28.735215 duration_in_ms=4105.731
2019-11-16 16:58:28,741 [salt.state       :1780][INFO    ][5602] Running state [nfs-common] at time 16:58:28.741034
2019-11-16 16:58:28,741 [salt.state       :1813][INFO    ][5602] Executing state pkg.installed for [nfs-common]
2019-11-16 16:58:29,427 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-11-16 16:58:29,450 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'nfs-common'] in directory '/root'
2019-11-16 16:58:38,394 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-16 16:58:38,434 [salt.state       :300 ][INFO    ][5602] Made the following changes:
'keyutils' changed from 'absent' to '1.5.9-9.2ubuntu2'
'nfs-common' changed from 'absent' to '1:1.3.4-2.1ubuntu5.2'
'rpcbind' changed from 'absent' to '0.2.3-0.6'
'libtirpc1' changed from 'absent' to '0.2.5-1.2ubuntu0.1'
'nfs-client' changed from 'absent' to '1'
'libnfsidmap2' changed from 'absent' to '0.25-5.1'
'portmap' changed from 'absent' to '1'

2019-11-16 16:58:38,450 [salt.state       :915 ][INFO    ][5602] Loading fresh modules for state activity
2019-11-16 16:58:38,490 [salt.state       :1951][INFO    ][5602] Completed state [nfs-common] at time 16:58:38.490246 duration_in_ms=9749.211
2019-11-16 16:58:38,498 [salt.state       :1780][INFO    ][5602] Running state [cifs-utils] at time 16:58:38.498088
2019-11-16 16:58:38,498 [salt.state       :1813][INFO    ][5602] Executing state pkg.installed for [cifs-utils]
2019-11-16 16:58:39,135 [salt.minion      :1308][INFO    ][4528] User sudo_ubuntu Executing command saltutil.find_job with jid 20191116165838849070
2019-11-16 16:58:39,146 [salt.minion      :1432][INFO    ][8752] Starting a new job with PID 8752
2019-11-16 16:58:39,161 [salt.minion      :1711][INFO    ][8752] Returning information for job: 20191116165838849070
2019-11-16 16:58:39,197 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-11-16 16:58:39,223 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'cifs-utils'] in directory '/root'
2019-11-16 16:58:51,945 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-16 16:58:51,983 [salt.state       :300 ][INFO    ][5602] Made the following changes:
'python2.7-ldb' changed from 'absent' to '1'
'python-ldb' changed from 'absent' to '2:1.2.3-1ubuntu0.1'
'libtdb1' changed from 'absent' to '1.3.15-2'
'libavahi-common3' changed from 'absent' to '0.7-3.1ubuntu1.2'
'python2.7-talloc' changed from 'absent' to '1'
'libavahi-client3' changed from 'absent' to '0.7-3.1ubuntu1.2'
'libwbclient0' changed from 'absent' to '2:4.7.6+dfsg~ubuntu-0ubuntu2.13'
'libavahi-common-data' changed from 'absent' to '0.7-3.1ubuntu1.2'
'libcups2' changed from 'absent' to '2.2.7-1ubuntu2.7'
'cifs-utils' changed from 'absent' to '2:6.8-1'
'samba-common' changed from 'absent' to '2:4.7.6+dfsg~ubuntu-0ubuntu2.13'
'python2.7-tdb' changed from 'absent' to '1'
'samba-libs' changed from 'absent' to '2:4.7.6+dfsg~ubuntu-0ubuntu2.13'
'libldb1' changed from 'absent' to '2:1.2.3-1ubuntu0.1'
'libtevent0' changed from 'absent' to '0.9.34-1'
'python-talloc' changed from 'absent' to '2.1.10-2ubuntu1'
'samba-common-bin' changed from 'absent' to '2:4.7.6+dfsg~ubuntu-0ubuntu2.13'
'python-samba' changed from 'absent' to '2:4.7.6+dfsg~ubuntu-0ubuntu2.13'
'libtalloc2' changed from 'absent' to '2.1.10-2ubuntu1'
'python2.7-samba' changed from 'absent' to '1'
'libjansson4' changed from 'absent' to '2.11-1'
'python-tdb' changed from 'absent' to '1.3.15-2'

2019-11-16 16:58:52,000 [salt.state       :915 ][INFO    ][5602] Loading fresh modules for state activity
2019-11-16 16:58:52,044 [salt.state       :1951][INFO    ][5602] Completed state [cifs-utils] at time 16:58:52.044020 duration_in_ms=13545.931
2019-11-16 16:58:52,047 [salt.state       :1780][INFO    ][5602] Running state [/usr/bin/hyperkube] at time 16:58:52.047937
2019-11-16 16:58:52,048 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/usr/bin/hyperkube]
2019-11-16 16:58:56,881 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:58:56,882 [salt.state       :1951][INFO    ][5602] Completed state [/usr/bin/hyperkube] at time 16:58:56.881941 duration_in_ms=4834.002
2019-11-16 16:58:56,883 [salt.state       :1780][INFO    ][5602] Running state [/usr/bin/kubectl] at time 16:58:56.883342
2019-11-16 16:58:56,883 [salt.state       :1813][INFO    ][5602] Executing state file.symlink for [/usr/bin/kubectl]
2019-11-16 16:58:56,891 [salt.state       :300 ][INFO    ][5602] {'new': '/usr/bin/kubectl'}
2019-11-16 16:58:56,902 [salt.state       :915 ][INFO    ][5602] Loading fresh modules for state activity
2019-11-16 16:58:56,941 [salt.state       :1951][INFO    ][5602] Completed state [/usr/bin/kubectl] at time 16:58:56.941144 duration_in_ms=57.802
2019-11-16 16:58:56,944 [salt.state       :1780][INFO    ][5602] Running state [/tmp/crictl] at time 16:58:56.944174
2019-11-16 16:58:56,944 [salt.state       :1813][INFO    ][5602] Executing state archive.extracted for [/tmp/crictl]
2019-11-16 16:58:59,103 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:58:59,353 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['tar', '--version'] in directory '/root'
2019-11-16 16:58:59,372 [salt.state       :300 ][INFO    ][5602] {'extracted_files': 'no tar output so far', 'directories_created': ['/tmp/crictl/']}
2019-11-16 16:58:59,373 [salt.state       :1951][INFO    ][5602] Completed state [/tmp/crictl] at time 16:58:59.373484 duration_in_ms=2429.297
2019-11-16 16:58:59,376 [salt.state       :1780][INFO    ][5602] Running state [/usr/local/bin/crictl] at time 16:58:59.376836
2019-11-16 16:58:59,377 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/usr/local/bin/crictl]
2019-11-16 16:58:59,377 [salt.loaded.int.states.file:1019][WARNING ][5602] Use of argument owner found, "owner" is invalid, please use "user"
2019-11-16 16:58:59,648 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:58:59,649 [salt.state       :1951][INFO    ][5602] Completed state [/usr/local/bin/crictl] at time 16:58:59.649210 duration_in_ms=272.378
2019-11-16 16:58:59,649 [salt.state       :1780][INFO    ][5602] Running state [/etc/crictl.yaml] at time 16:58:59.649508
2019-11-16 16:58:59,649 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/etc/crictl.yaml]
2019-11-16 16:58:59,686 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:58:59,686 [salt.state       :1951][INFO    ][5602] Completed state [/etc/crictl.yaml] at time 16:58:59.686823 duration_in_ms=37.315
2019-11-16 16:58:59,687 [salt.state       :1780][INFO    ][5602] Running state [/etc/criproxy] at time 16:58:59.687182
2019-11-16 16:58:59,687 [salt.state       :1813][INFO    ][5602] Executing state file.absent for [/etc/criproxy]
2019-11-16 16:58:59,688 [salt.state       :300 ][INFO    ][5602] File /etc/criproxy is not present
2019-11-16 16:58:59,688 [salt.state       :1951][INFO    ][5602] Completed state [/etc/criproxy] at time 16:58:59.688307 duration_in_ms=1.126
2019-11-16 16:59:00,295 [salt.state       :1780][INFO    ][5602] Running state [criproxy] at time 16:59:00.294968
2019-11-16 16:59:00,295 [salt.state       :1813][INFO    ][5602] Executing state service.dead for [criproxy]
2019-11-16 16:59:00,296 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'status', 'criproxy.service', '-n', '0'] in directory '/root'
2019-11-16 16:59:00,323 [salt.state       :300 ][INFO    ][5602] The named service criproxy is not available
2019-11-16 16:59:00,323 [salt.state       :1951][INFO    ][5602] Completed state [criproxy] at time 16:59:00.323635 duration_in_ms=28.667
2019-11-16 16:59:00,324 [salt.state       :1780][INFO    ][5602] Running state [/etc/systemd/system/kubelet.service] at time 16:59:00.324229
2019-11-16 16:59:00,324 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/etc/systemd/system/kubelet.service]
2019-11-16 16:59:00,346 [salt.fileclient  :1219][INFO    ][5602] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kubelet.service'
2019-11-16 16:59:00,394 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:59:00,530 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:59:00,672 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:59:00,672 [salt.state       :1951][INFO    ][5602] Completed state [/etc/systemd/system/kubelet.service] at time 16:59:00.672812 duration_in_ms=348.58
2019-11-16 16:59:00,673 [salt.state       :1780][INFO    ][5602] Running state [/etc/kubernetes/config] at time 16:59:00.673301
2019-11-16 16:59:00,673 [salt.state       :1813][INFO    ][5602] Executing state file.absent for [/etc/kubernetes/config]
2019-11-16 16:59:00,674 [salt.state       :300 ][INFO    ][5602] File /etc/kubernetes/config is not present
2019-11-16 16:59:00,674 [salt.state       :1951][INFO    ][5602] Completed state [/etc/kubernetes/config] at time 16:59:00.674536 duration_in_ms=1.235
2019-11-16 16:59:00,674 [salt.state       :1780][INFO    ][5602] Running state [/etc/default/kubelet] at time 16:59:00.674868
2019-11-16 16:59:00,675 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/etc/default/kubelet]
2019-11-16 16:59:00,697 [salt.fileclient  :1219][INFO    ][5602] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubelet/default.master'
2019-11-16 16:59:00,769 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:59:00,908 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:59:01,038 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:59:01,169 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:59:01,305 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:59:01,450 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:59:01,601 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:59:01,601 [salt.state       :1951][INFO    ][5602] Completed state [/etc/default/kubelet] at time 16:59:01.601727 duration_in_ms=926.858
2019-11-16 16:59:01,602 [salt.state       :1780][INFO    ][5602] Running state [/etc/kubernetes/kubelet.kubeconfig] at time 16:59:01.602058
2019-11-16 16:59:01,602 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/etc/kubernetes/kubelet.kubeconfig]
2019-11-16 16:59:01,621 [salt.fileclient  :1219][INFO    ][5602] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubelet/kubelet.kubeconfig.master'
2019-11-16 16:59:01,671 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:59:01,810 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:59:01,958 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:59:02,141 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:59:02,280 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:59:02,280 [salt.state       :1951][INFO    ][5602] Completed state [/etc/kubernetes/kubelet.kubeconfig] at time 16:59:02.280615 duration_in_ms=678.555
2019-11-16 16:59:02,281 [salt.state       :1780][INFO    ][5602] Running state [/etc/kubernetes/manifests] at time 16:59:02.280967
2019-11-16 16:59:02,281 [salt.state       :1813][INFO    ][5602] Executing state file.directory for [/etc/kubernetes/manifests]
2019-11-16 16:59:02,282 [salt.state       :300 ][INFO    ][5602] {'/etc/kubernetes/manifests': 'New Dir'}
2019-11-16 16:59:02,283 [salt.state       :1951][INFO    ][5602] Completed state [/etc/kubernetes/manifests] at time 16:59:02.282984 duration_in_ms=2.017
2019-11-16 16:59:02,285 [salt.state       :1780][INFO    ][5602] Running state [kubelet] at time 16:59:02.285180
2019-11-16 16:59:02,285 [salt.state       :1813][INFO    ][5602] Executing state service.running for [kubelet]
2019-11-16 16:59:02,286 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'status', 'kubelet.service', '-n', '0'] in directory '/root'
2019-11-16 16:59:02,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-11-16 16:59:02,331 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-11-16 16:59:02,358 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kubelet.service'] in directory '/root'
2019-11-16 16:59:02,386 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-11-16 16:59:02,402 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-11-16 16:59:02,423 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-11-16 16:59:02,447 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kubelet.service'] in directory '/root'
2019-11-16 16:59:02,598 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-11-16 16:59:02,615 [salt.state       :300 ][INFO    ][5602] {'kubelet': True}
2019-11-16 16:59:02,615 [salt.state       :1951][INFO    ][5602] Completed state [kubelet] at time 16:59:02.615748 duration_in_ms=330.567
2019-11-16 16:59:02,616 [salt.state       :1780][INFO    ][5602] Running state [/etc/logrotate.d/kubernetes] at time 16:59:02.616379
2019-11-16 16:59:02,617 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/etc/logrotate.d/kubernetes]
2019-11-16 16:59:02,637 [salt.fileclient  :1219][INFO    ][5602] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/logrotate'
2019-11-16 16:59:02,648 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:59:02,648 [salt.state       :1951][INFO    ][5602] Completed state [/etc/logrotate.d/kubernetes] at time 16:59:02.648678 duration_in_ms=32.297
2019-11-16 16:59:02,649 [salt.state       :1780][INFO    ][5602] Running state [/opt/cni/bin] at time 16:59:02.649093
2019-11-16 16:59:02,649 [salt.state       :1813][INFO    ][5602] Executing state archive.extracted for [/opt/cni/bin]
2019-11-16 16:59:04,617 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:59:05,268 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['tar', '--version'] in directory '/root'
2019-11-16 16:59:05,286 [salt.state       :300 ][INFO    ][5602] {'extracted_files': 'no tar output so far'}
2019-11-16 16:59:05,287 [salt.state       :1951][INFO    ][5602] Completed state [/opt/cni/bin] at time 16:59:05.287021 duration_in_ms=2637.919
2019-11-16 16:59:05,287 [salt.state       :1780][INFO    ][5602] Running state [/etc/kubernetes/proxy.kubeconfig] at time 16:59:05.287659
2019-11-16 16:59:05,288 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/etc/kubernetes/proxy.kubeconfig]
2019-11-16 16:59:05,310 [salt.fileclient  :1219][INFO    ][5602] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-proxy/proxy.kubeconfig'
2019-11-16 16:59:05,360 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:59:05,519 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:59:05,662 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:59:05,815 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] 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-11-16 16:59:05,969 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:59:05,970 [salt.state       :1951][INFO    ][5602] Completed state [/etc/kubernetes/proxy.kubeconfig] at time 16:59:05.970351 duration_in_ms=682.691
2019-11-16 16:59:05,970 [salt.state       :1780][INFO    ][5602] Running state [/etc/systemd/system/kube-proxy.service] at time 16:59:05.970698
2019-11-16 16:59:05,971 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/etc/systemd/system/kube-proxy.service]
2019-11-16 16:59:05,994 [salt.fileclient  :1219][INFO    ][5602] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-proxy.service'
2019-11-16 16:59:06,006 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:59:06,006 [salt.state       :1951][INFO    ][5602] Completed state [/etc/systemd/system/kube-proxy.service] at time 16:59:06.006934 duration_in_ms=36.236
2019-11-16 16:59:06,007 [salt.state       :1780][INFO    ][5602] Running state [/etc/default/kube-proxy] at time 16:59:06.007230
2019-11-16 16:59:06,007 [salt.state       :1813][INFO    ][5602] Executing state file.managed for [/etc/default/kube-proxy]
2019-11-16 16:59:06,009 [salt.state       :300 ][INFO    ][5602] File changed:
New file
2019-11-16 16:59:06,009 [salt.state       :1951][INFO    ][5602] Completed state [/etc/default/kube-proxy] at time 16:59:06.009808 duration_in_ms=2.578
2019-11-16 16:59:06,011 [salt.state       :1780][INFO    ][5602] Running state [kube-proxy] at time 16:59:06.011593
2019-11-16 16:59:06,011 [salt.state       :1813][INFO    ][5602] Executing state service.running for [kube-proxy]
2019-11-16 16:59:06,012 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'status', 'kube-proxy.service', '-n', '0'] in directory '/root'
2019-11-16 16:59:06,039 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-11-16 16:59:06,062 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-11-16 16:59:06,092 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-proxy.service'] in directory '/root'
2019-11-16 16:59:06,120 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-11-16 16:59:06,136 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-11-16 16:59:06,162 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-11-16 16:59:06,192 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-proxy.service'] in directory '/root'
2019-11-16 16:59:06,386 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5602] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-11-16 16:59:06,404 [salt.state       :300 ][INFO    ][5602] {'kube-proxy': True}
2019-11-16 16:59:06,404 [salt.state       :1951][INFO    ][5602] Completed state [kube-proxy] at time 16:59:06.404733 duration_in_ms=393.139
2019-11-16 16:59:06,408 [salt.minion      :1711][INFO    ][5602] Returning information for job: 20191116165753766584
2019-11-16 16:59:33,700 [salt.minion      :1308][INFO    ][4528] User sudo_ubuntu Executing command cmd.run with jid 20191116165933687300
2019-11-16 16:59:33,723 [salt.minion      :1432][INFO    ][10093] Starting a new job with PID 10093
2019-11-16 16:59:33,733 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][10093] Executing command 'calicoctl node status' in directory '/root'
2019-11-16 16:59:33,799 [salt.minion      :1711][INFO    ][10093] Returning information for job: 20191116165933687300
2019-11-16 16:59:34,427 [salt.minion      :1308][INFO    ][4528] User sudo_ubuntu Executing command cmd.run with jid 20191116165934415277
2019-11-16 16:59:34,445 [salt.minion      :1432][INFO    ][10112] Starting a new job with PID 10112
2019-11-16 16:59:34,456 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][10112] Executing command 'calicoctl get ippool' in directory '/root'
2019-11-16 16:59:34,581 [salt.minion      :1711][INFO    ][10112] Returning information for job: 20191116165934415277
2019-11-16 16:59:35,193 [salt.minion      :1308][INFO    ][4528] User sudo_ubuntu Executing command state.sls with jid 20191116165935179717
2019-11-16 16:59:35,217 [salt.minion      :1432][INFO    ][10133] Starting a new job with PID 10133
2019-11-16 16:59:41,525 [salt.state       :915 ][INFO    ][10133] Loading fresh modules for state activity
2019-11-16 16:59:41,618 [salt.fileclient  :1219][INFO    ][10133] Fetching file from saltenv 'base', ** done ** 'etcd/server/setup.sls'
2019-11-16 16:59:41,664 [salt.state       :1780][INFO    ][10133] Running state [/calico/ipam/v2/assignment/ipv4/block/192.168.0.0-16] at time 16:59:41.664468
2019-11-16 16:59:41,664 [salt.state       :1813][INFO    ][10133] Executing state etcd.set for [/calico/ipam/v2/assignment/ipv4/block/192.168.0.0-16]
2019-11-16 16:59:41,669 [salt.loaded.int.utils.etcd_util:200 ][ERROR   ][10133] etcd: Key not found : /calico
2019-11-16 16:59:41,672 [salt.state       :300 ][INFO    ][10133] {'/calico/ipam/v2/assignment/ipv4/block/192.168.0.0-16': '{"masquerade":true,"cidr":"192.168.0.0/16"}'}
2019-11-16 16:59:41,672 [salt.state       :1951][INFO    ][10133] Completed state [/calico/ipam/v2/assignment/ipv4/block/192.168.0.0-16] at time 16:59:41.672739 duration_in_ms=8.272
2019-11-16 16:59:41,674 [salt.minion      :1711][INFO    ][10133] Returning information for job: 20191116165935179717
2019-11-16 16:59:42,238 [salt.minion      :1308][INFO    ][4528] User sudo_ubuntu Executing command state.sls with jid 20191116165942225753
2019-11-16 16:59:42,259 [salt.minion      :1432][INFO    ][10148] Starting a new job with PID 10148
2019-11-16 16:59:43,400 [salt.state       :915 ][INFO    ][10148] Loading fresh modules for state activity
2019-11-16 16:59:43,512 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/init.sls'
2019-11-16 16:59:43,557 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/init.sls'
2019-11-16 16:59:43,617 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:43,763 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:43,930 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/service.sls'
2019-11-16 16:59:43,978 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:44,121 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:44,263 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:44,408 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:44,654 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:44,805 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:45,158 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:45,297 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:45,469 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:45,612 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:45,771 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:45,914 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:46,137 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/controller.sls'
2019-11-16 16:59:46,263 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:46,415 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:46,585 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:46,781 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:46,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:47,095 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:47,237 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command 'date "+%FT%TZ"' in directory '/root'
2019-11-16 16:59:47,314 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/setup.sls'
2019-11-16 16:59:47,375 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:47,538 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:47,695 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:47,839 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:48,090 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:48,243 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:48,396 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:48,546 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:48,794 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:48,950 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:49,112 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:49,266 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:49,529 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:49,664 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:49,824 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:50,005 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:50,225 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:50,368 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:50,579 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/control/init.sls'
2019-11-16 16:59:50,632 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:50,777 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:50,934 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/control/service.sls'
2019-11-16 16:59:51,018 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:51,161 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:51,343 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/control/role.sls'
2019-11-16 16:59:51,414 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:51,561 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:52,476 [salt.state       :1780][INFO    ][10148] Running state [curl] at time 16:59:52.476697
2019-11-16 16:59:52,477 [salt.state       :1813][INFO    ][10148] Executing state pkg.installed for [curl]
2019-11-16 16:59:52,477 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-16 16:59:53,333 [salt.state       :300 ][INFO    ][10148] All specified packages are already installed
2019-11-16 16:59:53,334 [salt.state       :1951][INFO    ][10148] Completed state [curl] at time 16:59:53.334336 duration_in_ms=857.641
2019-11-16 16:59:53,334 [salt.state       :1780][INFO    ][10148] Running state [git] at time 16:59:53.334686
2019-11-16 16:59:53,335 [salt.state       :1813][INFO    ][10148] Executing state pkg.installed for [git]
2019-11-16 16:59:53,344 [salt.state       :300 ][INFO    ][10148] All specified packages are already installed
2019-11-16 16:59:53,344 [salt.state       :1951][INFO    ][10148] Completed state [git] at time 16:59:53.344180 duration_in_ms=9.494
2019-11-16 16:59:53,344 [salt.state       :1780][INFO    ][10148] Running state [apt-transport-https] at time 16:59:53.344412
2019-11-16 16:59:53,344 [salt.state       :1813][INFO    ][10148] Executing state pkg.installed for [apt-transport-https]
2019-11-16 16:59:53,353 [salt.state       :300 ][INFO    ][10148] All specified packages are already installed
2019-11-16 16:59:53,353 [salt.state       :1951][INFO    ][10148] Completed state [apt-transport-https] at time 16:59:53.353315 duration_in_ms=8.902
2019-11-16 16:59:53,353 [salt.state       :1780][INFO    ][10148] Running state [python-apt] at time 16:59:53.353556
2019-11-16 16:59:53,353 [salt.state       :1813][INFO    ][10148] Executing state pkg.installed for [python-apt]
2019-11-16 16:59:53,362 [salt.state       :300 ][INFO    ][10148] All specified packages are already installed
2019-11-16 16:59:53,362 [salt.state       :1951][INFO    ][10148] Completed state [python-apt] at time 16:59:53.362308 duration_in_ms=8.752
2019-11-16 16:59:53,362 [salt.state       :1780][INFO    ][10148] Running state [socat] at time 16:59:53.362542
2019-11-16 16:59:53,362 [salt.state       :1813][INFO    ][10148] Executing state pkg.installed for [socat]
2019-11-16 16:59:53,371 [salt.state       :300 ][INFO    ][10148] All specified packages are already installed
2019-11-16 16:59:53,371 [salt.state       :1951][INFO    ][10148] Completed state [socat] at time 16:59:53.371696 duration_in_ms=9.154
2019-11-16 16:59:53,371 [salt.state       :1780][INFO    ][10148] Running state [openssl] at time 16:59:53.371941
2019-11-16 16:59:53,372 [salt.state       :1813][INFO    ][10148] Executing state pkg.installed for [openssl]
2019-11-16 16:59:53,380 [salt.state       :300 ][INFO    ][10148] All specified packages are already installed
2019-11-16 16:59:53,380 [salt.state       :1951][INFO    ][10148] Completed state [openssl] at time 16:59:53.380915 duration_in_ms=8.973
2019-11-16 16:59:53,381 [salt.state       :1780][INFO    ][10148] Running state [conntrack] at time 16:59:53.381149
2019-11-16 16:59:53,381 [salt.state       :1813][INFO    ][10148] Executing state pkg.installed for [conntrack]
2019-11-16 16:59:53,389 [salt.state       :300 ][INFO    ][10148] All specified packages are already installed
2019-11-16 16:59:53,389 [salt.state       :1951][INFO    ][10148] Completed state [conntrack] at time 16:59:53.389847 duration_in_ms=8.698
2019-11-16 16:59:53,390 [salt.state       :1780][INFO    ][10148] Running state [nfs-common] at time 16:59:53.390090
2019-11-16 16:59:53,390 [salt.state       :1813][INFO    ][10148] Executing state pkg.installed for [nfs-common]
2019-11-16 16:59:53,398 [salt.state       :300 ][INFO    ][10148] All specified packages are already installed
2019-11-16 16:59:53,398 [salt.state       :1951][INFO    ][10148] Completed state [nfs-common] at time 16:59:53.398909 duration_in_ms=8.819
2019-11-16 16:59:53,399 [salt.state       :1780][INFO    ][10148] Running state [cifs-utils] at time 16:59:53.399137
2019-11-16 16:59:53,399 [salt.state       :1813][INFO    ][10148] Executing state pkg.installed for [cifs-utils]
2019-11-16 16:59:53,407 [salt.state       :300 ][INFO    ][10148] All specified packages are already installed
2019-11-16 16:59:53,408 [salt.state       :1951][INFO    ][10148] Completed state [cifs-utils] at time 16:59:53.408019 duration_in_ms=8.882
2019-11-16 16:59:53,411 [salt.state       :1780][INFO    ][10148] Running state [/usr/bin/hyperkube] at time 16:59:53.411229
2019-11-16 16:59:53,411 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/usr/bin/hyperkube]
2019-11-16 16:59:54,138 [salt.state       :300 ][INFO    ][10148] File /usr/bin/hyperkube is in the correct state
2019-11-16 16:59:54,139 [salt.state       :1951][INFO    ][10148] Completed state [/usr/bin/hyperkube] at time 16:59:54.139055 duration_in_ms=727.825
2019-11-16 16:59:54,140 [salt.state       :1780][INFO    ][10148] Running state [/usr/bin/kubectl] at time 16:59:54.140233
2019-11-16 16:59:54,140 [salt.state       :1813][INFO    ][10148] Executing state file.symlink for [/usr/bin/kubectl]
2019-11-16 16:59:54,142 [salt.state       :300 ][INFO    ][10148] Symlink /usr/bin/kubectl is present and owned by root:root
2019-11-16 16:59:54,142 [salt.state       :1951][INFO    ][10148] Completed state [/usr/bin/kubectl] at time 16:59:54.142345 duration_in_ms=2.112
2019-11-16 16:59:54,142 [salt.state       :1780][INFO    ][10148] Running state [containerd] at time 16:59:54.142576
2019-11-16 16:59:54,142 [salt.state       :1813][INFO    ][10148] Executing state pkg.installed for [containerd]
2019-11-16 16:59:54,151 [salt.state       :300 ][INFO    ][10148] All specified packages are already installed
2019-11-16 16:59:54,151 [salt.state       :1951][INFO    ][10148] Completed state [containerd] at time 16:59:54.151801 duration_in_ms=9.225
2019-11-16 16:59:54,152 [salt.state       :1780][INFO    ][10148] Running state [/etc/containerd/config.toml] at time 16:59:54.152028
2019-11-16 16:59:54,152 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/containerd/config.toml]
2019-11-16 16:59:54,213 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:54,355 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:54,505 [salt.state       :300 ][INFO    ][10148] File /etc/containerd/config.toml is in the correct state
2019-11-16 16:59:54,506 [salt.state       :1951][INFO    ][10148] Completed state [/etc/containerd/config.toml] at time 16:59:54.506372 duration_in_ms=354.342
2019-11-16 16:59:54,508 [salt.state       :1780][INFO    ][10148] Running state [containerd] at time 16:59:54.508892
2019-11-16 16:59:54,509 [salt.state       :1813][INFO    ][10148] Executing state service.running for [containerd]
2019-11-16 16:59:54,509 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'status', 'containerd.service', '-n', '0'] in directory '/root'
2019-11-16 16:59:54,553 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-11-16 16:59:54,573 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-enabled', 'containerd.service'] in directory '/root'
2019-11-16 16:59:54,598 [salt.state       :300 ][INFO    ][10148] The service containerd is already running
2019-11-16 16:59:54,599 [salt.state       :1951][INFO    ][10148] Completed state [containerd] at time 16:59:54.599045 duration_in_ms=90.151
2019-11-16 16:59:54,602 [salt.state       :1780][INFO    ][10148] Running state [/tmp/crictl] at time 16:59:54.602859
2019-11-16 16:59:54,603 [salt.state       :1813][INFO    ][10148] Executing state archive.extracted for [/tmp/crictl]
2019-11-16 16:59:55,047 [salt.state       :300 ][INFO    ][10148] All files in archive are already present
2019-11-16 16:59:55,047 [salt.state       :1951][INFO    ][10148] Completed state [/tmp/crictl] at time 16:59:55.047739 duration_in_ms=444.88
2019-11-16 16:59:55,048 [salt.state       :1780][INFO    ][10148] Running state [/usr/local/bin/crictl] at time 16:59:55.048858
2019-11-16 16:59:55,049 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/usr/local/bin/crictl]
2019-11-16 16:59:55,049 [salt.loaded.int.states.file:1019][WARNING ][10148] Use of argument owner found, "owner" is invalid, please use "user"
2019-11-16 16:59:55,273 [salt.state       :300 ][INFO    ][10148] File /usr/local/bin/crictl is in the correct state
2019-11-16 16:59:55,273 [salt.state       :1951][INFO    ][10148] Completed state [/usr/local/bin/crictl] at time 16:59:55.273366 duration_in_ms=224.507
2019-11-16 16:59:55,273 [salt.state       :1780][INFO    ][10148] Running state [/etc/crictl.yaml] at time 16:59:55.273644
2019-11-16 16:59:55,273 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/crictl.yaml]
2019-11-16 16:59:55,275 [salt.state       :300 ][INFO    ][10148] File /etc/crictl.yaml is in the correct state
2019-11-16 16:59:55,275 [salt.state       :1951][INFO    ][10148] Completed state [/etc/crictl.yaml] at time 16:59:55.275699 duration_in_ms=2.055
2019-11-16 16:59:55,275 [salt.state       :1780][INFO    ][10148] Running state [/etc/criproxy] at time 16:59:55.275933
2019-11-16 16:59:55,276 [salt.state       :1813][INFO    ][10148] Executing state file.absent for [/etc/criproxy]
2019-11-16 16:59:55,276 [salt.state       :300 ][INFO    ][10148] File /etc/criproxy is not present
2019-11-16 16:59:55,276 [salt.state       :1951][INFO    ][10148] Completed state [/etc/criproxy] at time 16:59:55.276692 duration_in_ms=0.759
2019-11-16 16:59:55,276 [salt.state       :1780][INFO    ][10148] Running state [criproxy] at time 16:59:55.276914
2019-11-16 16:59:55,277 [salt.state       :1813][INFO    ][10148] Executing state service.dead for [criproxy]
2019-11-16 16:59:55,277 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'status', 'criproxy.service', '-n', '0'] in directory '/root'
2019-11-16 16:59:55,299 [salt.state       :300 ][INFO    ][10148] The named service criproxy is not available
2019-11-16 16:59:55,300 [salt.state       :1951][INFO    ][10148] Completed state [criproxy] at time 16:59:55.300230 duration_in_ms=23.315
2019-11-16 16:59:55,300 [salt.state       :1780][INFO    ][10148] Running state [/etc/systemd/system/kubelet.service] at time 16:59:55.300744
2019-11-16 16:59:55,301 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/systemd/system/kubelet.service]
2019-11-16 16:59:55,367 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:55,504 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:55,661 [salt.state       :300 ][INFO    ][10148] File /etc/systemd/system/kubelet.service is in the correct state
2019-11-16 16:59:55,662 [salt.state       :1951][INFO    ][10148] Completed state [/etc/systemd/system/kubelet.service] at time 16:59:55.662019 duration_in_ms=361.274
2019-11-16 16:59:55,662 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/config] at time 16:59:55.662399
2019-11-16 16:59:55,662 [salt.state       :1813][INFO    ][10148] Executing state file.absent for [/etc/kubernetes/config]
2019-11-16 16:59:55,663 [salt.state       :300 ][INFO    ][10148] File /etc/kubernetes/config is not present
2019-11-16 16:59:55,663 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/config] at time 16:59:55.663391 duration_in_ms=0.992
2019-11-16 16:59:55,663 [salt.state       :1780][INFO    ][10148] Running state [/etc/default/kubelet] at time 16:59:55.663655
2019-11-16 16:59:55,663 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/default/kubelet]
2019-11-16 16:59:55,758 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:55,899 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:56,047 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:56,204 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:56,383 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:56,527 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:56,667 [salt.state       :300 ][INFO    ][10148] File /etc/default/kubelet is in the correct state
2019-11-16 16:59:56,668 [salt.state       :1951][INFO    ][10148] Completed state [/etc/default/kubelet] at time 16:59:56.667990 duration_in_ms=1004.333
2019-11-16 16:59:56,668 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/kubelet.kubeconfig] at time 16:59:56.668471
2019-11-16 16:59:56,668 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/kubelet.kubeconfig]
2019-11-16 16:59:56,736 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:56,862 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:57,004 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:57,144 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:57,291 [salt.state       :300 ][INFO    ][10148] File /etc/kubernetes/kubelet.kubeconfig is in the correct state
2019-11-16 16:59:57,291 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/kubelet.kubeconfig] at time 16:59:57.291456 duration_in_ms=622.985
2019-11-16 16:59:57,291 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/manifests] at time 16:59:57.291877
2019-11-16 16:59:57,292 [salt.state       :1813][INFO    ][10148] Executing state file.directory for [/etc/kubernetes/manifests]
2019-11-16 16:59:57,293 [salt.state       :300 ][INFO    ][10148] Directory /etc/kubernetes/manifests is in the correct state
Directory /etc/kubernetes/manifests updated
2019-11-16 16:59:57,293 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/manifests] at time 16:59:57.293619 duration_in_ms=1.742
2019-11-16 16:59:57,297 [salt.state       :1780][INFO    ][10148] Running state [kubelet] at time 16:59:57.297709
2019-11-16 16:59:57,298 [salt.state       :1813][INFO    ][10148] Executing state service.running for [kubelet]
2019-11-16 16:59:57,298 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'status', 'kubelet.service', '-n', '0'] in directory '/root'
2019-11-16 16:59:57,327 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-11-16 16:59:57,336 [salt.minion      :1308][INFO    ][4528] User sudo_ubuntu Executing command saltutil.find_job with jid 20191116165957322174
2019-11-16 16:59:57,350 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-11-16 16:59:57,358 [salt.minion      :1432][INFO    ][11244] Starting a new job with PID 11244
2019-11-16 16:59:57,374 [salt.state       :300 ][INFO    ][10148] The service kubelet is already running
2019-11-16 16:59:57,375 [salt.state       :1951][INFO    ][10148] Completed state [kubelet] at time 16:59:57.375320 duration_in_ms=77.608
2019-11-16 16:59:57,376 [salt.state       :1780][INFO    ][10148] Running state [/etc/logrotate.d/kubernetes] at time 16:59:57.376132
2019-11-16 16:59:57,376 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/logrotate.d/kubernetes]
2019-11-16 16:59:57,380 [salt.minion      :1711][INFO    ][11244] Returning information for job: 20191116165957322174
2019-11-16 16:59:57,403 [salt.state       :300 ][INFO    ][10148] File /etc/logrotate.d/kubernetes is in the correct state
2019-11-16 16:59:57,403 [salt.state       :1951][INFO    ][10148] Completed state [/etc/logrotate.d/kubernetes] at time 16:59:57.403802 duration_in_ms=27.669
2019-11-16 16:59:57,404 [salt.state       :1780][INFO    ][10148] Running state [/opt/cni/bin] at time 16:59:57.404041
2019-11-16 16:59:57,404 [salt.state       :1813][INFO    ][10148] Executing state archive.extracted for [/opt/cni/bin]
2019-11-16 16:59:58,410 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:59,100 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['tar', '--version'] in directory '/root'
2019-11-16 16:59:59,116 [salt.state       :300 ][INFO    ][10148] {'extracted_files': 'no tar output so far'}
2019-11-16 16:59:59,117 [salt.state       :1951][INFO    ][10148] Completed state [/opt/cni/bin] at time 16:59:59.116920 duration_in_ms=1712.875
2019-11-16 16:59:59,117 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/addons] at time 16:59:59.117430
2019-11-16 16:59:59,117 [salt.state       :1813][INFO    ][10148] Executing state file.directory for [/etc/kubernetes/addons]
2019-11-16 16:59:59,119 [salt.state       :300 ][INFO    ][10148] Directory /etc/kubernetes/addons is in the correct state
Directory /etc/kubernetes/addons updated
2019-11-16 16:59:59,120 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/addons] at time 16:59:59.120120 duration_in_ms=2.691
2019-11-16 16:59:59,120 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/addons/calico/calico-kube-controllers.yml] at time 16:59:59.120509
2019-11-16 16:59:59,121 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/addons/calico/calico-kube-controllers.yml]
2019-11-16 16:59:59,193 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:59,357 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:59,502 [salt.state       :300 ][INFO    ][10148] File /etc/kubernetes/addons/calico/calico-kube-controllers.yml is in the correct state
2019-11-16 16:59:59,502 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/addons/calico/calico-kube-controllers.yml] at time 16:59:59.502734 duration_in_ms=382.223
2019-11-16 16:59:59,503 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/addons/calico/calico-rbac.yml] at time 16:59:59.503093
2019-11-16 16:59:59,503 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/addons/calico/calico-rbac.yml]
2019-11-16 16:59:59,527 [salt.state       :300 ][INFO    ][10148] File /etc/kubernetes/addons/calico/calico-rbac.yml is in the correct state
2019-11-16 16:59:59,528 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/addons/calico/calico-rbac.yml] at time 16:59:59.528024 duration_in_ms=24.93
2019-11-16 16:59:59,528 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/addons/netchecker/netchecker-svc.yml] at time 16:59:59.528271
2019-11-16 16:59:59,528 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-svc.yml]
2019-11-16 16:59:59,588 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:59,737 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 16:59:59,885 [salt.state       :300 ][INFO    ][10148] File /etc/kubernetes/addons/netchecker/netchecker-svc.yml is in the correct state
2019-11-16 16:59:59,886 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/addons/netchecker/netchecker-svc.yml] at time 16:59:59.886020 duration_in_ms=357.747
2019-11-16 16:59:59,886 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/addons/netchecker/netchecker-server.yml] at time 16:59:59.886528
2019-11-16 16:59:59,887 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-server.yml]
2019-11-16 16:59:59,960 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:00,168 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:00,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:00,470 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:00,648 [salt.state       :300 ][INFO    ][10148] File /etc/kubernetes/addons/netchecker/netchecker-server.yml is in the correct state
2019-11-16 17:00:00,649 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/addons/netchecker/netchecker-server.yml] at time 17:00:00.649720 duration_in_ms=763.19
2019-11-16 17:00:00,650 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/addons/netchecker/netchecker-agent.yml] at time 17:00:00.650109
2019-11-16 17:00:00,651 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-agent.yml]
2019-11-16 17:00:00,728 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:00,858 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:00,993 [salt.state       :300 ][INFO    ][10148] File /etc/kubernetes/addons/netchecker/netchecker-agent.yml is in the correct state
2019-11-16 17:00:00,994 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/addons/netchecker/netchecker-agent.yml] at time 17:00:00.994144 duration_in_ms=344.034
2019-11-16 17:00:00,994 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml] at time 17:00:00.994562
2019-11-16 17:00:00,995 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml]
2019-11-16 17:00:01,020 [salt.state       :300 ][INFO    ][10148] File /etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml is in the correct state
2019-11-16 17:00:01,020 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml] at time 17:00:01.020763 duration_in_ms=26.2
2019-11-16 17:00:01,021 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/addons/netchecker/netchecker-roles.yml] at time 17:00:01.021068
2019-11-16 17:00:01,021 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-roles.yml]
2019-11-16 17:00:01,049 [salt.state       :300 ][INFO    ][10148] File /etc/kubernetes/addons/netchecker/netchecker-roles.yml is in the correct state
2019-11-16 17:00:01,049 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/addons/netchecker/netchecker-roles.yml] at time 17:00:01.049606 duration_in_ms=28.537
2019-11-16 17:00:01,049 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/addons/prometheus/prometheus-roles.yml] at time 17:00:01.049909
2019-11-16 17:00:01,050 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/addons/prometheus/prometheus-roles.yml]
2019-11-16 17:00:01,072 [salt.state       :300 ][INFO    ][10148] File /etc/kubernetes/addons/prometheus/prometheus-roles.yml is in the correct state
2019-11-16 17:00:01,073 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/addons/prometheus/prometheus-roles.yml] at time 17:00:01.073070 duration_in_ms=23.16
2019-11-16 17:00:01,073 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/addons/dns] at time 17:00:01.073414
2019-11-16 17:00:01,073 [salt.state       :1813][INFO    ][10148] Executing state file.absent for [/etc/kubernetes/addons/dns]
2019-11-16 17:00:01,074 [salt.state       :300 ][INFO    ][10148] {'removed': '/etc/kubernetes/addons/dns'}
2019-11-16 17:00:01,074 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/addons/dns] at time 17:00:01.074749 duration_in_ms=1.336
2019-11-16 17:00:01,076 [salt.state       :1780][INFO    ][10148] Running state [kubectl -n kube-system delete svc kube-dns > /dev/null || echo "kube-dns is absent. OK" && true] at time 17:00:01.076144
2019-11-16 17:00:01,076 [salt.state       :1813][INFO    ][10148] Executing state cmd.run for [kubectl -n kube-system delete svc kube-dns > /dev/null || echo "kube-dns is absent. OK" && true]
2019-11-16 17:00:01,076 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command 'kubectl -n kube-system delete svc kube-dns > /dev/null || echo "kube-dns is absent. OK" && true' in directory '/root'
2019-11-16 17:00:01,259 [salt.state       :300 ][INFO    ][10148] {'pid': 11440, 'retcode': 0, 'stderr': 'The connection to the server localhost:8080 was refused - did you specify the right host or port?', 'stdout': 'kube-dns is absent. OK'}
2019-11-16 17:00:01,260 [salt.state       :1951][INFO    ][10148] Completed state [kubectl -n kube-system delete svc kube-dns > /dev/null || echo "kube-dns is absent. OK" && true] at time 17:00:01.260184 duration_in_ms=184.04
2019-11-16 17:00:01,260 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/addons/coredns/coredns-cm.yml] at time 17:00:01.260595
2019-11-16 17:00:01,260 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/addons/coredns/coredns-cm.yml]
2019-11-16 17:00:01,288 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/coredns/coredns-cm.yml'
2019-11-16 17:00:01,346 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:01,492 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:01,631 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:01,770 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:01,907 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:01,907 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/addons/coredns/coredns-cm.yml] at time 17:00:01.907811 duration_in_ms=647.214
2019-11-16 17:00:01,908 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/addons/coredns/coredns-deploy.yml] at time 17:00:01.908232
2019-11-16 17:00:01,908 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/addons/coredns/coredns-deploy.yml]
2019-11-16 17:00:01,937 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/coredns/coredns-deploy.yml'
2019-11-16 17:00:01,991 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:02,149 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:02,312 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:02,312 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/addons/coredns/coredns-deploy.yml] at time 17:00:02.312528 duration_in_ms=404.294
2019-11-16 17:00:02,313 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/addons/coredns/coredns-svc.yml] at time 17:00:02.312978
2019-11-16 17:00:02,313 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/addons/coredns/coredns-svc.yml]
2019-11-16 17:00:02,335 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/coredns/coredns-svc.yml'
2019-11-16 17:00:02,386 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:02,540 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:02,697 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:02,697 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/addons/coredns/coredns-svc.yml] at time 17:00:02.697692 duration_in_ms=384.711
2019-11-16 17:00:02,698 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/addons/coredns/coredns-rbac.yml] at time 17:00:02.698184
2019-11-16 17:00:02,698 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/addons/coredns/coredns-rbac.yml]
2019-11-16 17:00:02,724 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/coredns/coredns-rbac.yml'
2019-11-16 17:00:02,778 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:02,942 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:03,112 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:03,113 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/addons/coredns/coredns-rbac.yml] at time 17:00:03.113480 duration_in_ms=415.294
2019-11-16 17:00:03,114 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/addons/metrics-server] at time 17:00:03.114145
2019-11-16 17:00:03,114 [salt.state       :1813][INFO    ][10148] Executing state file.absent for [/etc/kubernetes/addons/metrics-server]
2019-11-16 17:00:03,115 [salt.state       :300 ][INFO    ][10148] File /etc/kubernetes/addons/metrics-server is not present
2019-11-16 17:00:03,115 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/addons/metrics-server] at time 17:00:03.115721 duration_in_ms=1.575
2019-11-16 17:00:03,116 [salt.state       :1780][INFO    ][10148] Running state [/srv/kubernetes/known_tokens.csv] at time 17:00:03.116208
2019-11-16 17:00:03,116 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/srv/kubernetes/known_tokens.csv]
2019-11-16 17:00:03,145 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/known_tokens.csv'
2019-11-16 17:00:03,204 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:03,415 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:03,559 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:03,732 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:03,879 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:03,880 [salt.state       :1951][INFO    ][10148] Completed state [/srv/kubernetes/known_tokens.csv] at time 17:00:03.880232 duration_in_ms=764.013
2019-11-16 17:00:03,881 [salt.state       :1780][INFO    ][10148] Running state [/srv/kubernetes/basic_auth.csv] at time 17:00:03.880986
2019-11-16 17:00:03,881 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/srv/kubernetes/basic_auth.csv]
2019-11-16 17:00:03,905 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/basic_auth.csv'
2019-11-16 17:00:03,960 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:04,098 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:04,242 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:04,243 [salt.state       :1951][INFO    ][10148] Completed state [/srv/kubernetes/basic_auth.csv] at time 17:00:04.243465 duration_in_ms=362.478
2019-11-16 17:00:04,244 [salt.state       :1780][INFO    ][10148] Running state [/etc/default/kube-apiserver] at time 17:00:04.244115
2019-11-16 17:00:04,244 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/default/kube-apiserver]
2019-11-16 17:00:04,248 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:04,248 [salt.state       :1951][INFO    ][10148] Completed state [/etc/default/kube-apiserver] at time 17:00:04.248297 duration_in_ms=4.183
2019-11-16 17:00:04,248 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/scheduler.kubeconfig] at time 17:00:04.248683
2019-11-16 17:00:04,249 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/scheduler.kubeconfig]
2019-11-16 17:00:04,273 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-scheduler/scheduler.kubeconfig'
2019-11-16 17:00:04,325 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:04,536 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:04,697 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:04,872 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:05,030 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:05,030 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/scheduler.kubeconfig] at time 17:00:05.030760 duration_in_ms=782.077
2019-11-16 17:00:05,031 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/controller-manager.kubeconfig] at time 17:00:05.031092
2019-11-16 17:00:05,031 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/controller-manager.kubeconfig]
2019-11-16 17:00:05,055 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-controller-manager/controller-manager.kubeconfig'
2019-11-16 17:00:05,115 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:05,257 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:05,405 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:05,541 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:05,691 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:05,692 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/controller-manager.kubeconfig] at time 17:00:05.692423 duration_in_ms=661.33
2019-11-16 17:00:05,692 [salt.state       :1780][INFO    ][10148] Running state [/etc/default/kube-controller-manager] at time 17:00:05.692773
2019-11-16 17:00:05,693 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/default/kube-controller-manager]
2019-11-16 17:00:05,695 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:05,695 [salt.state       :1951][INFO    ][10148] Completed state [/etc/default/kube-controller-manager] at time 17:00:05.695876 duration_in_ms=3.102
2019-11-16 17:00:05,696 [salt.state       :1780][INFO    ][10148] Running state [/etc/default/kube-scheduler] at time 17:00:05.696216
2019-11-16 17:00:05,696 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/default/kube-scheduler]
2019-11-16 17:00:05,698 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:05,699 [salt.state       :1951][INFO    ][10148] Completed state [/etc/default/kube-scheduler] at time 17:00:05.699181 duration_in_ms=2.965
2019-11-16 17:00:05,699 [salt.state       :1780][INFO    ][10148] Running state [/etc/systemd/system/kube-apiserver.service] at time 17:00:05.699517
2019-11-16 17:00:05,699 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/systemd/system/kube-apiserver.service]
2019-11-16 17:00:05,725 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-apiserver.service'
2019-11-16 17:00:05,738 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:05,738 [salt.state       :1951][INFO    ][10148] Completed state [/etc/systemd/system/kube-apiserver.service] at time 17:00:05.738681 duration_in_ms=39.163
2019-11-16 17:00:05,738 [salt.state       :1780][INFO    ][10148] Running state [/etc/systemd/system/kube-scheduler.service] at time 17:00:05.738924
2019-11-16 17:00:05,739 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/systemd/system/kube-scheduler.service]
2019-11-16 17:00:05,763 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-scheduler.service'
2019-11-16 17:00:05,773 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:05,774 [salt.state       :1951][INFO    ][10148] Completed state [/etc/systemd/system/kube-scheduler.service] at time 17:00:05.773978 duration_in_ms=35.054
2019-11-16 17:00:05,774 [salt.state       :1780][INFO    ][10148] Running state [/etc/systemd/system/kube-controller-manager.service] at time 17:00:05.774265
2019-11-16 17:00:05,774 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/systemd/system/kube-controller-manager.service]
2019-11-16 17:00:05,797 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-controller-manager.service'
2019-11-16 17:00:05,806 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:05,806 [salt.state       :1951][INFO    ][10148] Completed state [/etc/systemd/system/kube-controller-manager.service] at time 17:00:05.806835 duration_in_ms=32.569
2019-11-16 17:00:05,807 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/ssl/kubernetes-server.crt] at time 17:00:05.807110
2019-11-16 17:00:05,807 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/ssl/kubernetes-server.crt]
2019-11-16 17:00:05,830 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** '_certs/kubernetes/kubernetes-server.crt'
2019-11-16 17:00:05,832 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:05,833 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/ssl/kubernetes-server.crt] at time 17:00:05.832973 duration_in_ms=25.863
2019-11-16 17:00:05,833 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/ssl/kubernetes-server.key] at time 17:00:05.833317
2019-11-16 17:00:05,833 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/ssl/kubernetes-server.key]
2019-11-16 17:00:05,855 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** '_certs/kubernetes/kubernetes-server.key'
2019-11-16 17:00:05,857 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:05,857 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/ssl/kubernetes-server.key] at time 17:00:05.857826 duration_in_ms=24.51
2019-11-16 17:00:05,858 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/ssl/kubernetes-server.pem] at time 17:00:05.858268
2019-11-16 17:00:05,858 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/ssl/kubernetes-server.pem]
2019-11-16 17:00:05,882 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** '_certs/kubernetes/kubernetes-server.pem'
2019-11-16 17:00:05,883 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:05,884 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/ssl/kubernetes-server.pem] at time 17:00:05.884195 duration_in_ms=25.927
2019-11-16 17:00:05,892 [salt.state       :1780][INFO    ][10148] Running state [kube-apiserver] at time 17:00:05.892070
2019-11-16 17:00:05,892 [salt.state       :1813][INFO    ][10148] Executing state service.running for [kube-apiserver]
2019-11-16 17:00:05,893 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'status', 'kube-apiserver.service', '-n', '0'] in directory '/root'
2019-11-16 17:00:05,918 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-active', 'kube-apiserver.service'] in directory '/root'
2019-11-16 17:00:05,940 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-enabled', 'kube-apiserver.service'] in directory '/root'
2019-11-16 17:00:05,973 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-apiserver.service'] in directory '/root'
2019-11-16 17:00:18,014 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-active', 'kube-apiserver.service'] in directory '/root'
2019-11-16 17:00:18,033 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-enabled', 'kube-apiserver.service'] in directory '/root'
2019-11-16 17:00:18,056 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-enabled', 'kube-apiserver.service'] in directory '/root'
2019-11-16 17:00:18,088 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-apiserver.service'] in directory '/root'
2019-11-16 17:00:18,307 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-enabled', 'kube-apiserver.service'] in directory '/root'
2019-11-16 17:00:18,328 [salt.state       :300 ][INFO    ][10148] {'kube-apiserver': True}
2019-11-16 17:00:18,329 [salt.state       :1951][INFO    ][10148] Completed state [kube-apiserver] at time 17:00:18.329156 duration_in_ms=12437.084
2019-11-16 17:00:18,335 [salt.state       :1780][INFO    ][10148] Running state [kube-scheduler] at time 17:00:18.335871
2019-11-16 17:00:18,336 [salt.state       :1813][INFO    ][10148] Executing state service.running for [kube-scheduler]
2019-11-16 17:00:18,337 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'status', 'kube-scheduler.service', '-n', '0'] in directory '/root'
2019-11-16 17:00:18,361 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-active', 'kube-scheduler.service'] in directory '/root'
2019-11-16 17:00:18,379 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-enabled', 'kube-scheduler.service'] in directory '/root'
2019-11-16 17:00:18,411 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-scheduler.service'] in directory '/root'
2019-11-16 17:00:18,442 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-active', 'kube-scheduler.service'] in directory '/root'
2019-11-16 17:00:18,457 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-enabled', 'kube-scheduler.service'] in directory '/root'
2019-11-16 17:00:18,479 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-enabled', 'kube-scheduler.service'] in directory '/root'
2019-11-16 17:00:18,509 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-scheduler.service'] in directory '/root'
2019-11-16 17:00:18,699 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-enabled', 'kube-scheduler.service'] in directory '/root'
2019-11-16 17:00:18,717 [salt.state       :300 ][INFO    ][10148] {'kube-scheduler': True}
2019-11-16 17:00:18,717 [salt.state       :1951][INFO    ][10148] Completed state [kube-scheduler] at time 17:00:18.717323 duration_in_ms=381.451
2019-11-16 17:00:18,722 [salt.state       :1780][INFO    ][10148] Running state [kube-controller-manager] at time 17:00:18.722018
2019-11-16 17:00:18,722 [salt.state       :1813][INFO    ][10148] Executing state service.running for [kube-controller-manager]
2019-11-16 17:00:18,723 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'status', 'kube-controller-manager.service', '-n', '0'] in directory '/root'
2019-11-16 17:00:18,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-active', 'kube-controller-manager.service'] in directory '/root'
2019-11-16 17:00:18,760 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-enabled', 'kube-controller-manager.service'] in directory '/root'
2019-11-16 17:00:18,789 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-controller-manager.service'] in directory '/root'
2019-11-16 17:00:18,827 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-active', 'kube-controller-manager.service'] in directory '/root'
2019-11-16 17:00:18,844 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-enabled', 'kube-controller-manager.service'] in directory '/root'
2019-11-16 17:00:18,872 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-enabled', 'kube-controller-manager.service'] in directory '/root'
2019-11-16 17:00:18,905 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-controller-manager.service'] in directory '/root'
2019-11-16 17:00:19,076 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-enabled', 'kube-controller-manager.service'] in directory '/root'
2019-11-16 17:00:19,093 [salt.state       :300 ][INFO    ][10148] {'kube-controller-manager': True}
2019-11-16 17:00:19,093 [salt.state       :1951][INFO    ][10148] Completed state [kube-controller-manager] at time 17:00:19.093905 duration_in_ms=371.886
2019-11-16 17:00:19,094 [salt.state       :1780][INFO    ][10148] Running state [kubectl create ns "netchecker"] at time 17:00:19.094336
2019-11-16 17:00:19,094 [salt.state       :1813][INFO    ][10148] Executing state cmd.run for [kubectl create ns "netchecker"]
2019-11-16 17:00:19,095 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command 'kubectl get ns -o=custom-columns=NAME:.metadata.name | grep -v NAME | grep "netchecker"' in directory '/root'
2019-11-16 17:00:19,371 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command 'kubectl create ns "netchecker"' in directory '/root'
2019-11-16 17:00:19,515 [salt.state       :300 ][INFO    ][10148] {'pid': 12205, 'retcode': 0, 'stderr': '', 'stdout': 'namespace/netchecker created'}
2019-11-16 17:00:19,516 [salt.state       :1951][INFO    ][10148] Completed state [kubectl create ns "netchecker"] at time 17:00:19.515972 duration_in_ms=421.635
2019-11-16 17:00:19,516 [salt.state       :1780][INFO    ][10148] Running state [/usr/bin/calicoctl] at time 17:00:19.516494
2019-11-16 17:00:19,516 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/usr/bin/calicoctl]
2019-11-16 17:00:19,717 [salt.state       :300 ][INFO    ][10148] File /usr/bin/calicoctl is in the correct state
2019-11-16 17:00:19,718 [salt.state       :1951][INFO    ][10148] Completed state [/usr/bin/calicoctl] at time 17:00:19.718408 duration_in_ms=201.912
2019-11-16 17:00:19,718 [salt.state       :1780][INFO    ][10148] Running state [/usr/bin/birdcl] at time 17:00:19.718829
2019-11-16 17:00:19,719 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/usr/bin/birdcl]
2019-11-16 17:00:19,723 [salt.state       :300 ][INFO    ][10148] File /usr/bin/birdcl is in the correct state
2019-11-16 17:00:19,723 [salt.state       :1951][INFO    ][10148] Completed state [/usr/bin/birdcl] at time 17:00:19.723452 duration_in_ms=4.624
2019-11-16 17:00:19,723 [salt.state       :1780][INFO    ][10148] Running state [/opt/cni/bin/calico] at time 17:00:19.723810
2019-11-16 17:00:19,724 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/opt/cni/bin/calico]
2019-11-16 17:00:19,865 [salt.state       :300 ][INFO    ][10148] File /opt/cni/bin/calico is in the correct state
2019-11-16 17:00:19,865 [salt.state       :1951][INFO    ][10148] Completed state [/opt/cni/bin/calico] at time 17:00:19.865408 duration_in_ms=141.597
2019-11-16 17:00:19,865 [salt.state       :1780][INFO    ][10148] Running state [/opt/cni/bin/calico-ipam] at time 17:00:19.865717
2019-11-16 17:00:19,866 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/opt/cni/bin/calico-ipam]
2019-11-16 17:00:20,001 [salt.state       :300 ][INFO    ][10148] File /opt/cni/bin/calico-ipam is in the correct state
2019-11-16 17:00:20,001 [salt.state       :1951][INFO    ][10148] Completed state [/opt/cni/bin/calico-ipam] at time 17:00:20.001655 duration_in_ms=135.938
2019-11-16 17:00:20,001 [salt.state       :1780][INFO    ][10148] Running state [/etc/cni/net.d/10-calico.conf] at time 17:00:20.001954
2019-11-16 17:00:20,002 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/cni/net.d/10-calico.conf]
2019-11-16 17:00:20,071 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:20,215 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:20,369 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:20,522 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:20,669 [salt.state       :300 ][INFO    ][10148] File /etc/cni/net.d/10-calico.conf is in the correct state
2019-11-16 17:00:20,670 [salt.state       :1951][INFO    ][10148] Completed state [/etc/cni/net.d/10-calico.conf] at time 17:00:20.670445 duration_in_ms=668.488
2019-11-16 17:00:20,673 [salt.state       :1780][INFO    ][10148] Running state [/etc/calico/network-environment] at time 17:00:20.673606
2019-11-16 17:00:20,674 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/calico/network-environment]
2019-11-16 17:00:20,737 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:20,887 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:21,054 [salt.state       :300 ][INFO    ][10148] File /etc/calico/network-environment is in the correct state
2019-11-16 17:00:21,055 [salt.state       :1951][INFO    ][10148] Completed state [/etc/calico/network-environment] at time 17:00:21.055087 duration_in_ms=381.48
2019-11-16 17:00:21,055 [salt.state       :1780][INFO    ][10148] Running state [/etc/calico/calicoctl.cfg] at time 17:00:21.055561
2019-11-16 17:00:21,056 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/calico/calicoctl.cfg]
2019-11-16 17:00:21,120 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:21,272 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:21,425 [salt.state       :300 ][INFO    ][10148] File /etc/calico/calicoctl.cfg is in the correct state
2019-11-16 17:00:21,425 [salt.state       :1951][INFO    ][10148] Completed state [/etc/calico/calicoctl.cfg] at time 17:00:21.425814 duration_in_ms=370.251
2019-11-16 17:00:21,427 [salt.state       :1780][INFO    ][10148] Running state [/etc/systemd/system/calico-node.service] at time 17:00:21.427460
2019-11-16 17:00:21,427 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/systemd/system/calico-node.service]
2019-11-16 17:00:21,461 [salt.state       :300 ][INFO    ][10148] File /etc/systemd/system/calico-node.service is in the correct state
2019-11-16 17:00:21,462 [salt.state       :1951][INFO    ][10148] Completed state [/etc/systemd/system/calico-node.service] at time 17:00:21.462147 duration_in_ms=34.687
2019-11-16 17:00:21,462 [salt.state       :1780][INFO    ][10148] Running state [/var/lib/calico] at time 17:00:21.462441
2019-11-16 17:00:21,462 [salt.state       :1813][INFO    ][10148] Executing state file.directory for [/var/lib/calico]
2019-11-16 17:00:21,463 [salt.state       :300 ][INFO    ][10148] Directory /var/lib/calico is in the correct state
Directory /var/lib/calico updated
2019-11-16 17:00:21,463 [salt.state       :1951][INFO    ][10148] Completed state [/var/lib/calico] at time 17:00:21.463720 duration_in_ms=1.279
2019-11-16 17:00:21,463 [salt.state       :1780][INFO    ][10148] Running state [/var/log/calico] at time 17:00:21.463955
2019-11-16 17:00:21,464 [salt.state       :1813][INFO    ][10148] Executing state file.directory for [/var/log/calico]
2019-11-16 17:00:21,465 [salt.state       :300 ][INFO    ][10148] Directory /var/log/calico is in the correct state
Directory /var/log/calico updated
2019-11-16 17:00:21,465 [salt.state       :1951][INFO    ][10148] Completed state [/var/log/calico] at time 17:00:21.465169 duration_in_ms=1.214
2019-11-16 17:00:21,468 [salt.state       :1780][INFO    ][10148] Running state [calico-node] at time 17:00:21.468902
2019-11-16 17:00:21,469 [salt.state       :1813][INFO    ][10148] Executing state service.running for [calico-node]
2019-11-16 17:00:21,469 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'status', 'calico-node.service', '-n', '0'] in directory '/root'
2019-11-16 17:00:21,490 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-11-16 17:00:21,513 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-11-16 17:00:21,535 [salt.state       :300 ][INFO    ][10148] The service calico-node is already running
2019-11-16 17:00:21,535 [salt.state       :1951][INFO    ][10148] Completed state [calico-node] at time 17:00:21.535675 duration_in_ms=66.772
2019-11-16 17:00:21,536 [salt.state       :1780][INFO    ][10148] Running state [/etc/kubernetes/proxy.kubeconfig] at time 17:00:21.536120
2019-11-16 17:00:21,536 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/kubernetes/proxy.kubeconfig]
2019-11-16 17:00:21,603 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:21,756 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:21,909 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:22,069 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] 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-11-16 17:00:22,267 [salt.state       :300 ][INFO    ][10148] File /etc/kubernetes/proxy.kubeconfig is in the correct state
2019-11-16 17:00:22,268 [salt.state       :1951][INFO    ][10148] Completed state [/etc/kubernetes/proxy.kubeconfig] at time 17:00:22.267938 duration_in_ms=731.817
2019-11-16 17:00:22,268 [salt.state       :1780][INFO    ][10148] Running state [/etc/systemd/system/kube-proxy.service] at time 17:00:22.268293
2019-11-16 17:00:22,268 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/systemd/system/kube-proxy.service]
2019-11-16 17:00:22,288 [salt.state       :300 ][INFO    ][10148] File /etc/systemd/system/kube-proxy.service is in the correct state
2019-11-16 17:00:22,288 [salt.state       :1951][INFO    ][10148] Completed state [/etc/systemd/system/kube-proxy.service] at time 17:00:22.288616 duration_in_ms=20.323
2019-11-16 17:00:22,288 [salt.state       :1780][INFO    ][10148] Running state [/etc/default/kube-proxy] at time 17:00:22.288883
2019-11-16 17:00:22,289 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/etc/default/kube-proxy]
2019-11-16 17:00:22,290 [salt.state       :300 ][INFO    ][10148] File /etc/default/kube-proxy is in the correct state
2019-11-16 17:00:22,290 [salt.state       :1951][INFO    ][10148] Completed state [/etc/default/kube-proxy] at time 17:00:22.290791 duration_in_ms=1.909
2019-11-16 17:00:22,293 [salt.state       :1780][INFO    ][10148] Running state [kube-proxy] at time 17:00:22.293446
2019-11-16 17:00:22,293 [salt.state       :1813][INFO    ][10148] Executing state service.running for [kube-proxy]
2019-11-16 17:00:22,294 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'status', 'kube-proxy.service', '-n', '0'] in directory '/root'
2019-11-16 17:00:22,312 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-11-16 17:00:22,329 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-11-16 17:00:22,346 [salt.state       :300 ][INFO    ][10148] The service kube-proxy is already running
2019-11-16 17:00:22,346 [salt.state       :1951][INFO    ][10148] Completed state [kube-proxy] at time 17:00:22.346790 duration_in_ms=53.345
2019-11-16 17:00:22,348 [salt.state       :1780][INFO    ][10148] Running state [/srv/kubernetes] at time 17:00:22.348709
2019-11-16 17:00:22,349 [salt.state       :1813][INFO    ][10148] Executing state file.directory for [/srv/kubernetes]
2019-11-16 17:00:22,349 [salt.state       :300 ][INFO    ][10148] Directory /srv/kubernetes is in the correct state
Directory /srv/kubernetes updated
2019-11-16 17:00:22,350 [salt.state       :1951][INFO    ][10148] Completed state [/srv/kubernetes] at time 17:00:22.350141 duration_in_ms=1.432
2019-11-16 17:00:22,351 [salt.state       :1780][INFO    ][10148] Running state [/srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml] at time 17:00:22.351389
2019-11-16 17:00:22,351 [salt.state       :1813][INFO    ][10148] Executing state file.managed for [/srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml]
2019-11-16 17:00:22,372 [salt.fileclient  :1219][INFO    ][10148] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/rolebinding.yml'
2019-11-16 17:00:22,390 [salt.state       :300 ][INFO    ][10148] File changed:
New file
2019-11-16 17:00:22,390 [salt.state       :1951][INFO    ][10148] Completed state [/srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml] at time 17:00:22.390946 duration_in_ms=39.557
2019-11-16 17:00:22,392 [salt.state       :1780][INFO    ][10148] Running state [kubectl apply -f /srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml] at time 17:00:22.392241
2019-11-16 17:00:22,392 [salt.state       :1813][INFO    ][10148] Executing state cmd.run for [kubectl apply -f /srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml]
2019-11-16 17:00:22,393 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10148] Executing command 'kubectl apply -f /srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml' in directory '/root'
2019-11-16 17:00:22,775 [salt.state       :300 ][INFO    ][10148] {'pid': 12525, 'retcode': 0, 'stderr': '', 'stdout': 'clusterrolebinding.rbac.authorization.k8s.io/root-cluster-admin-binding created'}
2019-11-16 17:00:22,775 [salt.state       :1951][INFO    ][10148] Completed state [kubectl apply -f /srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml] at time 17:00:22.775752 duration_in_ms=383.51
2019-11-16 17:00:22,781 [salt.minion      :1711][INFO    ][10148] Returning information for job: 20191116165942225753
2019-11-16 17:00:23,481 [salt.minion      :1308][INFO    ][4528] User sudo_ubuntu Executing command state.sls with jid 20191116170023465408
2019-11-16 17:00:23,496 [salt.minion      :1432][INFO    ][12550] Starting a new job with PID 12550
2019-11-16 17:00:29,702 [salt.state       :915 ][INFO    ][12550] Loading fresh modules for state activity
2019-11-16 17:00:29,872 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12550] 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-11-16 17:00:30,016 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12550] 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-11-16 17:00:30,161 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12550] 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-11-16 17:00:30,304 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12550] 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-11-16 17:00:30,493 [salt.state       :1780][INFO    ][12550] Running state [/etc/kubernetes/kubeconfig.sh] at time 17:00:30.493430
2019-11-16 17:00:30,494 [salt.state       :1813][INFO    ][12550] Executing state file.managed for [/etc/kubernetes/kubeconfig.sh]
2019-11-16 17:00:30,546 [salt.fileclient  :1219][INFO    ][12550] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubeconfig.sh'
2019-11-16 17:00:30,596 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12550] 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-11-16 17:00:30,734 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12550] 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-11-16 17:00:30,893 [salt.state       :300 ][INFO    ][12550] File changed:
New file
2019-11-16 17:00:30,894 [salt.state       :1951][INFO    ][12550] Completed state [/etc/kubernetes/kubeconfig.sh] at time 17:00:30.894338 duration_in_ms=400.907
2019-11-16 17:00:30,896 [salt.state       :1780][INFO    ][12550] Running state [/etc/kubernetes/kubeconfig.sh > /etc/kubernetes/admin-kube-config] at time 17:00:30.896231
2019-11-16 17:00:30,896 [salt.state       :1813][INFO    ][12550] Executing state cmd.run for [/etc/kubernetes/kubeconfig.sh > /etc/kubernetes/admin-kube-config]
2019-11-16 17:00:30,897 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12550] Executing command '/etc/kubernetes/kubeconfig.sh > /etc/kubernetes/admin-kube-config' in directory '/root'
2019-11-16 17:00:30,955 [salt.state       :300 ][INFO    ][12550] {'pid': 12693, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-16 17:00:30,955 [salt.state       :1951][INFO    ][12550] Completed state [/etc/kubernetes/kubeconfig.sh > /etc/kubernetes/admin-kube-config] at time 17:00:30.955711 duration_in_ms=59.48
2019-11-16 17:00:30,956 [salt.state       :1780][INFO    ][12550] Running state [/etc/kubernetes/addons/namespace.yaml] at time 17:00:30.956295
2019-11-16 17:00:30,957 [salt.state       :1813][INFO    ][12550] Executing state file.managed for [/etc/kubernetes/addons/namespace.yaml]
2019-11-16 17:00:30,982 [salt.fileclient  :1219][INFO    ][12550] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addon-manager/namespace.yaml'
2019-11-16 17:00:30,984 [salt.state       :300 ][INFO    ][12550] File changed:
New file
2019-11-16 17:00:30,984 [salt.state       :1951][INFO    ][12550] Completed state [/etc/kubernetes/addons/namespace.yaml] at time 17:00:30.984790 duration_in_ms=28.495
2019-11-16 17:00:30,985 [salt.state       :1780][INFO    ][12550] Running state [/etc/kubernetes/manifests/kube-addon-manager.yml] at time 17:00:30.985108
2019-11-16 17:00:30,985 [salt.state       :1813][INFO    ][12550] Executing state file.managed for [/etc/kubernetes/manifests/kube-addon-manager.yml]
2019-11-16 17:00:31,012 [salt.fileclient  :1219][INFO    ][12550] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/manifest/kube-addon-manager.yml'
2019-11-16 17:00:31,068 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12550] 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-11-16 17:00:31,243 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12550] 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-11-16 17:00:31,384 [salt.state       :300 ][INFO    ][12550] File changed:
New file
2019-11-16 17:00:31,384 [salt.state       :1951][INFO    ][12550] Completed state [/etc/kubernetes/manifests/kube-addon-manager.yml] at time 17:00:31.384410 duration_in_ms=399.302
2019-11-16 17:00:31,384 [salt.state       :1780][INFO    ][12550] Running state [/etc/default/kube-addon-manager] at time 17:00:31.384744
2019-11-16 17:00:31,385 [salt.state       :1813][INFO    ][12550] Executing state file.absent for [/etc/default/kube-addon-manager]
2019-11-16 17:00:31,385 [salt.state       :300 ][INFO    ][12550] File /etc/default/kube-addon-manager is not present
2019-11-16 17:00:31,385 [salt.state       :1951][INFO    ][12550] Completed state [/etc/default/kube-addon-manager] at time 17:00:31.385647 duration_in_ms=0.902
2019-11-16 17:00:31,385 [salt.state       :1780][INFO    ][12550] Running state [/usr/bin/kube-addons.sh] at time 17:00:31.385878
2019-11-16 17:00:31,386 [salt.state       :1813][INFO    ][12550] Executing state file.absent for [/usr/bin/kube-addons.sh]
2019-11-16 17:00:31,386 [salt.state       :300 ][INFO    ][12550] File /usr/bin/kube-addons.sh is not present
2019-11-16 17:00:31,386 [salt.state       :1951][INFO    ][12550] Completed state [/usr/bin/kube-addons.sh] at time 17:00:31.386622 duration_in_ms=0.744
2019-11-16 17:00:31,855 [salt.state       :1780][INFO    ][12550] Running state [kube-addon-manager] at time 17:00:31.855141
2019-11-16 17:00:31,855 [salt.state       :1813][INFO    ][12550] Executing state service.dead for [kube-addon-manager]
2019-11-16 17:00:31,856 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12550] Executing command ['systemctl', 'status', 'kube-addon-manager.service', '-n', '0'] in directory '/root'
2019-11-16 17:00:31,889 [salt.state       :300 ][INFO    ][12550] The named service kube-addon-manager is not available
2019-11-16 17:00:31,889 [salt.state       :1951][INFO    ][12550] Completed state [kube-addon-manager] at time 17:00:31.889695 duration_in_ms=34.555
2019-11-16 17:00:31,890 [salt.state       :1780][INFO    ][12550] Running state [/etc/systemd/system/kube-addon-manager.service] at time 17:00:31.890160
2019-11-16 17:00:31,890 [salt.state       :1813][INFO    ][12550] Executing state file.absent for [/etc/systemd/system/kube-addon-manager.service]
2019-11-16 17:00:31,891 [salt.state       :300 ][INFO    ][12550] File /etc/systemd/system/kube-addon-manager.service is not present
2019-11-16 17:00:31,891 [salt.state       :1951][INFO    ][12550] Completed state [/etc/systemd/system/kube-addon-manager.service] at time 17:00:31.891888 duration_in_ms=1.728
2019-11-16 17:00:31,892 [salt.state       :1780][INFO    ][12550] Running state [/srv/kubernetes/conformance.yml] at time 17:00:31.892256
2019-11-16 17:00:31,892 [salt.state       :1813][INFO    ][12550] Executing state file.managed for [/srv/kubernetes/conformance.yml]
2019-11-16 17:00:31,914 [salt.fileclient  :1219][INFO    ][12550] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/conformance/conformance.yml'
2019-11-16 17:00:31,968 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12550] 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-11-16 17:00:32,152 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12550] 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-11-16 17:00:32,302 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12550] 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-11-16 17:00:32,457 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12550] 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-11-16 17:00:32,657 [salt.state       :300 ][INFO    ][12550] File changed:
New file
2019-11-16 17:00:32,658 [salt.state       :1951][INFO    ][12550] Completed state [/srv/kubernetes/conformance.yml] at time 17:00:32.658245 duration_in_ms=765.988
2019-11-16 17:00:32,660 [salt.minion      :1711][INFO    ][12550] Returning information for job: 20191116170023465408
2019-11-16 17:00:33,209 [salt.minion      :1308][INFO    ][4528] User sudo_ubuntu Executing command cp.push with jid 20191116170033198322
2019-11-16 17:00:33,230 [salt.minion      :1432][INFO    ][12823] Starting a new job with PID 12823
2019-11-16 17:00:33,261 [salt.minion      :1711][INFO    ][12823] Returning information for job: 20191116170033198322
2019-11-16 17:00:33,983 [salt.minion      :1308][INFO    ][4528] User sudo_ubuntu Executing command cp.push_dir with jid 20191116170033971440
2019-11-16 17:00:34,001 [salt.minion      :1432][INFO    ][12890] Starting a new job with PID 12890
2019-11-16 17:00:34,922 [salt.minion      :1711][INFO    ][12890] Returning information for job: 20191116170033971440
2019-11-16 17:03:48,773 [salt.minion      :1308][INFO    ][4528] User sudo_ubuntu Executing command cp.push_dir with jid 20191116170348759627
2019-11-16 17:03:48,792 [salt.minion      :1432][INFO    ][14224] Starting a new job with PID 14224
