2019-08-28 16:07:42,537 [salt.utils       :831 ][ERROR   ][1650] DNS lookup or connection check of 'salt' failed.
2019-08-28 16:07:42,538 [salt.minion      :152 ][ERROR   ][1650] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-08-28 16:07:45,538 [salt.utils.parsers:1051][WARNING ][1650] Minion received a SIGTERM. Exiting.
2019-08-28 16:10:11,050 [salt.utils.decorators:613 ][WARNING ][2209] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-28 16:10:54,573 [salt.loaded.int.states.file:2298][WARNING ][2209] 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-08-28 16:11:05,927 [salt.utils.decorators:613 ][WARNING ][2209] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-28 16:11:06,118 [salt.loaded.int.states.file:2298][WARNING ][2209] 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-08-28 16:11:06,122 [salt.loaded.int.states.file:2298][WARNING ][2209] 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-08-28 16:11:06,124 [salt.loaded.int.states.file:2298][WARNING ][2209] 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-08-28 16:11:06,126 [salt.loaded.int.states.file:2298][WARNING ][2209] 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-08-28 16:11:06,129 [salt.loaded.int.states.file:2298][WARNING ][2209] 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-08-28 16:11:06,130 [salt.loaded.int.states.file:2298][WARNING ][2209] 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-08-28 16:11:06,131 [salt.loaded.int.states.file:2298][WARNING ][2209] 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-08-28 16:11:06,133 [salt.loaded.int.states.file:2298][WARNING ][2209] 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-08-28 16:11:18,178 [salt.loaded.int.module.debian_ip:1964][WARNING ][2209] The network state sls is requiring a reboot of the system to properly apply network configuration.
2019-08-28 16:11:19,963 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][2209] Command '['umount', '/dev/shm']' failed with return code: 32
2019-08-28 16:11:19,963 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][2209] stderr: umount: /dev/shm: target is busy.
2019-08-28 16:11:19,963 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][2209] retcode: 32
2019-08-28 16:11:35,244 [salt.utils.parsers:1051][WARNING ][2003] Minion received a SIGTERM. Exiting.
2019-08-28 16:12:49,490 [salt.utils.decorators:613 ][WARNING ][2786] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-28 16:12:53,831 [salt.loaded.int.states.file:2298][WARNING ][2786] 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-08-28 16:12:58,773 [salt.loaded.int.states.file:2298][WARNING ][2786] 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-08-28 16:13:18,989 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4439] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-08-28 16:13:19,024 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4439] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-08-28 16:13:19,051 [salt.utils.parsers:1051][WARNING ][2616] Minion received a SIGTERM. Exiting.
2019-08-28 16:13:19,934 [salt.cli.daemons :293 ][INFO    ][4492] Setting up the Salt Minion "ctl01.mcp-k8s-calico-noha.local"
2019-08-28 16:13:20,077 [salt.cli.daemons :82  ][INFO    ][4492] Starting up the Salt Minion
2019-08-28 16:13:20,078 [salt.utils.event :1017][INFO    ][4492] Starting pull socket on /var/run/salt/minion/minion_event_1899fe9592_pull.ipc
2019-08-28 16:13:20,799 [salt.minion      :976 ][INFO    ][4492] Creating minion process manager
2019-08-28 16:13:22,313 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][4492] Executing command ['date', '+%z'] in directory '/root'
2019-08-28 16:13:22,330 [salt.utils.schedule:568 ][INFO    ][4492] Updating job settings for scheduled job: __mine_interval
2019-08-28 16:13:22,331 [salt.minion      :1108][INFO    ][4492] Added mine.update to scheduler
2019-08-28 16:13:22,337 [salt.minion      :1975][INFO    ][4492] Minion is starting as user 'root'
2019-08-28 16:13:22,350 [salt.minion      :2336][INFO    ][4492] Minion is ready to receive requests!
2019-08-28 16:13:31,078 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command state.sls with jid 20190828161331067379
2019-08-28 16:13:31,099 [salt.minion      :1432][INFO    ][4580] Starting a new job with PID 4580
2019-08-28 16:13:32,302 [salt.state       :915 ][INFO    ][4580] Loading fresh modules for state activity
2019-08-28 16:13:32,395 [salt.fileclient  :1219][INFO    ][4580] Fetching file from saltenv 'base', ** done ** 'etcd/server/service.sls'
2019-08-28 16:13:32,452 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] Executing command '. /var/lib/etcd/configenv; etcdctl cluster-health > /dev/null 2>&1; echo $?' in directory '/root'
2019-08-28 16:13:32,468 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4580] Command '. /var/lib/etcd/configenv; etcdctl cluster-health > /dev/null 2>&1; echo $?' failed with return code: 2
2019-08-28 16:13:32,469 [salt.loaded.int.module.cmdmod:732 ][ERROR   ][4580] stdout: /bin/sh: 1: .: Can't open /var/lib/etcd/configenv
2019-08-28 16:13:32,469 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4580] retcode: 2
2019-08-28 16:13:33,124 [salt.state       :1780][INFO    ][4580] Running state [etcd_support_packages] at time 16:13:33.124862
2019-08-28 16:13:33,125 [salt.state       :1813][INFO    ][4580] Executing state pkg.installed for [etcd_support_packages]
2019-08-28 16:13:33,125 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-28 16:13:33,957 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] Executing command ['apt-cache', '-q', 'policy', 'python-etcd'] in directory '/root'
2019-08-28 16:13:34,403 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-08-28 16:13:37,055 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-08-28 16:13:37,087 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] 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-08-28 16:13:40,840 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-28 16:13:40,883 [salt.state       :300 ][INFO    ][4580] 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-08-28 16:13:40,901 [salt.state       :915 ][INFO    ][4580] Loading fresh modules for state activity
2019-08-28 16:13:40,947 [salt.state       :1951][INFO    ][4580] Completed state [etcd_support_packages] at time 16:13:40.946977 duration_in_ms=7822.113
2019-08-28 16:13:40,951 [salt.state       :1780][INFO    ][4580] Running state [/tmp/etcd/bin] at time 16:13:40.951883
2019-08-28 16:13:40,952 [salt.state       :1813][INFO    ][4580] Executing state archive.extracted for [/tmp/etcd/bin]
2019-08-28 16:13:43,260 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] 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-08-28 16:13:43,827 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] Executing command ['tar', '--version'] in directory '/root'
2019-08-28 16:13:43,846 [salt.state       :300 ][INFO    ][4580] {'extracted_files': 'no tar output so far', 'directories_created': ['/tmp/etcd/bin/']}
2019-08-28 16:13:43,847 [salt.state       :1951][INFO    ][4580] Completed state [/tmp/etcd/bin] at time 16:13:43.847154 duration_in_ms=2895.267
2019-08-28 16:13:43,848 [salt.state       :1780][INFO    ][4580] Running state [/usr/local/bin/etcd] at time 16:13:43.848078
2019-08-28 16:13:43,848 [salt.state       :1813][INFO    ][4580] Executing state file.managed for [/usr/local/bin/etcd]
2019-08-28 16:13:44,066 [salt.state       :300 ][INFO    ][4580] File changed:
New file
2019-08-28 16:13:44,067 [salt.state       :1951][INFO    ][4580] Completed state [/usr/local/bin/etcd] at time 16:13:44.067436 duration_in_ms=219.357
2019-08-28 16:13:44,068 [salt.state       :1780][INFO    ][4580] Running state [/usr/local/bin/etcdctl] at time 16:13:44.068037
2019-08-28 16:13:44,068 [salt.state       :1813][INFO    ][4580] Executing state file.managed for [/usr/local/bin/etcdctl]
2019-08-28 16:13:44,238 [salt.state       :300 ][INFO    ][4580] File changed:
New file
2019-08-28 16:13:44,238 [salt.state       :1951][INFO    ][4580] Completed state [/usr/local/bin/etcdctl] at time 16:13:44.238751 duration_in_ms=170.713
2019-08-28 16:13:44,240 [salt.state       :1780][INFO    ][4580] Running state [etcd] at time 16:13:44.240540
2019-08-28 16:13:44,240 [salt.state       :1813][INFO    ][4580] Executing state user.present for [etcd]
2019-08-28 16:13:44,250 [salt.state       :300 ][INFO    ][4580] User etcd is present and up to date
2019-08-28 16:13:44,250 [salt.state       :1951][INFO    ][4580] Completed state [etcd] at time 16:13:44.250778 duration_in_ms=10.238
2019-08-28 16:13:44,251 [salt.state       :1780][INFO    ][4580] Running state [/etc/systemd/system/etcd.service] at time 16:13:44.251119
2019-08-28 16:13:44,251 [salt.state       :1813][INFO    ][4580] Executing state file.managed for [/etc/systemd/system/etcd.service]
2019-08-28 16:13:44,273 [salt.fileclient  :1219][INFO    ][4580] Fetching file from saltenv 'base', ** done ** 'etcd/files/systemd/etcd.service'
2019-08-28 16:13:44,284 [salt.state       :300 ][INFO    ][4580] File changed:
New file
2019-08-28 16:13:44,285 [salt.state       :1951][INFO    ][4580] Completed state [/etc/systemd/system/etcd.service] at time 16:13:44.285030 duration_in_ms=33.91
2019-08-28 16:13:44,285 [salt.state       :1780][INFO    ][4580] Running state [/etc/default/etcd] at time 16:13:44.285378
2019-08-28 16:13:44,285 [salt.state       :1813][INFO    ][4580] Executing state file.managed for [/etc/default/etcd]
2019-08-28 16:13:44,307 [salt.fileclient  :1219][INFO    ][4580] Fetching file from saltenv 'base', ** done ** 'etcd/files/default'
2019-08-28 16:13:44,365 [salt.state       :300 ][INFO    ][4580] File changed:
New file
2019-08-28 16:13:44,366 [salt.state       :1951][INFO    ][4580] Completed state [/etc/default/etcd] at time 16:13:44.366185 duration_in_ms=80.807
2019-08-28 16:13:44,366 [salt.state       :1780][INFO    ][4580] Running state [/var/lib/etcd/] at time 16:13:44.366531
2019-08-28 16:13:44,366 [salt.state       :1813][INFO    ][4580] Executing state file.directory for [/var/lib/etcd/]
2019-08-28 16:13:44,371 [salt.state       :300 ][INFO    ][4580] Directory /var/lib/etcd is in the correct state
Directory /var/lib/etcd updated
2019-08-28 16:13:44,371 [salt.state       :1951][INFO    ][4580] Completed state [/var/lib/etcd/] at time 16:13:44.371625 duration_in_ms=5.093
2019-08-28 16:13:44,372 [salt.state       :1780][INFO    ][4580] Running state [/var/lib/etcd/configenv] at time 16:13:44.372281
2019-08-28 16:13:44,372 [salt.state       :1813][INFO    ][4580] Executing state file.managed for [/var/lib/etcd/configenv]
2019-08-28 16:13:44,393 [salt.fileclient  :1219][INFO    ][4580] Fetching file from saltenv 'base', ** done ** 'etcd/files/configenv'
2019-08-28 16:13:44,430 [salt.state       :300 ][INFO    ][4580] File changed:
New file
2019-08-28 16:13:44,430 [salt.state       :1951][INFO    ][4580] Completed state [/var/lib/etcd/configenv] at time 16:13:44.430523 duration_in_ms=58.242
2019-08-28 16:13:44,431 [salt.state       :1780][INFO    ][4580] Running state [/var/lib/etcd/configenvv3] at time 16:13:44.430974
2019-08-28 16:13:44,431 [salt.state       :1813][INFO    ][4580] Executing state file.managed for [/var/lib/etcd/configenvv3]
2019-08-28 16:13:44,452 [salt.fileclient  :1219][INFO    ][4580] Fetching file from saltenv 'base', ** done ** 'etcd/files/configenvv3'
2019-08-28 16:13:44,482 [salt.state       :300 ][INFO    ][4580] File changed:
New file
2019-08-28 16:13:44,482 [salt.state       :1951][INFO    ][4580] Completed state [/var/lib/etcd/configenvv3] at time 16:13:44.482387 duration_in_ms=51.413
2019-08-28 16:13:45,050 [salt.state       :1780][INFO    ][4580] Running state [etcd] at time 16:13:45.050621
2019-08-28 16:13:45,051 [salt.state       :1813][INFO    ][4580] Executing state service.running for [etcd]
2019-08-28 16:13:45,051 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] Executing command ['systemctl', 'status', 'etcd.service', '-n', '0'] in directory '/root'
2019-08-28 16:13:45,073 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] Executing command ['systemctl', 'is-active', 'etcd.service'] in directory '/root'
2019-08-28 16:13:45,098 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] Executing command ['systemctl', 'is-enabled', 'etcd.service'] in directory '/root'
2019-08-28 16:13:45,129 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'etcd.service'] in directory '/root'
2019-08-28 16:13:46,135 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command saltutil.find_job with jid 20190828161346118721
2019-08-28 16:13:46,157 [salt.minion      :1432][INFO    ][5312] Starting a new job with PID 5312
2019-08-28 16:13:46,176 [salt.minion      :1711][INFO    ][5312] Returning information for job: 20190828161346118721
2019-08-28 16:13:46,202 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] Executing command ['systemctl', 'is-active', 'etcd.service'] in directory '/root'
2019-08-28 16:13:46,221 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] Executing command ['systemctl', 'is-enabled', 'etcd.service'] in directory '/root'
2019-08-28 16:13:46,246 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] Executing command ['systemctl', 'is-enabled', 'etcd.service'] in directory '/root'
2019-08-28 16:13:46,269 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'etcd.service'] in directory '/root'
2019-08-28 16:13:46,448 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4580] Executing command ['systemctl', 'is-enabled', 'etcd.service'] in directory '/root'
2019-08-28 16:13:46,470 [salt.state       :300 ][INFO    ][4580] {'etcd': True}
2019-08-28 16:13:46,470 [salt.state       :1951][INFO    ][4580] Completed state [etcd] at time 16:13:46.470643 duration_in_ms=1420.022
2019-08-28 16:13:46,473 [salt.minion      :1711][INFO    ][4580] Returning information for job: 20190828161331067379
2019-08-28 16:13:47,184 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command cmd.run with jid 20190828161347174244
2019-08-28 16:13:47,203 [salt.minion      :1432][INFO    ][5378] Starting a new job with PID 5378
2019-08-28 16:13:47,213 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][5378] Executing command '. /var/lib/etcd/configenv && etcdctl cluster-health' in directory '/root'
2019-08-28 16:13:47,370 [salt.minion      :1711][INFO    ][5378] Returning information for job: 20190828161347174244
2019-08-28 16:13:48,053 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command state.sls with jid 20190828161348041078
2019-08-28 16:13:48,075 [salt.minion      :1432][INFO    ][5392] Starting a new job with PID 5392
2019-08-28 16:13:54,554 [salt.state       :915 ][INFO    ][5392] Loading fresh modules for state activity
2019-08-28 16:13:54,648 [salt.fileclient  :1219][INFO    ][5392] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/kube-addons.sls'
2019-08-28 16:13:54,788 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:54,805 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:54,829 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:54,845 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:54,864 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:54,880 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:54,974 [salt.state       :1780][INFO    ][5392] Running state [/etc/kubernetes/addons] at time 16:13:54.973948
2019-08-28 16:13:54,974 [salt.state       :1813][INFO    ][5392] Executing state file.directory for [/etc/kubernetes/addons]
2019-08-28 16:13:54,986 [salt.state       :300 ][INFO    ][5392] {'/etc/kubernetes/addons': 'New Dir'}
2019-08-28 16:13:54,987 [salt.state       :1951][INFO    ][5392] Completed state [/etc/kubernetes/addons] at time 16:13:54.987467 duration_in_ms=13.52
2019-08-28 16:13:54,988 [salt.state       :1780][INFO    ][5392] Running state [/etc/kubernetes/addons/calico/calico-kube-controllers.yml] at time 16:13:54.988057
2019-08-28 16:13:54,988 [salt.state       :1813][INFO    ][5392] Executing state file.managed for [/etc/kubernetes/addons/calico/calico-kube-controllers.yml]
2019-08-28 16:13:55,023 [salt.fileclient  :1219][INFO    ][5392] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/calico/calico-kube-controllers.yml'
2019-08-28 16:13:55,080 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:55,099 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:55,127 [salt.state       :300 ][INFO    ][5392] File changed:
New file
2019-08-28 16:13:55,128 [salt.state       :1951][INFO    ][5392] Completed state [/etc/kubernetes/addons/calico/calico-kube-controllers.yml] at time 16:13:55.128351 duration_in_ms=140.292
2019-08-28 16:13:55,128 [salt.state       :1780][INFO    ][5392] Running state [/etc/kubernetes/addons/calico/calico-rbac.yml] at time 16:13:55.128682
2019-08-28 16:13:55,128 [salt.state       :1813][INFO    ][5392] Executing state file.managed for [/etc/kubernetes/addons/calico/calico-rbac.yml]
2019-08-28 16:13:55,149 [salt.fileclient  :1219][INFO    ][5392] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/calico/calico-rbac.yml'
2019-08-28 16:13:55,182 [salt.state       :300 ][INFO    ][5392] File changed:
New file
2019-08-28 16:13:55,183 [salt.state       :1951][INFO    ][5392] Completed state [/etc/kubernetes/addons/calico/calico-rbac.yml] at time 16:13:55.183012 duration_in_ms=54.328
2019-08-28 16:13:55,183 [salt.state       :1780][INFO    ][5392] Running state [/etc/kubernetes/addons/netchecker/netchecker-svc.yml] at time 16:13:55.183571
2019-08-28 16:13:55,184 [salt.state       :1813][INFO    ][5392] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-svc.yml]
2019-08-28 16:13:55,207 [salt.fileclient  :1219][INFO    ][5392] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-svc.yml'
2019-08-28 16:13:55,256 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:55,270 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:55,290 [salt.state       :300 ][INFO    ][5392] File changed:
New file
2019-08-28 16:13:55,291 [salt.state       :1951][INFO    ][5392] Completed state [/etc/kubernetes/addons/netchecker/netchecker-svc.yml] at time 16:13:55.291179 duration_in_ms=107.607
2019-08-28 16:13:55,291 [salt.state       :1780][INFO    ][5392] Running state [/etc/kubernetes/addons/netchecker/netchecker-server.yml] at time 16:13:55.291637
2019-08-28 16:13:55,292 [salt.state       :1813][INFO    ][5392] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-server.yml]
2019-08-28 16:13:55,309 [salt.fileclient  :1219][INFO    ][5392] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-server.yml'
2019-08-28 16:13:55,365 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:55,381 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:55,401 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:55,422 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:55,458 [salt.state       :300 ][INFO    ][5392] File changed:
New file
2019-08-28 16:13:55,459 [salt.state       :1951][INFO    ][5392] Completed state [/etc/kubernetes/addons/netchecker/netchecker-server.yml] at time 16:13:55.459513 duration_in_ms=167.873
2019-08-28 16:13:55,460 [salt.state       :1780][INFO    ][5392] Running state [/etc/kubernetes/addons/netchecker/netchecker-agent.yml] at time 16:13:55.460245
2019-08-28 16:13:55,460 [salt.state       :1813][INFO    ][5392] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-agent.yml]
2019-08-28 16:13:55,481 [salt.fileclient  :1219][INFO    ][5392] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-agent.yml'
2019-08-28 16:13:55,534 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:55,552 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:55,573 [salt.state       :300 ][INFO    ][5392] File changed:
New file
2019-08-28 16:13:55,573 [salt.state       :1951][INFO    ][5392] Completed state [/etc/kubernetes/addons/netchecker/netchecker-agent.yml] at time 16:13:55.573840 duration_in_ms=113.595
2019-08-28 16:13:55,574 [salt.state       :1780][INFO    ][5392] Running state [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml] at time 16:13:55.574334
2019-08-28 16:13:55,574 [salt.state       :1813][INFO    ][5392] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml]
2019-08-28 16:13:55,596 [salt.fileclient  :1219][INFO    ][5392] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-serviceaccount.yml'
2019-08-28 16:13:55,606 [salt.state       :300 ][INFO    ][5392] File changed:
New file
2019-08-28 16:13:55,607 [salt.state       :1951][INFO    ][5392] Completed state [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml] at time 16:13:55.607143 duration_in_ms=32.808
2019-08-28 16:13:55,607 [salt.state       :1780][INFO    ][5392] Running state [/etc/kubernetes/addons/netchecker/netchecker-roles.yml] at time 16:13:55.607387
2019-08-28 16:13:55,607 [salt.state       :1813][INFO    ][5392] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-roles.yml]
2019-08-28 16:13:55,624 [salt.fileclient  :1219][INFO    ][5392] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-roles.yml'
2019-08-28 16:13:55,634 [salt.state       :300 ][INFO    ][5392] File changed:
New file
2019-08-28 16:13:55,634 [salt.state       :1951][INFO    ][5392] Completed state [/etc/kubernetes/addons/netchecker/netchecker-roles.yml] at time 16:13:55.634913 duration_in_ms=27.525
2019-08-28 16:13:55,635 [salt.state       :1780][INFO    ][5392] Running state [/etc/kubernetes/addons/prometheus/prometheus-roles.yml] at time 16:13:55.635216
2019-08-28 16:13:55,635 [salt.state       :1813][INFO    ][5392] Executing state file.managed for [/etc/kubernetes/addons/prometheus/prometheus-roles.yml]
2019-08-28 16:13:55,654 [salt.fileclient  :1219][INFO    ][5392] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/prometheus/prometheus-roles.yml'
2019-08-28 16:13:55,666 [salt.state       :300 ][INFO    ][5392] File changed:
New file
2019-08-28 16:13:55,666 [salt.state       :1951][INFO    ][5392] Completed state [/etc/kubernetes/addons/prometheus/prometheus-roles.yml] at time 16:13:55.666509 duration_in_ms=31.293
2019-08-28 16:13:55,666 [salt.state       :1780][INFO    ][5392] Running state [/etc/kubernetes/addons/coredns] at time 16:13:55.666765
2019-08-28 16:13:55,667 [salt.state       :1813][INFO    ][5392] Executing state file.absent for [/etc/kubernetes/addons/coredns]
2019-08-28 16:13:55,667 [salt.state       :300 ][INFO    ][5392] File /etc/kubernetes/addons/coredns is not present
2019-08-28 16:13:55,667 [salt.state       :1951][INFO    ][5392] Completed state [/etc/kubernetes/addons/coredns] at time 16:13:55.667592 duration_in_ms=0.827
2019-08-28 16:13:55,668 [salt.state       :1780][INFO    ][5392] Running state [kubectl -n kube-system delete svc coredns > /dev/null || echo "coredns is absent. OK" && true] at time 16:13:55.668599
2019-08-28 16:13:55,668 [salt.state       :1813][INFO    ][5392] Executing state cmd.run for [kubectl -n kube-system delete svc coredns > /dev/null || echo "coredns is absent. OK" && true]
2019-08-28 16:13:55,669 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] Executing command 'kubectl -n kube-system delete svc coredns > /dev/null || echo "coredns is absent. OK" && true' in directory '/root'
2019-08-28 16:13:55,682 [salt.state       :300 ][INFO    ][5392] {'pid': 5473, 'retcode': 0, 'stderr': '/bin/sh: 1: kubectl: not found', 'stdout': 'coredns is absent. OK'}
2019-08-28 16:13:55,682 [salt.state       :1951][INFO    ][5392] Completed state [kubectl -n kube-system delete svc coredns > /dev/null || echo "coredns is absent. OK" && true] at time 16:13:55.682400 duration_in_ms=13.801
2019-08-28 16:13:55,683 [salt.state       :1780][INFO    ][5392] Running state [/etc/kubernetes/addons/dns/kubedns-svc.yaml] at time 16:13:55.683429
2019-08-28 16:13:55,684 [salt.state       :1813][INFO    ][5392] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-svc.yaml]
2019-08-28 16:13:55,708 [salt.fileclient  :1219][INFO    ][5392] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-svc.yaml'
2019-08-28 16:13:55,752 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:55,769 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:55,791 [salt.state       :300 ][INFO    ][5392] File changed:
New file
2019-08-28 16:13:55,792 [salt.state       :1951][INFO    ][5392] Completed state [/etc/kubernetes/addons/dns/kubedns-svc.yaml] at time 16:13:55.791890 duration_in_ms=108.461
2019-08-28 16:13:55,792 [salt.state       :1780][INFO    ][5392] Running state [/etc/kubernetes/addons/dns/kubedns-rc.yaml] at time 16:13:55.792429
2019-08-28 16:13:55,792 [salt.state       :1813][INFO    ][5392] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-rc.yaml]
2019-08-28 16:13:55,816 [salt.fileclient  :1219][INFO    ][5392] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-rc.yaml'
2019-08-28 16:13:55,876 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:55,892 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:55,913 [salt.state       :300 ][INFO    ][5392] File changed:
New file
2019-08-28 16:13:55,914 [salt.state       :1951][INFO    ][5392] Completed state [/etc/kubernetes/addons/dns/kubedns-rc.yaml] at time 16:13:55.914173 duration_in_ms=121.742
2019-08-28 16:13:55,914 [salt.state       :1780][INFO    ][5392] Running state [/etc/kubernetes/addons/dns/kubedns-sa.yaml] at time 16:13:55.914661
2019-08-28 16:13:55,915 [salt.state       :1813][INFO    ][5392] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-sa.yaml]
2019-08-28 16:13:55,937 [salt.fileclient  :1219][INFO    ][5392] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-sa.yaml'
2019-08-28 16:13:55,947 [salt.state       :300 ][INFO    ][5392] File changed:
New file
2019-08-28 16:13:55,947 [salt.state       :1951][INFO    ][5392] Completed state [/etc/kubernetes/addons/dns/kubedns-sa.yaml] at time 16:13:55.947705 duration_in_ms=33.045
2019-08-28 16:13:55,948 [salt.state       :1780][INFO    ][5392] Running state [/etc/kubernetes/addons/dns/kubedns-autoscaler.yaml] at time 16:13:55.948010
2019-08-28 16:13:55,948 [salt.state       :1813][INFO    ][5392] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-autoscaler.yaml]
2019-08-28 16:13:55,967 [salt.fileclient  :1219][INFO    ][5392] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-autoscaler.yaml'
2019-08-28 16:13:56,017 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:56,033 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5392] 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-08-28 16:13:56,053 [salt.state       :300 ][INFO    ][5392] File changed:
New file
2019-08-28 16:13:56,053 [salt.state       :1951][INFO    ][5392] Completed state [/etc/kubernetes/addons/dns/kubedns-autoscaler.yaml] at time 16:13:56.053677 duration_in_ms=105.665
2019-08-28 16:13:56,054 [salt.state       :1780][INFO    ][5392] Running state [/etc/kubernetes/addons/dns/kubedns-autoscaler-rbac.yaml] at time 16:13:56.054178
2019-08-28 16:13:56,054 [salt.state       :1813][INFO    ][5392] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-autoscaler-rbac.yaml]
2019-08-28 16:13:56,078 [salt.fileclient  :1219][INFO    ][5392] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-autoscaler-rbac.yaml'
2019-08-28 16:13:56,089 [salt.state       :300 ][INFO    ][5392] File changed:
New file
2019-08-28 16:13:56,089 [salt.state       :1951][INFO    ][5392] Completed state [/etc/kubernetes/addons/dns/kubedns-autoscaler-rbac.yaml] at time 16:13:56.089743 duration_in_ms=35.565
2019-08-28 16:13:56,090 [salt.state       :1780][INFO    ][5392] Running state [/etc/kubernetes/addons/dns/kubedns-clusterrole.yaml] at time 16:13:56.090055
2019-08-28 16:13:56,090 [salt.state       :1813][INFO    ][5392] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-clusterrole.yaml]
2019-08-28 16:13:56,109 [salt.fileclient  :1219][INFO    ][5392] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-clusterrole.yaml'
2019-08-28 16:13:56,124 [salt.state       :300 ][INFO    ][5392] File changed:
New file
2019-08-28 16:13:56,124 [salt.state       :1951][INFO    ][5392] Completed state [/etc/kubernetes/addons/dns/kubedns-clusterrole.yaml] at time 16:13:56.124179 duration_in_ms=34.125
2019-08-28 16:13:56,124 [salt.state       :1780][INFO    ][5392] Running state [/etc/kubernetes/addons/metrics-server] at time 16:13:56.124450
2019-08-28 16:13:56,124 [salt.state       :1813][INFO    ][5392] Executing state file.absent for [/etc/kubernetes/addons/metrics-server]
2019-08-28 16:13:56,125 [salt.state       :300 ][INFO    ][5392] File /etc/kubernetes/addons/metrics-server is not present
2019-08-28 16:13:56,125 [salt.state       :1951][INFO    ][5392] Completed state [/etc/kubernetes/addons/metrics-server] at time 16:13:56.125238 duration_in_ms=0.788
2019-08-28 16:13:56,128 [salt.minion      :1711][INFO    ][5392] Returning information for job: 20190828161348041078
2019-08-28 16:13:56,777 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command state.sls with jid 20190828161356768692
2019-08-28 16:13:56,795 [salt.minion      :1432][INFO    ][5511] Starting a new job with PID 5511
2019-08-28 16:13:57,793 [salt.state       :915 ][INFO    ][5511] Loading fresh modules for state activity
2019-08-28 16:13:57,872 [salt.fileclient  :1219][INFO    ][5511] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/init.sls'
2019-08-28 16:13:57,933 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:13:57,980 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:13:58,001 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:13:58,013 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:13:58,047 [salt.fileclient  :1219][INFO    ][5511] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/calico.sls'
2019-08-28 16:13:58,107 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:13:58,121 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:13:58,139 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:13:58,152 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:13:58,234 [salt.fileclient  :1219][INFO    ][5511] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/service.sls'
2019-08-28 16:13:58,278 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:13:58,292 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:13:58,309 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:13:58,323 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:13:58,359 [salt.fileclient  :1219][INFO    ][5511] Fetching file from saltenv 'base', ** done ** 'kubernetes/_common.sls'
2019-08-28 16:13:58,440 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:13:58,458 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:13:58,524 [salt.fileclient  :1219][INFO    ][5511] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/kube-proxy.sls'
2019-08-28 16:13:58,575 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:13:58,593 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:13:58,630 [salt.state       :1780][INFO    ][5511] Running state [/usr/bin/calicoctl] at time 16:13:58.630511
2019-08-28 16:13:58,630 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/usr/bin/calicoctl]
2019-08-28 16:14:09,566 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:14:09,566 [salt.state       :1951][INFO    ][5511] Completed state [/usr/bin/calicoctl] at time 16:14:09.566557 duration_in_ms=10936.046
2019-08-28 16:14:09,566 [salt.state       :1780][INFO    ][5511] Running state [/usr/bin/birdcl] at time 16:14:09.566917
2019-08-28 16:14:09,567 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/usr/bin/birdcl]
2019-08-28 16:14:10,040 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:14:10,041 [salt.state       :1951][INFO    ][5511] Completed state [/usr/bin/birdcl] at time 16:14:10.041252 duration_in_ms=474.334
2019-08-28 16:14:10,041 [salt.state       :1780][INFO    ][5511] Running state [/opt/cni/bin/calico] at time 16:14:10.041566
2019-08-28 16:14:10,041 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/opt/cni/bin/calico]
2019-08-28 16:14:11,837 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command saltutil.find_job with jid 20190828161411825253
2019-08-28 16:14:11,850 [salt.minion      :1432][INFO    ][5586] Starting a new job with PID 5586
2019-08-28 16:14:11,868 [salt.minion      :1711][INFO    ][5586] Returning information for job: 20190828161411825253
2019-08-28 16:14:17,245 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:14:17,245 [salt.state       :1951][INFO    ][5511] Completed state [/opt/cni/bin/calico] at time 16:14:17.245385 duration_in_ms=7203.818
2019-08-28 16:14:17,245 [salt.state       :1780][INFO    ][5511] Running state [/opt/cni/bin/calico-ipam] at time 16:14:17.245736
2019-08-28 16:14:17,246 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/opt/cni/bin/calico-ipam]
2019-08-28 16:14:22,204 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:14:22,204 [salt.state       :1951][INFO    ][5511] Completed state [/opt/cni/bin/calico-ipam] at time 16:14:22.204464 duration_in_ms=4958.728
2019-08-28 16:14:22,204 [salt.state       :1780][INFO    ][5511] Running state [/etc/cni/net.d/10-calico.conf] at time 16:14:22.204724
2019-08-28 16:14:22,204 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/etc/cni/net.d/10-calico.conf]
2019-08-28 16:14:22,220 [salt.fileclient  :1219][INFO    ][5511] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calico.conf'
2019-08-28 16:14:22,273 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:14:22,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:14:22,298 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:14:22,309 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:14:22,324 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:14:22,324 [salt.state       :1951][INFO    ][5511] Completed state [/etc/cni/net.d/10-calico.conf] at time 16:14:22.324616 duration_in_ms=119.892
2019-08-28 16:14:22,324 [salt.state       :1780][INFO    ][5511] Running state [/etc/calico/network-environment] at time 16:14:22.324940
2019-08-28 16:14:22,325 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/etc/calico/network-environment]
2019-08-28 16:14:22,344 [salt.fileclient  :1219][INFO    ][5511] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/network-environment.pool'
2019-08-28 16:14:22,390 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:14:22,402 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:14:22,417 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:14:22,417 [salt.state       :1951][INFO    ][5511] Completed state [/etc/calico/network-environment] at time 16:14:22.417303 duration_in_ms=92.362
2019-08-28 16:14:22,417 [salt.state       :1780][INFO    ][5511] Running state [/etc/calico/calicoctl.cfg] at time 16:14:22.417623
2019-08-28 16:14:22,417 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/etc/calico/calicoctl.cfg]
2019-08-28 16:14:22,435 [salt.fileclient  :1219][INFO    ][5511] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calicoctl.cfg.pool'
2019-08-28 16:14:22,502 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:14:22,514 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:14:22,528 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:14:22,528 [salt.state       :1951][INFO    ][5511] Completed state [/etc/calico/calicoctl.cfg] at time 16:14:22.528871 duration_in_ms=111.247
2019-08-28 16:14:23,182 [salt.state       :1780][INFO    ][5511] Running state [containerd] at time 16:14:23.182232
2019-08-28 16:14:23,182 [salt.state       :1813][INFO    ][5511] Executing state pkg.installed for [containerd]
2019-08-28 16:14:23,183 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-28 16:14:24,017 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['apt-cache', '-q', 'policy', 'containerd'] in directory '/root'
2019-08-28 16:14:24,433 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-08-28 16:14:26,708 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-08-28 16:14:26,730 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:14:33,978 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-28 16:14:34,015 [salt.state       :300 ][INFO    ][5511] 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-08-28 16:14:34,035 [salt.state       :915 ][INFO    ][5511] Loading fresh modules for state activity
2019-08-28 16:14:34,078 [salt.state       :1951][INFO    ][5511] Completed state [containerd] at time 16:14:34.078777 duration_in_ms=10896.546
2019-08-28 16:14:34,083 [salt.state       :1780][INFO    ][5511] Running state [/etc/containerd/config.toml] at time 16:14:34.083546
2019-08-28 16:14:34,084 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/etc/containerd/config.toml]
2019-08-28 16:14:34,107 [salt.fileclient  :1219][INFO    ][5511] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/containerd/config.toml'
2019-08-28 16:14:34,157 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:14:34,171 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:14:34,194 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:14:34,195 [salt.state       :1951][INFO    ][5511] Completed state [/etc/containerd/config.toml] at time 16:14:34.195236 duration_in_ms=111.689
2019-08-28 16:14:34,714 [salt.state       :1780][INFO    ][5511] Running state [containerd] at time 16:14:34.713999
2019-08-28 16:14:34,714 [salt.state       :1813][INFO    ][5511] Executing state service.running for [containerd]
2019-08-28 16:14:34,715 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'status', 'containerd.service', '-n', '0'] in directory '/root'
2019-08-28 16:14:34,733 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-08-28 16:14:34,749 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-enabled', 'containerd.service'] in directory '/root'
2019-08-28 16:14:34,765 [salt.state       :300 ][INFO    ][5511] The service containerd is already running
2019-08-28 16:14:34,765 [salt.state       :1951][INFO    ][5511] Completed state [containerd] at time 16:14:34.765604 duration_in_ms=51.604
2019-08-28 16:14:34,766 [salt.state       :1780][INFO    ][5511] Running state [containerd] at time 16:14:34.766093
2019-08-28 16:14:34,766 [salt.state       :1813][INFO    ][5511] Executing state service.mod_watch for [containerd]
2019-08-28 16:14:34,767 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-08-28 16:14:34,784 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'containerd.service'] in directory '/root'
2019-08-28 16:14:34,819 [salt.state       :300 ][INFO    ][5511] {'containerd': True}
2019-08-28 16:14:34,819 [salt.state       :1951][INFO    ][5511] Completed state [containerd] at time 16:14:34.819550 duration_in_ms=53.458
2019-08-28 16:14:34,820 [salt.state       :1780][INFO    ][5511] Running state [/etc/systemd/system/calico-node.service] at time 16:14:34.820553
2019-08-28 16:14:34,821 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/etc/systemd/system/calico-node.service]
2019-08-28 16:14:34,842 [salt.fileclient  :1219][INFO    ][5511] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calico-node.service.ctr'
2019-08-28 16:14:34,866 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:14:34,866 [salt.state       :1951][INFO    ][5511] Completed state [/etc/systemd/system/calico-node.service] at time 16:14:34.866578 duration_in_ms=46.024
2019-08-28 16:14:34,866 [salt.state       :1780][INFO    ][5511] Running state [/var/lib/calico] at time 16:14:34.866875
2019-08-28 16:14:34,867 [salt.state       :1813][INFO    ][5511] Executing state file.directory for [/var/lib/calico]
2019-08-28 16:14:34,868 [salt.state       :300 ][INFO    ][5511] {'/var/lib/calico': 'New Dir'}
2019-08-28 16:14:34,868 [salt.state       :1951][INFO    ][5511] Completed state [/var/lib/calico] at time 16:14:34.868570 duration_in_ms=1.694
2019-08-28 16:14:34,868 [salt.state       :1780][INFO    ][5511] Running state [/var/log/calico] at time 16:14:34.868815
2019-08-28 16:14:34,869 [salt.state       :1813][INFO    ][5511] Executing state file.directory for [/var/log/calico]
2019-08-28 16:14:34,870 [salt.state       :300 ][INFO    ][5511] {'/var/log/calico': 'New Dir'}
2019-08-28 16:14:34,871 [salt.state       :1951][INFO    ][5511] Completed state [/var/log/calico] at time 16:14:34.871041 duration_in_ms=2.226
2019-08-28 16:14:34,873 [salt.state       :1780][INFO    ][5511] Running state [calico-node] at time 16:14:34.873298
2019-08-28 16:14:34,873 [salt.state       :1813][INFO    ][5511] Executing state service.running for [calico-node]
2019-08-28 16:14:34,874 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'status', 'calico-node.service', '-n', '0'] in directory '/root'
2019-08-28 16:14:34,899 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-08-28 16:14:34,922 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-08-28 16:14:34,956 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'calico-node.service'] in directory '/root'
2019-08-28 16:14:41,860 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command saltutil.find_job with jid 20190828161441852956
2019-08-28 16:14:41,872 [salt.minion      :1432][INFO    ][6707] Starting a new job with PID 6707
2019-08-28 16:14:41,889 [salt.minion      :1711][INFO    ][6707] Returning information for job: 20190828161441852956
2019-08-28 16:14:43,699 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-08-28 16:14:43,716 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-08-28 16:14:43,736 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-08-28 16:14:43,762 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'calico-node.service'] in directory '/root'
2019-08-28 16:14:43,940 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-08-28 16:14:43,959 [salt.state       :300 ][INFO    ][5511] {'calico-node': True}
2019-08-28 16:14:43,959 [salt.state       :1951][INFO    ][5511] Completed state [calico-node] at time 16:14:43.959404 duration_in_ms=9086.105
2019-08-28 16:14:43,961 [salt.state       :1780][INFO    ][5511] Running state [curl] at time 16:14:43.961384
2019-08-28 16:14:43,961 [salt.state       :1813][INFO    ][5511] Executing state pkg.installed for [curl]
2019-08-28 16:14:44,153 [salt.state       :300 ][INFO    ][5511] All specified packages are already installed
2019-08-28 16:14:44,154 [salt.state       :1951][INFO    ][5511] Completed state [curl] at time 16:14:44.153983 duration_in_ms=192.599
2019-08-28 16:14:44,154 [salt.state       :1780][INFO    ][5511] Running state [git] at time 16:14:44.154318
2019-08-28 16:14:44,154 [salt.state       :1813][INFO    ][5511] Executing state pkg.installed for [git]
2019-08-28 16:14:44,164 [salt.state       :300 ][INFO    ][5511] All specified packages are already installed
2019-08-28 16:14:44,164 [salt.state       :1951][INFO    ][5511] Completed state [git] at time 16:14:44.164261 duration_in_ms=9.943
2019-08-28 16:14:44,164 [salt.state       :1780][INFO    ][5511] Running state [apt-transport-https] at time 16:14:44.164523
2019-08-28 16:14:44,164 [salt.state       :1813][INFO    ][5511] Executing state pkg.installed for [apt-transport-https]
2019-08-28 16:14:44,173 [salt.state       :300 ][INFO    ][5511] All specified packages are already installed
2019-08-28 16:14:44,174 [salt.state       :1951][INFO    ][5511] Completed state [apt-transport-https] at time 16:14:44.174010 duration_in_ms=9.486
2019-08-28 16:14:44,174 [salt.state       :1780][INFO    ][5511] Running state [python-apt] at time 16:14:44.174254
2019-08-28 16:14:44,174 [salt.state       :1813][INFO    ][5511] Executing state pkg.installed for [python-apt]
2019-08-28 16:14:44,186 [salt.state       :300 ][INFO    ][5511] All specified packages are already installed
2019-08-28 16:14:44,186 [salt.state       :1951][INFO    ][5511] Completed state [python-apt] at time 16:14:44.186400 duration_in_ms=12.146
2019-08-28 16:14:44,186 [salt.state       :1780][INFO    ][5511] Running state [socat] at time 16:14:44.186650
2019-08-28 16:14:44,186 [salt.state       :1813][INFO    ][5511] Executing state pkg.installed for [socat]
2019-08-28 16:14:44,213 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-08-28 16:14:44,235 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:14:48,100 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-28 16:14:48,146 [salt.state       :300 ][INFO    ][5511] Made the following changes:
'socat' changed from 'absent' to '1.7.3.2-2ubuntu2'

2019-08-28 16:14:48,162 [salt.state       :915 ][INFO    ][5511] Loading fresh modules for state activity
2019-08-28 16:14:48,219 [salt.state       :1951][INFO    ][5511] Completed state [socat] at time 16:14:48.219371 duration_in_ms=4032.719
2019-08-28 16:14:48,230 [salt.state       :1780][INFO    ][5511] Running state [openssl] at time 16:14:48.230738
2019-08-28 16:14:48,231 [salt.state       :1813][INFO    ][5511] Executing state pkg.installed for [openssl]
2019-08-28 16:14:48,922 [salt.state       :300 ][INFO    ][5511] All specified packages are already installed
2019-08-28 16:14:48,922 [salt.state       :1951][INFO    ][5511] Completed state [openssl] at time 16:14:48.922354 duration_in_ms=691.617
2019-08-28 16:14:48,922 [salt.state       :1780][INFO    ][5511] Running state [conntrack] at time 16:14:48.922662
2019-08-28 16:14:48,922 [salt.state       :1813][INFO    ][5511] Executing state pkg.installed for [conntrack]
2019-08-28 16:14:48,949 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-08-28 16:14:48,975 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:14:52,986 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-28 16:14:53,024 [salt.state       :300 ][INFO    ][5511] Made the following changes:
'conntrack' changed from 'absent' to '1:1.4.4+snapshot20161117-6ubuntu2'

2019-08-28 16:14:53,040 [salt.state       :915 ][INFO    ][5511] Loading fresh modules for state activity
2019-08-28 16:14:53,098 [salt.state       :1951][INFO    ][5511] Completed state [conntrack] at time 16:14:53.098805 duration_in_ms=4176.142
2019-08-28 16:14:53,106 [salt.state       :1780][INFO    ][5511] Running state [nfs-common] at time 16:14:53.106778
2019-08-28 16:14:53,107 [salt.state       :1813][INFO    ][5511] Executing state pkg.installed for [nfs-common]
2019-08-28 16:14:53,821 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-08-28 16:14:53,846 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:15:02,124 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-28 16:15:02,170 [salt.state       :300 ][INFO    ][5511] 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-08-28 16:15:02,190 [salt.state       :915 ][INFO    ][5511] Loading fresh modules for state activity
2019-08-28 16:15:02,260 [salt.state       :1951][INFO    ][5511] Completed state [nfs-common] at time 16:15:02.260359 duration_in_ms=9153.577
2019-08-28 16:15:02,276 [salt.state       :1780][INFO    ][5511] Running state [cifs-utils] at time 16:15:02.276323
2019-08-28 16:15:02,276 [salt.state       :1813][INFO    ][5511] Executing state pkg.installed for [cifs-utils]
2019-08-28 16:15:03,033 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-08-28 16:15:03,059 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:15:11,982 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command saltutil.find_job with jid 20190828161511972210
2019-08-28 16:15:11,996 [salt.minion      :1432][INFO    ][9238] Starting a new job with PID 9238
2019-08-28 16:15:12,011 [salt.minion      :1711][INFO    ][9238] Returning information for job: 20190828161511972210
2019-08-28 16:15:13,258 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-28 16:15:13,304 [salt.state       :300 ][INFO    ][5511] 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.11'
'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.11'
'python2.7-tdb' changed from 'absent' to '1'
'samba-libs' changed from 'absent' to '2:4.7.6+dfsg~ubuntu-0ubuntu2.11'
'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.11'
'python-samba' changed from 'absent' to '2:4.7.6+dfsg~ubuntu-0ubuntu2.11'
'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-08-28 16:15:13,330 [salt.state       :915 ][INFO    ][5511] Loading fresh modules for state activity
2019-08-28 16:15:13,405 [salt.state       :1951][INFO    ][5511] Completed state [cifs-utils] at time 16:15:13.405786 duration_in_ms=11129.461
2019-08-28 16:15:13,411 [salt.state       :1780][INFO    ][5511] Running state [/usr/bin/hyperkube] at time 16:15:13.411181
2019-08-28 16:15:13,411 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/usr/bin/hyperkube]
2019-08-28 16:15:41,998 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command saltutil.find_job with jid 20190828161541990032
2019-08-28 16:15:42,011 [salt.minion      :1432][INFO    ][9317] Starting a new job with PID 9317
2019-08-28 16:15:42,030 [salt.minion      :1711][INFO    ][9317] Returning information for job: 20190828161541990032
2019-08-28 16:16:06,227 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:16:06,227 [salt.state       :1951][INFO    ][5511] Completed state [/usr/bin/hyperkube] at time 16:16:06.227626 duration_in_ms=52816.444
2019-08-28 16:16:06,228 [salt.state       :1780][INFO    ][5511] Running state [/usr/bin/kubectl] at time 16:16:06.228717
2019-08-28 16:16:06,229 [salt.state       :1813][INFO    ][5511] Executing state file.symlink for [/usr/bin/kubectl]
2019-08-28 16:16:06,235 [salt.state       :300 ][INFO    ][5511] {'new': '/usr/bin/kubectl'}
2019-08-28 16:16:06,247 [salt.state       :915 ][INFO    ][5511] Loading fresh modules for state activity
2019-08-28 16:16:06,290 [salt.state       :1951][INFO    ][5511] Completed state [/usr/bin/kubectl] at time 16:16:06.290363 duration_in_ms=61.646
2019-08-28 16:16:06,293 [salt.state       :1780][INFO    ][5511] Running state [/tmp/crictl] at time 16:16:06.293624
2019-08-28 16:16:06,293 [salt.state       :1813][INFO    ][5511] Executing state archive.extracted for [/tmp/crictl]
2019-08-28 16:16:08,435 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:08,691 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['tar', '--version'] in directory '/root'
2019-08-28 16:16:08,705 [salt.state       :300 ][INFO    ][5511] {'extracted_files': 'no tar output so far', 'directories_created': ['/tmp/crictl/']}
2019-08-28 16:16:08,706 [salt.state       :1951][INFO    ][5511] Completed state [/tmp/crictl] at time 16:16:08.706157 duration_in_ms=2412.528
2019-08-28 16:16:08,707 [salt.state       :1780][INFO    ][5511] Running state [/usr/local/bin/crictl] at time 16:16:08.707621
2019-08-28 16:16:08,708 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/usr/local/bin/crictl]
2019-08-28 16:16:08,708 [salt.loaded.int.states.file:1019][WARNING ][5511] Use of argument owner found, "owner" is invalid, please use "user"
2019-08-28 16:16:08,988 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:16:08,989 [salt.state       :1951][INFO    ][5511] Completed state [/usr/local/bin/crictl] at time 16:16:08.989144 duration_in_ms=281.523
2019-08-28 16:16:08,989 [salt.state       :1780][INFO    ][5511] Running state [/etc/crictl.yaml] at time 16:16:08.989459
2019-08-28 16:16:08,989 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/etc/crictl.yaml]
2019-08-28 16:16:09,018 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:16:09,018 [salt.state       :1951][INFO    ][5511] Completed state [/etc/crictl.yaml] at time 16:16:09.018245 duration_in_ms=28.786
2019-08-28 16:16:09,018 [salt.state       :1780][INFO    ][5511] Running state [/etc/criproxy] at time 16:16:09.018493
2019-08-28 16:16:09,018 [salt.state       :1813][INFO    ][5511] Executing state file.absent for [/etc/criproxy]
2019-08-28 16:16:09,019 [salt.state       :300 ][INFO    ][5511] File /etc/criproxy is not present
2019-08-28 16:16:09,019 [salt.state       :1951][INFO    ][5511] Completed state [/etc/criproxy] at time 16:16:09.019323 duration_in_ms=0.83
2019-08-28 16:16:09,604 [salt.state       :1780][INFO    ][5511] Running state [criproxy] at time 16:16:09.604890
2019-08-28 16:16:09,605 [salt.state       :1813][INFO    ][5511] Executing state service.dead for [criproxy]
2019-08-28 16:16:09,605 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'status', 'criproxy.service', '-n', '0'] in directory '/root'
2019-08-28 16:16:09,623 [salt.state       :300 ][INFO    ][5511] The named service criproxy is not available
2019-08-28 16:16:09,623 [salt.state       :1951][INFO    ][5511] Completed state [criproxy] at time 16:16:09.623389 duration_in_ms=18.499
2019-08-28 16:16:09,623 [salt.state       :1780][INFO    ][5511] Running state [/etc/systemd/system/kubelet.service] at time 16:16:09.623757
2019-08-28 16:16:09,624 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/etc/systemd/system/kubelet.service]
2019-08-28 16:16:09,641 [salt.fileclient  :1219][INFO    ][5511] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kubelet.service'
2019-08-28 16:16:09,690 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:09,847 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:09,981 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:16:09,981 [salt.state       :1951][INFO    ][5511] Completed state [/etc/systemd/system/kubelet.service] at time 16:16:09.981418 duration_in_ms=357.661
2019-08-28 16:16:09,981 [salt.state       :1780][INFO    ][5511] Running state [/etc/kubernetes/config] at time 16:16:09.981756
2019-08-28 16:16:09,982 [salt.state       :1813][INFO    ][5511] Executing state file.absent for [/etc/kubernetes/config]
2019-08-28 16:16:09,982 [salt.state       :300 ][INFO    ][5511] File /etc/kubernetes/config is not present
2019-08-28 16:16:09,982 [salt.state       :1951][INFO    ][5511] Completed state [/etc/kubernetes/config] at time 16:16:09.982679 duration_in_ms=0.924
2019-08-28 16:16:09,982 [salt.state       :1780][INFO    ][5511] Running state [/etc/default/kubelet] at time 16:16:09.982938
2019-08-28 16:16:09,983 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/etc/default/kubelet]
2019-08-28 16:16:10,004 [salt.fileclient  :1219][INFO    ][5511] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubelet/default.master'
2019-08-28 16:16:10,077 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:10,220 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:10,345 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:10,489 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:10,642 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:10,789 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:10,920 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:16:10,920 [salt.state       :1951][INFO    ][5511] Completed state [/etc/default/kubelet] at time 16:16:10.920704 duration_in_ms=937.766
2019-08-28 16:16:10,921 [salt.state       :1780][INFO    ][5511] Running state [/etc/kubernetes/kubelet.kubeconfig] at time 16:16:10.921035
2019-08-28 16:16:10,921 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/etc/kubernetes/kubelet.kubeconfig]
2019-08-28 16:16:10,949 [salt.fileclient  :1219][INFO    ][5511] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubelet/kubelet.kubeconfig.master'
2019-08-28 16:16:11,005 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:11,159 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:11,294 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:11,423 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:11,552 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:16:11,553 [salt.state       :1951][INFO    ][5511] Completed state [/etc/kubernetes/kubelet.kubeconfig] at time 16:16:11.553365 duration_in_ms=632.329
2019-08-28 16:16:11,553 [salt.state       :1780][INFO    ][5511] Running state [/etc/kubernetes/manifests] at time 16:16:11.553845
2019-08-28 16:16:11,554 [salt.state       :1813][INFO    ][5511] Executing state file.directory for [/etc/kubernetes/manifests]
2019-08-28 16:16:11,556 [salt.state       :300 ][INFO    ][5511] {'/etc/kubernetes/manifests': 'New Dir'}
2019-08-28 16:16:11,557 [salt.state       :1951][INFO    ][5511] Completed state [/etc/kubernetes/manifests] at time 16:16:11.556984 duration_in_ms=3.139
2019-08-28 16:16:11,560 [salt.state       :1780][INFO    ][5511] Running state [kubelet] at time 16:16:11.560728
2019-08-28 16:16:11,561 [salt.state       :1813][INFO    ][5511] Executing state service.running for [kubelet]
2019-08-28 16:16:11,561 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'status', 'kubelet.service', '-n', '0'] in directory '/root'
2019-08-28 16:16:11,586 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-08-28 16:16:11,605 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-08-28 16:16:11,633 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kubelet.service'] in directory '/root'
2019-08-28 16:16:11,661 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-08-28 16:16:11,677 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-08-28 16:16:11,704 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-08-28 16:16:11,733 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kubelet.service'] in directory '/root'
2019-08-28 16:16:11,919 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-08-28 16:16:11,937 [salt.state       :300 ][INFO    ][5511] {'kubelet': True}
2019-08-28 16:16:11,938 [salt.state       :1951][INFO    ][5511] Completed state [kubelet] at time 16:16:11.938213 duration_in_ms=377.484
2019-08-28 16:16:11,938 [salt.state       :1780][INFO    ][5511] Running state [/etc/logrotate.d/kubernetes] at time 16:16:11.938610
2019-08-28 16:16:11,938 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/etc/logrotate.d/kubernetes]
2019-08-28 16:16:11,956 [salt.fileclient  :1219][INFO    ][5511] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/logrotate'
2019-08-28 16:16:11,966 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:16:11,966 [salt.state       :1951][INFO    ][5511] Completed state [/etc/logrotate.d/kubernetes] at time 16:16:11.966629 duration_in_ms=28.018
2019-08-28 16:16:11,966 [salt.state       :1780][INFO    ][5511] Running state [/opt/cni/bin] at time 16:16:11.966880
2019-08-28 16:16:11,967 [salt.state       :1813][INFO    ][5511] Executing state archive.extracted for [/opt/cni/bin]
2019-08-28 16:16:12,214 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command saltutil.find_job with jid 20190828161612207694
2019-08-28 16:16:12,226 [salt.minion      :1432][INFO    ][9698] Starting a new job with PID 9698
2019-08-28 16:16:12,240 [salt.minion      :1711][INFO    ][9698] Returning information for job: 20190828161612207694
2019-08-28 16:16:18,436 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:19,056 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['tar', '--version'] in directory '/root'
2019-08-28 16:16:19,072 [salt.state       :300 ][INFO    ][5511] {'extracted_files': 'no tar output so far'}
2019-08-28 16:16:19,072 [salt.state       :1951][INFO    ][5511] Completed state [/opt/cni/bin] at time 16:16:19.072779 duration_in_ms=7105.893
2019-08-28 16:16:19,073 [salt.state       :1780][INFO    ][5511] Running state [/etc/kubernetes/proxy.kubeconfig] at time 16:16:19.073181
2019-08-28 16:16:19,073 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/etc/kubernetes/proxy.kubeconfig]
2019-08-28 16:16:19,094 [salt.fileclient  :1219][INFO    ][5511] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-proxy/proxy.kubeconfig'
2019-08-28 16:16:19,142 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:19,304 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:19,474 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:19,618 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] 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-08-28 16:16:19,769 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:16:19,770 [salt.state       :1951][INFO    ][5511] Completed state [/etc/kubernetes/proxy.kubeconfig] at time 16:16:19.770256 duration_in_ms=697.075
2019-08-28 16:16:19,770 [salt.state       :1780][INFO    ][5511] Running state [/etc/systemd/system/kube-proxy.service] at time 16:16:19.770593
2019-08-28 16:16:19,770 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/etc/systemd/system/kube-proxy.service]
2019-08-28 16:16:19,794 [salt.fileclient  :1219][INFO    ][5511] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-proxy.service'
2019-08-28 16:16:19,806 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:16:19,806 [salt.state       :1951][INFO    ][5511] Completed state [/etc/systemd/system/kube-proxy.service] at time 16:16:19.806342 duration_in_ms=35.749
2019-08-28 16:16:19,806 [salt.state       :1780][INFO    ][5511] Running state [/etc/default/kube-proxy] at time 16:16:19.806610
2019-08-28 16:16:19,806 [salt.state       :1813][INFO    ][5511] Executing state file.managed for [/etc/default/kube-proxy]
2019-08-28 16:16:19,809 [salt.state       :300 ][INFO    ][5511] File changed:
New file
2019-08-28 16:16:19,810 [salt.state       :1951][INFO    ][5511] Completed state [/etc/default/kube-proxy] at time 16:16:19.810253 duration_in_ms=3.643
2019-08-28 16:16:19,812 [salt.state       :1780][INFO    ][5511] Running state [kube-proxy] at time 16:16:19.812748
2019-08-28 16:16:19,813 [salt.state       :1813][INFO    ][5511] Executing state service.running for [kube-proxy]
2019-08-28 16:16:19,813 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'status', 'kube-proxy.service', '-n', '0'] in directory '/root'
2019-08-28 16:16:19,837 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-08-28 16:16:19,855 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-08-28 16:16:19,886 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-proxy.service'] in directory '/root'
2019-08-28 16:16:19,915 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-08-28 16:16:19,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-08-28 16:16:19,960 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-08-28 16:16:19,992 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-proxy.service'] in directory '/root'
2019-08-28 16:16:20,172 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5511] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-08-28 16:16:20,194 [salt.state       :300 ][INFO    ][5511] {'kube-proxy': True}
2019-08-28 16:16:20,194 [salt.state       :1951][INFO    ][5511] Completed state [kube-proxy] at time 16:16:20.194694 duration_in_ms=381.944
2019-08-28 16:16:20,199 [salt.minion      :1711][INFO    ][5511] Returning information for job: 20190828161356768692
2019-08-28 16:16:21,511 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command cmd.run with jid 20190828161621502835
2019-08-28 16:16:21,528 [salt.minion      :1432][INFO    ][9960] Starting a new job with PID 9960
2019-08-28 16:16:21,538 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][9960] Executing command 'calicoctl node status' in directory '/root'
2019-08-28 16:16:21,600 [salt.minion      :1711][INFO    ][9960] Returning information for job: 20190828161621502835
2019-08-28 16:16:22,155 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command cmd.run with jid 20190828161622147548
2019-08-28 16:16:22,171 [salt.minion      :1432][INFO    ][9979] Starting a new job with PID 9979
2019-08-28 16:16:22,186 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][9979] Executing command 'calicoctl get ippool' in directory '/root'
2019-08-28 16:16:22,284 [salt.minion      :1711][INFO    ][9979] Returning information for job: 20190828161622147548
2019-08-28 16:16:22,935 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command state.sls with jid 20190828161622927403
2019-08-28 16:16:22,952 [salt.minion      :1432][INFO    ][10000] Starting a new job with PID 10000
2019-08-28 16:16:29,299 [salt.state       :915 ][INFO    ][10000] Loading fresh modules for state activity
2019-08-28 16:16:29,376 [salt.fileclient  :1219][INFO    ][10000] Fetching file from saltenv 'base', ** done ** 'etcd/server/setup.sls'
2019-08-28 16:16:29,477 [salt.state       :1780][INFO    ][10000] Running state [/calico/ipam/v2/assignment/ipv4/block/192.168.0.0-16] at time 16:16:29.477200
2019-08-28 16:16:29,477 [salt.state       :1813][INFO    ][10000] Executing state etcd.set for [/calico/ipam/v2/assignment/ipv4/block/192.168.0.0-16]
2019-08-28 16:16:29,480 [salt.loaded.int.utils.etcd_util:200 ][ERROR   ][10000] etcd: Key not found : /calico
2019-08-28 16:16:29,483 [salt.state       :300 ][INFO    ][10000] {'/calico/ipam/v2/assignment/ipv4/block/192.168.0.0-16': '{"masquerade":true,"cidr":"192.168.0.0/16"}'}
2019-08-28 16:16:29,483 [salt.state       :1951][INFO    ][10000] Completed state [/calico/ipam/v2/assignment/ipv4/block/192.168.0.0-16] at time 16:16:29.483432 duration_in_ms=6.232
2019-08-28 16:16:29,485 [salt.minion      :1711][INFO    ][10000] Returning information for job: 20190828161622927403
2019-08-28 16:16:30,050 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command state.sls with jid 20190828161630039595
2019-08-28 16:16:30,069 [salt.minion      :1432][INFO    ][10012] Starting a new job with PID 10012
2019-08-28 16:16:31,192 [salt.state       :915 ][INFO    ][10012] Loading fresh modules for state activity
2019-08-28 16:16:31,252 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/init.sls'
2019-08-28 16:16:31,339 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/init.sls'
2019-08-28 16:16:31,397 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:31,608 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:31,795 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/service.sls'
2019-08-28 16:16:31,853 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:31,980 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:32,118 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:32,258 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:32,525 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:32,725 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:33,046 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:33,170 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:33,308 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:33,438 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:33,593 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:33,727 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:33,926 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/controller.sls'
2019-08-28 16:16:34,046 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:34,175 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:34,312 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:34,442 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:34,584 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:34,715 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:34,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command 'date "+%FT%TZ"' in directory '/root'
2019-08-28 16:16:34,929 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/setup.sls'
2019-08-28 16:16:34,991 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:35,164 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:35,296 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:35,428 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:35,672 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:35,816 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:35,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:36,111 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:36,337 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:36,481 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:36,628 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:36,758 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:37,055 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:37,214 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:37,357 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:37,525 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:37,744 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:37,912 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:38,081 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/control/init.sls'
2019-08-28 16:16:38,134 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:38,293 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:38,460 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/control/service.sls'
2019-08-28 16:16:38,536 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:38,680 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:38,857 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/control/role.sls'
2019-08-28 16:16:38,927 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:39,066 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:39,900 [salt.state       :1780][INFO    ][10012] Running state [curl] at time 16:16:39.900642
2019-08-28 16:16:39,901 [salt.state       :1813][INFO    ][10012] Executing state pkg.installed for [curl]
2019-08-28 16:16:39,901 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-28 16:16:40,726 [salt.state       :300 ][INFO    ][10012] All specified packages are already installed
2019-08-28 16:16:40,726 [salt.state       :1951][INFO    ][10012] Completed state [curl] at time 16:16:40.726672 duration_in_ms=826.033
2019-08-28 16:16:40,727 [salt.state       :1780][INFO    ][10012] Running state [git] at time 16:16:40.727018
2019-08-28 16:16:40,727 [salt.state       :1813][INFO    ][10012] Executing state pkg.installed for [git]
2019-08-28 16:16:40,736 [salt.state       :300 ][INFO    ][10012] All specified packages are already installed
2019-08-28 16:16:40,736 [salt.state       :1951][INFO    ][10012] Completed state [git] at time 16:16:40.736494 duration_in_ms=9.476
2019-08-28 16:16:40,736 [salt.state       :1780][INFO    ][10012] Running state [apt-transport-https] at time 16:16:40.736733
2019-08-28 16:16:40,736 [salt.state       :1813][INFO    ][10012] Executing state pkg.installed for [apt-transport-https]
2019-08-28 16:16:40,746 [salt.state       :300 ][INFO    ][10012] All specified packages are already installed
2019-08-28 16:16:40,746 [salt.state       :1951][INFO    ][10012] Completed state [apt-transport-https] at time 16:16:40.746183 duration_in_ms=9.45
2019-08-28 16:16:40,746 [salt.state       :1780][INFO    ][10012] Running state [python-apt] at time 16:16:40.746424
2019-08-28 16:16:40,746 [salt.state       :1813][INFO    ][10012] Executing state pkg.installed for [python-apt]
2019-08-28 16:16:40,755 [salt.state       :300 ][INFO    ][10012] All specified packages are already installed
2019-08-28 16:16:40,755 [salt.state       :1951][INFO    ][10012] Completed state [python-apt] at time 16:16:40.755855 duration_in_ms=9.431
2019-08-28 16:16:40,756 [salt.state       :1780][INFO    ][10012] Running state [socat] at time 16:16:40.756116
2019-08-28 16:16:40,756 [salt.state       :1813][INFO    ][10012] Executing state pkg.installed for [socat]
2019-08-28 16:16:40,765 [salt.state       :300 ][INFO    ][10012] All specified packages are already installed
2019-08-28 16:16:40,765 [salt.state       :1951][INFO    ][10012] Completed state [socat] at time 16:16:40.765283 duration_in_ms=9.166
2019-08-28 16:16:40,765 [salt.state       :1780][INFO    ][10012] Running state [openssl] at time 16:16:40.765518
2019-08-28 16:16:40,765 [salt.state       :1813][INFO    ][10012] Executing state pkg.installed for [openssl]
2019-08-28 16:16:40,774 [salt.state       :300 ][INFO    ][10012] All specified packages are already installed
2019-08-28 16:16:40,774 [salt.state       :1951][INFO    ][10012] Completed state [openssl] at time 16:16:40.774928 duration_in_ms=9.409
2019-08-28 16:16:40,775 [salt.state       :1780][INFO    ][10012] Running state [conntrack] at time 16:16:40.775167
2019-08-28 16:16:40,775 [salt.state       :1813][INFO    ][10012] Executing state pkg.installed for [conntrack]
2019-08-28 16:16:40,784 [salt.state       :300 ][INFO    ][10012] All specified packages are already installed
2019-08-28 16:16:40,784 [salt.state       :1951][INFO    ][10012] Completed state [conntrack] at time 16:16:40.784794 duration_in_ms=9.627
2019-08-28 16:16:40,785 [salt.state       :1780][INFO    ][10012] Running state [nfs-common] at time 16:16:40.785028
2019-08-28 16:16:40,785 [salt.state       :1813][INFO    ][10012] Executing state pkg.installed for [nfs-common]
2019-08-28 16:16:40,794 [salt.state       :300 ][INFO    ][10012] All specified packages are already installed
2019-08-28 16:16:40,794 [salt.state       :1951][INFO    ][10012] Completed state [nfs-common] at time 16:16:40.794199 duration_in_ms=9.171
2019-08-28 16:16:40,794 [salt.state       :1780][INFO    ][10012] Running state [cifs-utils] at time 16:16:40.794437
2019-08-28 16:16:40,794 [salt.state       :1813][INFO    ][10012] Executing state pkg.installed for [cifs-utils]
2019-08-28 16:16:40,803 [salt.state       :300 ][INFO    ][10012] All specified packages are already installed
2019-08-28 16:16:40,803 [salt.state       :1951][INFO    ][10012] Completed state [cifs-utils] at time 16:16:40.803723 duration_in_ms=9.286
2019-08-28 16:16:40,806 [salt.state       :1780][INFO    ][10012] Running state [/usr/bin/hyperkube] at time 16:16:40.806951
2019-08-28 16:16:40,807 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/usr/bin/hyperkube]
2019-08-28 16:16:41,532 [salt.state       :300 ][INFO    ][10012] File /usr/bin/hyperkube is in the correct state
2019-08-28 16:16:41,533 [salt.state       :1951][INFO    ][10012] Completed state [/usr/bin/hyperkube] at time 16:16:41.533253 duration_in_ms=726.301
2019-08-28 16:16:41,534 [salt.state       :1780][INFO    ][10012] Running state [/usr/bin/kubectl] at time 16:16:41.534376
2019-08-28 16:16:41,534 [salt.state       :1813][INFO    ][10012] Executing state file.symlink for [/usr/bin/kubectl]
2019-08-28 16:16:41,536 [salt.state       :300 ][INFO    ][10012] Symlink /usr/bin/kubectl is present and owned by root:root
2019-08-28 16:16:41,536 [salt.state       :1951][INFO    ][10012] Completed state [/usr/bin/kubectl] at time 16:16:41.536429 duration_in_ms=2.054
2019-08-28 16:16:41,536 [salt.state       :1780][INFO    ][10012] Running state [containerd] at time 16:16:41.536663
2019-08-28 16:16:41,536 [salt.state       :1813][INFO    ][10012] Executing state pkg.installed for [containerd]
2019-08-28 16:16:41,546 [salt.state       :300 ][INFO    ][10012] All specified packages are already installed
2019-08-28 16:16:41,546 [salt.state       :1951][INFO    ][10012] Completed state [containerd] at time 16:16:41.546232 duration_in_ms=9.569
2019-08-28 16:16:41,546 [salt.state       :1780][INFO    ][10012] Running state [/etc/containerd/config.toml] at time 16:16:41.546468
2019-08-28 16:16:41,546 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/containerd/config.toml]
2019-08-28 16:16:41,606 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:41,736 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:41,889 [salt.state       :300 ][INFO    ][10012] File /etc/containerd/config.toml is in the correct state
2019-08-28 16:16:41,890 [salt.state       :1951][INFO    ][10012] Completed state [/etc/containerd/config.toml] at time 16:16:41.890027 duration_in_ms=343.558
2019-08-28 16:16:41,892 [salt.state       :1780][INFO    ][10012] Running state [containerd] at time 16:16:41.892431
2019-08-28 16:16:41,892 [salt.state       :1813][INFO    ][10012] Executing state service.running for [containerd]
2019-08-28 16:16:41,893 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'status', 'containerd.service', '-n', '0'] in directory '/root'
2019-08-28 16:16:41,926 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-08-28 16:16:41,944 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'containerd.service'] in directory '/root'
2019-08-28 16:16:41,958 [salt.state       :300 ][INFO    ][10012] The service containerd is already running
2019-08-28 16:16:41,958 [salt.state       :1951][INFO    ][10012] Completed state [containerd] at time 16:16:41.958875 duration_in_ms=66.444
2019-08-28 16:16:41,961 [salt.state       :1780][INFO    ][10012] Running state [/tmp/crictl] at time 16:16:41.961036
2019-08-28 16:16:41,961 [salt.state       :1813][INFO    ][10012] Executing state archive.extracted for [/tmp/crictl]
2019-08-28 16:16:42,276 [salt.state       :300 ][INFO    ][10012] All files in archive are already present
2019-08-28 16:16:42,276 [salt.state       :1951][INFO    ][10012] Completed state [/tmp/crictl] at time 16:16:42.276212 duration_in_ms=315.176
2019-08-28 16:16:42,277 [salt.state       :1780][INFO    ][10012] Running state [/usr/local/bin/crictl] at time 16:16:42.277302
2019-08-28 16:16:42,277 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/usr/local/bin/crictl]
2019-08-28 16:16:42,277 [salt.loaded.int.states.file:1019][WARNING ][10012] Use of argument owner found, "owner" is invalid, please use "user"
2019-08-28 16:16:42,702 [salt.state       :300 ][INFO    ][10012] File /usr/local/bin/crictl is in the correct state
2019-08-28 16:16:42,702 [salt.state       :1951][INFO    ][10012] Completed state [/usr/local/bin/crictl] at time 16:16:42.702323 duration_in_ms=425.02
2019-08-28 16:16:42,702 [salt.state       :1780][INFO    ][10012] Running state [/etc/crictl.yaml] at time 16:16:42.702593
2019-08-28 16:16:42,702 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/crictl.yaml]
2019-08-28 16:16:42,704 [salt.state       :300 ][INFO    ][10012] File /etc/crictl.yaml is in the correct state
2019-08-28 16:16:42,704 [salt.state       :1951][INFO    ][10012] Completed state [/etc/crictl.yaml] at time 16:16:42.704651 duration_in_ms=2.058
2019-08-28 16:16:42,704 [salt.state       :1780][INFO    ][10012] Running state [/etc/criproxy] at time 16:16:42.704899
2019-08-28 16:16:42,705 [salt.state       :1813][INFO    ][10012] Executing state file.absent for [/etc/criproxy]
2019-08-28 16:16:42,705 [salt.state       :300 ][INFO    ][10012] File /etc/criproxy is not present
2019-08-28 16:16:42,705 [salt.state       :1951][INFO    ][10012] Completed state [/etc/criproxy] at time 16:16:42.705689 duration_in_ms=0.79
2019-08-28 16:16:42,705 [salt.state       :1780][INFO    ][10012] Running state [criproxy] at time 16:16:42.705922
2019-08-28 16:16:42,706 [salt.state       :1813][INFO    ][10012] Executing state service.dead for [criproxy]
2019-08-28 16:16:42,706 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'status', 'criproxy.service', '-n', '0'] in directory '/root'
2019-08-28 16:16:42,726 [salt.state       :300 ][INFO    ][10012] The named service criproxy is not available
2019-08-28 16:16:42,726 [salt.state       :1951][INFO    ][10012] Completed state [criproxy] at time 16:16:42.726496 duration_in_ms=20.573
2019-08-28 16:16:42,727 [salt.state       :1780][INFO    ][10012] Running state [/etc/systemd/system/kubelet.service] at time 16:16:42.727009
2019-08-28 16:16:42,727 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/systemd/system/kubelet.service]
2019-08-28 16:16:42,791 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:42,915 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:43,043 [salt.state       :300 ][INFO    ][10012] File /etc/systemd/system/kubelet.service is in the correct state
2019-08-28 16:16:43,044 [salt.state       :1951][INFO    ][10012] Completed state [/etc/systemd/system/kubelet.service] at time 16:16:43.044016 duration_in_ms=317.007
2019-08-28 16:16:43,044 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/config] at time 16:16:43.044367
2019-08-28 16:16:43,044 [salt.state       :1813][INFO    ][10012] Executing state file.absent for [/etc/kubernetes/config]
2019-08-28 16:16:43,045 [salt.state       :300 ][INFO    ][10012] File /etc/kubernetes/config is not present
2019-08-28 16:16:43,045 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/config] at time 16:16:43.045287 duration_in_ms=0.92
2019-08-28 16:16:43,045 [salt.state       :1780][INFO    ][10012] Running state [/etc/default/kubelet] at time 16:16:43.045531
2019-08-28 16:16:43,045 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/default/kubelet]
2019-08-28 16:16:43,129 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:43,263 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:43,416 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:43,554 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:43,685 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:43,814 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:44,027 [salt.state       :300 ][INFO    ][10012] File /etc/default/kubelet is in the correct state
2019-08-28 16:16:44,028 [salt.state       :1951][INFO    ][10012] Completed state [/etc/default/kubelet] at time 16:16:44.028020 duration_in_ms=982.489
2019-08-28 16:16:44,028 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/kubelet.kubeconfig] at time 16:16:44.028476
2019-08-28 16:16:44,028 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/kubelet.kubeconfig]
2019-08-28 16:16:44,088 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:44,208 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:44,348 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:44,482 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:44,628 [salt.state       :300 ][INFO    ][10012] File /etc/kubernetes/kubelet.kubeconfig is in the correct state
2019-08-28 16:16:44,629 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/kubelet.kubeconfig] at time 16:16:44.629239 duration_in_ms=600.762
2019-08-28 16:16:44,629 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/manifests] at time 16:16:44.629823
2019-08-28 16:16:44,630 [salt.state       :1813][INFO    ][10012] Executing state file.directory for [/etc/kubernetes/manifests]
2019-08-28 16:16:44,631 [salt.state       :300 ][INFO    ][10012] Directory /etc/kubernetes/manifests is in the correct state
Directory /etc/kubernetes/manifests updated
2019-08-28 16:16:44,632 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/manifests] at time 16:16:44.632239 duration_in_ms=2.416
2019-08-28 16:16:44,636 [salt.state       :1780][INFO    ][10012] Running state [kubelet] at time 16:16:44.636787
2019-08-28 16:16:44,637 [salt.state       :1813][INFO    ][10012] Executing state service.running for [kubelet]
2019-08-28 16:16:44,638 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'status', 'kubelet.service', '-n', '0'] in directory '/root'
2019-08-28 16:16:44,665 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-08-28 16:16:44,686 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-08-28 16:16:44,712 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kubelet.service'] in directory '/root'
2019-08-28 16:16:44,744 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-08-28 16:16:44,762 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-08-28 16:16:44,778 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-08-28 16:16:44,792 [salt.state       :300 ][INFO    ][10012] {'kubelet': True}
2019-08-28 16:16:44,793 [salt.state       :1951][INFO    ][10012] Completed state [kubelet] at time 16:16:44.793098 duration_in_ms=156.31
2019-08-28 16:16:44,793 [salt.state       :1780][INFO    ][10012] Running state [/etc/logrotate.d/kubernetes] at time 16:16:44.793640
2019-08-28 16:16:44,794 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/logrotate.d/kubernetes]
2019-08-28 16:16:44,821 [salt.state       :300 ][INFO    ][10012] File /etc/logrotate.d/kubernetes is in the correct state
2019-08-28 16:16:44,822 [salt.state       :1951][INFO    ][10012] Completed state [/etc/logrotate.d/kubernetes] at time 16:16:44.822387 duration_in_ms=28.747
2019-08-28 16:16:44,822 [salt.state       :1780][INFO    ][10012] Running state [/opt/cni/bin] at time 16:16:44.822784
2019-08-28 16:16:44,823 [salt.state       :1813][INFO    ][10012] Executing state archive.extracted for [/opt/cni/bin]
2019-08-28 16:16:45,130 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command saltutil.find_job with jid 20190828161645117610
2019-08-28 16:16:45,148 [salt.minion      :1432][INFO    ][11158] Starting a new job with PID 11158
2019-08-28 16:16:45,165 [salt.minion      :1711][INFO    ][11158] Returning information for job: 20190828161645117610
2019-08-28 16:16:45,985 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:46,677 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['tar', '--version'] in directory '/root'
2019-08-28 16:16:46,702 [salt.state       :300 ][INFO    ][10012] {'extracted_files': 'no tar output so far'}
2019-08-28 16:16:46,703 [salt.state       :1951][INFO    ][10012] Completed state [/opt/cni/bin] at time 16:16:46.702979 duration_in_ms=1880.191
2019-08-28 16:16:46,703 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/addons] at time 16:16:46.703673
2019-08-28 16:16:46,704 [salt.state       :1813][INFO    ][10012] Executing state file.directory for [/etc/kubernetes/addons]
2019-08-28 16:16:46,706 [salt.state       :300 ][INFO    ][10012] Directory /etc/kubernetes/addons is in the correct state
Directory /etc/kubernetes/addons updated
2019-08-28 16:16:46,706 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/addons] at time 16:16:46.706513 duration_in_ms=2.84
2019-08-28 16:16:46,707 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/addons/calico/calico-kube-controllers.yml] at time 16:16:46.706934
2019-08-28 16:16:46,707 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/addons/calico/calico-kube-controllers.yml]
2019-08-28 16:16:46,776 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:46,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:47,100 [salt.state       :300 ][INFO    ][10012] File /etc/kubernetes/addons/calico/calico-kube-controllers.yml is in the correct state
2019-08-28 16:16:47,100 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/addons/calico/calico-kube-controllers.yml] at time 16:16:47.100470 duration_in_ms=393.536
2019-08-28 16:16:47,100 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/addons/calico/calico-rbac.yml] at time 16:16:47.100801
2019-08-28 16:16:47,101 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/addons/calico/calico-rbac.yml]
2019-08-28 16:16:47,125 [salt.state       :300 ][INFO    ][10012] File /etc/kubernetes/addons/calico/calico-rbac.yml is in the correct state
2019-08-28 16:16:47,125 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/addons/calico/calico-rbac.yml] at time 16:16:47.125212 duration_in_ms=24.411
2019-08-28 16:16:47,125 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/addons/netchecker/netchecker-svc.yml] at time 16:16:47.125453
2019-08-28 16:16:47,125 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-svc.yml]
2019-08-28 16:16:47,186 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:47,343 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:47,504 [salt.state       :300 ][INFO    ][10012] File /etc/kubernetes/addons/netchecker/netchecker-svc.yml is in the correct state
2019-08-28 16:16:47,505 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/addons/netchecker/netchecker-svc.yml] at time 16:16:47.505188 duration_in_ms=379.735
2019-08-28 16:16:47,505 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/addons/netchecker/netchecker-server.yml] at time 16:16:47.505541
2019-08-28 16:16:47,505 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-server.yml]
2019-08-28 16:16:47,587 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:47,740 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:47,890 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:48,100 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:48,254 [salt.state       :300 ][INFO    ][10012] File /etc/kubernetes/addons/netchecker/netchecker-server.yml is in the correct state
2019-08-28 16:16:48,254 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/addons/netchecker/netchecker-server.yml] at time 16:16:48.254477 duration_in_ms=748.935
2019-08-28 16:16:48,254 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/addons/netchecker/netchecker-agent.yml] at time 16:16:48.254822
2019-08-28 16:16:48,255 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-agent.yml]
2019-08-28 16:16:48,328 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:48,462 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:48,608 [salt.state       :300 ][INFO    ][10012] File /etc/kubernetes/addons/netchecker/netchecker-agent.yml is in the correct state
2019-08-28 16:16:48,609 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/addons/netchecker/netchecker-agent.yml] at time 16:16:48.609428 duration_in_ms=354.604
2019-08-28 16:16:48,610 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml] at time 16:16:48.610132
2019-08-28 16:16:48,610 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml]
2019-08-28 16:16:48,638 [salt.state       :300 ][INFO    ][10012] File /etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml is in the correct state
2019-08-28 16:16:48,639 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml] at time 16:16:48.639109 duration_in_ms=28.978
2019-08-28 16:16:48,639 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/addons/netchecker/netchecker-roles.yml] at time 16:16:48.639488
2019-08-28 16:16:48,639 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-roles.yml]
2019-08-28 16:16:48,663 [salt.state       :300 ][INFO    ][10012] File /etc/kubernetes/addons/netchecker/netchecker-roles.yml is in the correct state
2019-08-28 16:16:48,663 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/addons/netchecker/netchecker-roles.yml] at time 16:16:48.663571 duration_in_ms=24.083
2019-08-28 16:16:48,663 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/addons/prometheus/prometheus-roles.yml] at time 16:16:48.663840
2019-08-28 16:16:48,664 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/addons/prometheus/prometheus-roles.yml]
2019-08-28 16:16:48,685 [salt.state       :300 ][INFO    ][10012] File /etc/kubernetes/addons/prometheus/prometheus-roles.yml is in the correct state
2019-08-28 16:16:48,685 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/addons/prometheus/prometheus-roles.yml] at time 16:16:48.685525 duration_in_ms=21.685
2019-08-28 16:16:48,685 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/addons/dns] at time 16:16:48.685780
2019-08-28 16:16:48,686 [salt.state       :1813][INFO    ][10012] Executing state file.absent for [/etc/kubernetes/addons/dns]
2019-08-28 16:16:48,686 [salt.state       :300 ][INFO    ][10012] {'removed': '/etc/kubernetes/addons/dns'}
2019-08-28 16:16:48,686 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/addons/dns] at time 16:16:48.686851 duration_in_ms=1.071
2019-08-28 16:16:48,688 [salt.state       :1780][INFO    ][10012] Running state [kubectl -n kube-system delete svc kube-dns > /dev/null || echo "kube-dns is absent. OK" && true] at time 16:16:48.688261
2019-08-28 16:16:48,688 [salt.state       :1813][INFO    ][10012] Executing state cmd.run for [kubectl -n kube-system delete svc kube-dns > /dev/null || echo "kube-dns is absent. OK" && true]
2019-08-28 16:16:48,689 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command 'kubectl -n kube-system delete svc kube-dns > /dev/null || echo "kube-dns is absent. OK" && true' in directory '/root'
2019-08-28 16:16:48,847 [salt.state       :300 ][INFO    ][10012] {'pid': 11356, '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-08-28 16:16:48,848 [salt.state       :1951][INFO    ][10012] Completed state [kubectl -n kube-system delete svc kube-dns > /dev/null || echo "kube-dns is absent. OK" && true] at time 16:16:48.847882 duration_in_ms=159.62
2019-08-28 16:16:48,848 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/addons/coredns/coredns-cm.yml] at time 16:16:48.848508
2019-08-28 16:16:48,849 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/addons/coredns/coredns-cm.yml]
2019-08-28 16:16:48,874 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/coredns/coredns-cm.yml'
2019-08-28 16:16:48,932 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:49,091 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:49,233 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:49,405 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:49,550 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:16:49,551 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/addons/coredns/coredns-cm.yml] at time 16:16:49.551047 duration_in_ms=702.539
2019-08-28 16:16:49,551 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/addons/coredns/coredns-deploy.yml] at time 16:16:49.551382
2019-08-28 16:16:49,551 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/addons/coredns/coredns-deploy.yml]
2019-08-28 16:16:49,578 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/coredns/coredns-deploy.yml'
2019-08-28 16:16:49,629 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:49,777 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:49,919 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:16:49,919 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/addons/coredns/coredns-deploy.yml] at time 16:16:49.919593 duration_in_ms=368.21
2019-08-28 16:16:49,920 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/addons/coredns/coredns-svc.yml] at time 16:16:49.920191
2019-08-28 16:16:49,920 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/addons/coredns/coredns-svc.yml]
2019-08-28 16:16:49,945 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/coredns/coredns-svc.yml'
2019-08-28 16:16:49,991 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:50,141 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:50,308 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:16:50,309 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/addons/coredns/coredns-svc.yml] at time 16:16:50.309126 duration_in_ms=388.933
2019-08-28 16:16:50,309 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/addons/coredns/coredns-rbac.yml] at time 16:16:50.309507
2019-08-28 16:16:50,309 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/addons/coredns/coredns-rbac.yml]
2019-08-28 16:16:50,328 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/coredns/coredns-rbac.yml'
2019-08-28 16:16:50,376 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:50,541 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:50,679 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:16:50,680 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/addons/coredns/coredns-rbac.yml] at time 16:16:50.679998 duration_in_ms=370.491
2019-08-28 16:16:50,680 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/addons/metrics-server] at time 16:16:50.680358
2019-08-28 16:16:50,680 [salt.state       :1813][INFO    ][10012] Executing state file.absent for [/etc/kubernetes/addons/metrics-server]
2019-08-28 16:16:50,681 [salt.state       :300 ][INFO    ][10012] File /etc/kubernetes/addons/metrics-server is not present
2019-08-28 16:16:50,681 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/addons/metrics-server] at time 16:16:50.681404 duration_in_ms=1.046
2019-08-28 16:16:50,681 [salt.state       :1780][INFO    ][10012] Running state [/srv/kubernetes/known_tokens.csv] at time 16:16:50.681687
2019-08-28 16:16:50,682 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/srv/kubernetes/known_tokens.csv]
2019-08-28 16:16:50,708 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/known_tokens.csv'
2019-08-28 16:16:50,764 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:50,942 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:51,093 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:51,250 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:51,398 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:16:51,398 [salt.state       :1951][INFO    ][10012] Completed state [/srv/kubernetes/known_tokens.csv] at time 16:16:51.398712 duration_in_ms=717.025
2019-08-28 16:16:51,399 [salt.state       :1780][INFO    ][10012] Running state [/srv/kubernetes/basic_auth.csv] at time 16:16:51.399032
2019-08-28 16:16:51,399 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/srv/kubernetes/basic_auth.csv]
2019-08-28 16:16:51,421 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/basic_auth.csv'
2019-08-28 16:16:51,487 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:51,638 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:51,822 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:16:51,822 [salt.state       :1951][INFO    ][10012] Completed state [/srv/kubernetes/basic_auth.csv] at time 16:16:51.822739 duration_in_ms=423.705
2019-08-28 16:16:51,823 [salt.state       :1780][INFO    ][10012] Running state [/etc/default/kube-apiserver] at time 16:16:51.823376
2019-08-28 16:16:51,824 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/default/kube-apiserver]
2019-08-28 16:16:51,827 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:16:51,827 [salt.state       :1951][INFO    ][10012] Completed state [/etc/default/kube-apiserver] at time 16:16:51.827299 duration_in_ms=3.923
2019-08-28 16:16:51,828 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/scheduler.kubeconfig] at time 16:16:51.827990
2019-08-28 16:16:51,828 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/scheduler.kubeconfig]
2019-08-28 16:16:51,850 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-scheduler/scheduler.kubeconfig'
2019-08-28 16:16:51,899 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:52,052 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:52,188 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:52,344 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:52,484 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:16:52,485 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/scheduler.kubeconfig] at time 16:16:52.485205 duration_in_ms=657.214
2019-08-28 16:16:52,485 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/controller-manager.kubeconfig] at time 16:16:52.485529
2019-08-28 16:16:52,486 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/controller-manager.kubeconfig]
2019-08-28 16:16:52,507 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-controller-manager/controller-manager.kubeconfig'
2019-08-28 16:16:52,557 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:52,715 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:52,864 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:53,030 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:16:53,175 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:16:53,176 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/controller-manager.kubeconfig] at time 16:16:53.175866 duration_in_ms=690.336
2019-08-28 16:16:53,176 [salt.state       :1780][INFO    ][10012] Running state [/etc/default/kube-controller-manager] at time 16:16:53.176454
2019-08-28 16:16:53,176 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/default/kube-controller-manager]
2019-08-28 16:16:53,179 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:16:53,179 [salt.state       :1951][INFO    ][10012] Completed state [/etc/default/kube-controller-manager] at time 16:16:53.179565 duration_in_ms=3.112
2019-08-28 16:16:53,180 [salt.state       :1780][INFO    ][10012] Running state [/etc/default/kube-scheduler] at time 16:16:53.180235
2019-08-28 16:16:53,180 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/default/kube-scheduler]
2019-08-28 16:16:53,182 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:16:53,183 [salt.state       :1951][INFO    ][10012] Completed state [/etc/default/kube-scheduler] at time 16:16:53.183139 duration_in_ms=2.905
2019-08-28 16:16:53,184 [salt.state       :1780][INFO    ][10012] Running state [/etc/systemd/system/kube-apiserver.service] at time 16:16:53.184108
2019-08-28 16:16:53,184 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/systemd/system/kube-apiserver.service]
2019-08-28 16:16:53,208 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-apiserver.service'
2019-08-28 16:16:53,219 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:16:53,220 [salt.state       :1951][INFO    ][10012] Completed state [/etc/systemd/system/kube-apiserver.service] at time 16:16:53.220214 duration_in_ms=36.106
2019-08-28 16:16:53,220 [salt.state       :1780][INFO    ][10012] Running state [/etc/systemd/system/kube-scheduler.service] at time 16:16:53.220637
2019-08-28 16:16:53,220 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/systemd/system/kube-scheduler.service]
2019-08-28 16:16:53,238 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-scheduler.service'
2019-08-28 16:16:53,248 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:16:53,248 [salt.state       :1951][INFO    ][10012] Completed state [/etc/systemd/system/kube-scheduler.service] at time 16:16:53.248955 duration_in_ms=28.317
2019-08-28 16:16:53,249 [salt.state       :1780][INFO    ][10012] Running state [/etc/systemd/system/kube-controller-manager.service] at time 16:16:53.249305
2019-08-28 16:16:53,249 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/systemd/system/kube-controller-manager.service]
2019-08-28 16:16:53,266 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-controller-manager.service'
2019-08-28 16:16:53,276 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:16:53,276 [salt.state       :1951][INFO    ][10012] Completed state [/etc/systemd/system/kube-controller-manager.service] at time 16:16:53.276778 duration_in_ms=27.472
2019-08-28 16:16:53,277 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/ssl/kubernetes-server.crt] at time 16:16:53.277314
2019-08-28 16:16:53,277 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/ssl/kubernetes-server.crt]
2019-08-28 16:16:53,297 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** '_certs/kubernetes/kubernetes-server.crt'
2019-08-28 16:16:53,298 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:16:53,299 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/ssl/kubernetes-server.crt] at time 16:16:53.299059 duration_in_ms=21.744
2019-08-28 16:16:53,299 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/ssl/kubernetes-server.key] at time 16:16:53.299575
2019-08-28 16:16:53,300 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/ssl/kubernetes-server.key]
2019-08-28 16:16:53,320 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** '_certs/kubernetes/kubernetes-server.key'
2019-08-28 16:16:53,322 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:16:53,322 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/ssl/kubernetes-server.key] at time 16:16:53.322889 duration_in_ms=23.314
2019-08-28 16:16:53,323 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/ssl/kubernetes-server.pem] at time 16:16:53.323459
2019-08-28 16:16:53,323 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/ssl/kubernetes-server.pem]
2019-08-28 16:16:53,344 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** '_certs/kubernetes/kubernetes-server.pem'
2019-08-28 16:16:53,346 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:16:53,346 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/ssl/kubernetes-server.pem] at time 16:16:53.346330 duration_in_ms=22.871
2019-08-28 16:16:53,355 [salt.state       :1780][INFO    ][10012] Running state [kube-apiserver] at time 16:16:53.355008
2019-08-28 16:16:53,355 [salt.state       :1813][INFO    ][10012] Executing state service.running for [kube-apiserver]
2019-08-28 16:16:53,356 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'status', 'kube-apiserver.service', '-n', '0'] in directory '/root'
2019-08-28 16:16:53,388 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-active', 'kube-apiserver.service'] in directory '/root'
2019-08-28 16:16:53,413 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'kube-apiserver.service'] in directory '/root'
2019-08-28 16:16:53,450 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-apiserver.service'] in directory '/root'
2019-08-28 16:17:06,017 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-active', 'kube-apiserver.service'] in directory '/root'
2019-08-28 16:17:06,050 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'kube-apiserver.service'] in directory '/root'
2019-08-28 16:17:06,076 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'kube-apiserver.service'] in directory '/root'
2019-08-28 16:17:06,101 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-apiserver.service'] in directory '/root'
2019-08-28 16:17:06,282 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'kube-apiserver.service'] in directory '/root'
2019-08-28 16:17:06,300 [salt.state       :300 ][INFO    ][10012] {'kube-apiserver': True}
2019-08-28 16:17:06,300 [salt.state       :1951][INFO    ][10012] Completed state [kube-apiserver] at time 16:17:06.300593 duration_in_ms=12945.584
2019-08-28 16:17:06,307 [salt.state       :1780][INFO    ][10012] Running state [kube-scheduler] at time 16:17:06.307354
2019-08-28 16:17:06,307 [salt.state       :1813][INFO    ][10012] Executing state service.running for [kube-scheduler]
2019-08-28 16:17:06,308 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'status', 'kube-scheduler.service', '-n', '0'] in directory '/root'
2019-08-28 16:17:06,330 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-active', 'kube-scheduler.service'] in directory '/root'
2019-08-28 16:17:06,346 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'kube-scheduler.service'] in directory '/root'
2019-08-28 16:17:06,369 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-scheduler.service'] in directory '/root'
2019-08-28 16:17:06,399 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-active', 'kube-scheduler.service'] in directory '/root'
2019-08-28 16:17:06,416 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'kube-scheduler.service'] in directory '/root'
2019-08-28 16:17:06,447 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'kube-scheduler.service'] in directory '/root'
2019-08-28 16:17:06,479 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-scheduler.service'] in directory '/root'
2019-08-28 16:17:06,678 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'kube-scheduler.service'] in directory '/root'
2019-08-28 16:17:06,701 [salt.state       :300 ][INFO    ][10012] {'kube-scheduler': True}
2019-08-28 16:17:06,701 [salt.state       :1951][INFO    ][10012] Completed state [kube-scheduler] at time 16:17:06.701546 duration_in_ms=394.191
2019-08-28 16:17:06,706 [salt.state       :1780][INFO    ][10012] Running state [kube-controller-manager] at time 16:17:06.706450
2019-08-28 16:17:06,706 [salt.state       :1813][INFO    ][10012] Executing state service.running for [kube-controller-manager]
2019-08-28 16:17:06,707 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'status', 'kube-controller-manager.service', '-n', '0'] in directory '/root'
2019-08-28 16:17:06,731 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-active', 'kube-controller-manager.service'] in directory '/root'
2019-08-28 16:17:06,750 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'kube-controller-manager.service'] in directory '/root'
2019-08-28 16:17:06,780 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-controller-manager.service'] in directory '/root'
2019-08-28 16:17:06,809 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-active', 'kube-controller-manager.service'] in directory '/root'
2019-08-28 16:17:06,828 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'kube-controller-manager.service'] in directory '/root'
2019-08-28 16:17:06,856 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'kube-controller-manager.service'] in directory '/root'
2019-08-28 16:17:06,885 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-controller-manager.service'] in directory '/root'
2019-08-28 16:17:07,065 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'kube-controller-manager.service'] in directory '/root'
2019-08-28 16:17:07,083 [salt.state       :300 ][INFO    ][10012] {'kube-controller-manager': True}
2019-08-28 16:17:07,083 [salt.state       :1951][INFO    ][10012] Completed state [kube-controller-manager] at time 16:17:07.083301 duration_in_ms=376.85
2019-08-28 16:17:07,083 [salt.state       :1780][INFO    ][10012] Running state [kubectl create ns "netchecker"] at time 16:17:07.083670
2019-08-28 16:17:07,084 [salt.state       :1813][INFO    ][10012] Executing state cmd.run for [kubectl create ns "netchecker"]
2019-08-28 16:17:07,084 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command 'kubectl get ns -o=custom-columns=NAME:.metadata.name | grep -v NAME | grep "netchecker"' in directory '/root'
2019-08-28 16:17:07,354 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command 'kubectl create ns "netchecker"' in directory '/root'
2019-08-28 16:17:07,520 [salt.state       :300 ][INFO    ][10012] {'pid': 12216, 'retcode': 0, 'stderr': '', 'stdout': 'namespace/netchecker created'}
2019-08-28 16:17:07,521 [salt.state       :1951][INFO    ][10012] Completed state [kubectl create ns "netchecker"] at time 16:17:07.521234 duration_in_ms=437.563
2019-08-28 16:17:07,522 [salt.state       :1780][INFO    ][10012] Running state [/usr/bin/calicoctl] at time 16:17:07.522017
2019-08-28 16:17:07,522 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/usr/bin/calicoctl]
2019-08-28 16:17:07,666 [salt.state       :300 ][INFO    ][10012] File /usr/bin/calicoctl is in the correct state
2019-08-28 16:17:07,666 [salt.state       :1951][INFO    ][10012] Completed state [/usr/bin/calicoctl] at time 16:17:07.666894 duration_in_ms=144.876
2019-08-28 16:17:07,667 [salt.state       :1780][INFO    ][10012] Running state [/usr/bin/birdcl] at time 16:17:07.667319
2019-08-28 16:17:07,667 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/usr/bin/birdcl]
2019-08-28 16:17:07,670 [salt.state       :300 ][INFO    ][10012] File /usr/bin/birdcl is in the correct state
2019-08-28 16:17:07,671 [salt.state       :1951][INFO    ][10012] Completed state [/usr/bin/birdcl] at time 16:17:07.671025 duration_in_ms=3.706
2019-08-28 16:17:07,671 [salt.state       :1780][INFO    ][10012] Running state [/opt/cni/bin/calico] at time 16:17:07.671403
2019-08-28 16:17:07,671 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/opt/cni/bin/calico]
2019-08-28 16:17:07,798 [salt.state       :300 ][INFO    ][10012] File /opt/cni/bin/calico is in the correct state
2019-08-28 16:17:07,798 [salt.state       :1951][INFO    ][10012] Completed state [/opt/cni/bin/calico] at time 16:17:07.798872 duration_in_ms=127.468
2019-08-28 16:17:07,799 [salt.state       :1780][INFO    ][10012] Running state [/opt/cni/bin/calico-ipam] at time 16:17:07.799288
2019-08-28 16:17:07,799 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/opt/cni/bin/calico-ipam]
2019-08-28 16:17:07,938 [salt.state       :300 ][INFO    ][10012] File /opt/cni/bin/calico-ipam is in the correct state
2019-08-28 16:17:07,938 [salt.state       :1951][INFO    ][10012] Completed state [/opt/cni/bin/calico-ipam] at time 16:17:07.938706 duration_in_ms=139.418
2019-08-28 16:17:07,939 [salt.state       :1780][INFO    ][10012] Running state [/etc/cni/net.d/10-calico.conf] at time 16:17:07.939049
2019-08-28 16:17:07,939 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/cni/net.d/10-calico.conf]
2019-08-28 16:17:08,009 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:17:08,172 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:17:08,335 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:17:08,480 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:17:08,632 [salt.state       :300 ][INFO    ][10012] File /etc/cni/net.d/10-calico.conf is in the correct state
2019-08-28 16:17:08,633 [salt.state       :1951][INFO    ][10012] Completed state [/etc/cni/net.d/10-calico.conf] at time 16:17:08.633682 duration_in_ms=694.632
2019-08-28 16:17:08,634 [salt.state       :1780][INFO    ][10012] Running state [/etc/calico/network-environment] at time 16:17:08.634167
2019-08-28 16:17:08,634 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/calico/network-environment]
2019-08-28 16:17:08,707 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:17:08,877 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:17:09,034 [salt.state       :300 ][INFO    ][10012] File /etc/calico/network-environment is in the correct state
2019-08-28 16:17:09,036 [salt.state       :1951][INFO    ][10012] Completed state [/etc/calico/network-environment] at time 16:17:09.034939 duration_in_ms=400.769
2019-08-28 16:17:09,036 [salt.state       :1780][INFO    ][10012] Running state [/etc/calico/calicoctl.cfg] at time 16:17:09.036490
2019-08-28 16:17:09,036 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/calico/calicoctl.cfg]
2019-08-28 16:17:09,113 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:17:09,274 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:17:09,498 [salt.state       :300 ][INFO    ][10012] File /etc/calico/calicoctl.cfg is in the correct state
2019-08-28 16:17:09,499 [salt.state       :1951][INFO    ][10012] Completed state [/etc/calico/calicoctl.cfg] at time 16:17:09.498998 duration_in_ms=462.508
2019-08-28 16:17:09,500 [salt.state       :1780][INFO    ][10012] Running state [/etc/systemd/system/calico-node.service] at time 16:17:09.500533
2019-08-28 16:17:09,501 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/systemd/system/calico-node.service]
2019-08-28 16:17:09,537 [salt.state       :300 ][INFO    ][10012] File /etc/systemd/system/calico-node.service is in the correct state
2019-08-28 16:17:09,538 [salt.state       :1951][INFO    ][10012] Completed state [/etc/systemd/system/calico-node.service] at time 16:17:09.538218 duration_in_ms=37.685
2019-08-28 16:17:09,538 [salt.state       :1780][INFO    ][10012] Running state [/var/lib/calico] at time 16:17:09.538599
2019-08-28 16:17:09,538 [salt.state       :1813][INFO    ][10012] Executing state file.directory for [/var/lib/calico]
2019-08-28 16:17:09,539 [salt.state       :300 ][INFO    ][10012] Directory /var/lib/calico is in the correct state
Directory /var/lib/calico updated
2019-08-28 16:17:09,540 [salt.state       :1951][INFO    ][10012] Completed state [/var/lib/calico] at time 16:17:09.540410 duration_in_ms=1.81
2019-08-28 16:17:09,540 [salt.state       :1780][INFO    ][10012] Running state [/var/log/calico] at time 16:17:09.540767
2019-08-28 16:17:09,541 [salt.state       :1813][INFO    ][10012] Executing state file.directory for [/var/log/calico]
2019-08-28 16:17:09,542 [salt.state       :300 ][INFO    ][10012] Directory /var/log/calico is in the correct state
Directory /var/log/calico updated
2019-08-28 16:17:09,542 [salt.state       :1951][INFO    ][10012] Completed state [/var/log/calico] at time 16:17:09.542542 duration_in_ms=1.775
2019-08-28 16:17:09,545 [salt.state       :1780][INFO    ][10012] Running state [calico-node] at time 16:17:09.545686
2019-08-28 16:17:09,546 [salt.state       :1813][INFO    ][10012] Executing state service.running for [calico-node]
2019-08-28 16:17:09,546 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'status', 'calico-node.service', '-n', '0'] in directory '/root'
2019-08-28 16:17:09,577 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-08-28 16:17:09,602 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-08-28 16:17:09,625 [salt.state       :300 ][INFO    ][10012] The service calico-node is already running
2019-08-28 16:17:09,626 [salt.state       :1951][INFO    ][10012] Completed state [calico-node] at time 16:17:09.626267 duration_in_ms=80.58
2019-08-28 16:17:09,627 [salt.state       :1780][INFO    ][10012] Running state [/etc/kubernetes/proxy.kubeconfig] at time 16:17:09.627123
2019-08-28 16:17:09,627 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/kubernetes/proxy.kubeconfig]
2019-08-28 16:17:09,719 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:17:09,864 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:17:10,039 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:17:10,231 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] 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-08-28 16:17:10,444 [salt.state       :300 ][INFO    ][10012] File /etc/kubernetes/proxy.kubeconfig is in the correct state
2019-08-28 16:17:10,445 [salt.state       :1951][INFO    ][10012] Completed state [/etc/kubernetes/proxy.kubeconfig] at time 16:17:10.445190 duration_in_ms=818.068
2019-08-28 16:17:10,445 [salt.state       :1780][INFO    ][10012] Running state [/etc/systemd/system/kube-proxy.service] at time 16:17:10.445615
2019-08-28 16:17:10,446 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/systemd/system/kube-proxy.service]
2019-08-28 16:17:10,473 [salt.state       :300 ][INFO    ][10012] File /etc/systemd/system/kube-proxy.service is in the correct state
2019-08-28 16:17:10,473 [salt.state       :1951][INFO    ][10012] Completed state [/etc/systemd/system/kube-proxy.service] at time 16:17:10.473405 duration_in_ms=27.79
2019-08-28 16:17:10,473 [salt.state       :1780][INFO    ][10012] Running state [/etc/default/kube-proxy] at time 16:17:10.473722
2019-08-28 16:17:10,474 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/etc/default/kube-proxy]
2019-08-28 16:17:10,477 [salt.state       :300 ][INFO    ][10012] File /etc/default/kube-proxy is in the correct state
2019-08-28 16:17:10,477 [salt.state       :1951][INFO    ][10012] Completed state [/etc/default/kube-proxy] at time 16:17:10.477761 duration_in_ms=4.039
2019-08-28 16:17:10,481 [salt.state       :1780][INFO    ][10012] Running state [kube-proxy] at time 16:17:10.481041
2019-08-28 16:17:10,481 [salt.state       :1813][INFO    ][10012] Executing state service.running for [kube-proxy]
2019-08-28 16:17:10,484 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'status', 'kube-proxy.service', '-n', '0'] in directory '/root'
2019-08-28 16:17:10,511 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-08-28 16:17:10,527 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-08-28 16:17:10,544 [salt.state       :300 ][INFO    ][10012] The service kube-proxy is already running
2019-08-28 16:17:10,546 [salt.state       :1951][INFO    ][10012] Completed state [kube-proxy] at time 16:17:10.545538 duration_in_ms=64.496
2019-08-28 16:17:10,550 [salt.state       :1780][INFO    ][10012] Running state [/srv/kubernetes] at time 16:17:10.550334
2019-08-28 16:17:10,550 [salt.state       :1813][INFO    ][10012] Executing state file.directory for [/srv/kubernetes]
2019-08-28 16:17:10,551 [salt.state       :300 ][INFO    ][10012] Directory /srv/kubernetes is in the correct state
Directory /srv/kubernetes updated
2019-08-28 16:17:10,552 [salt.state       :1951][INFO    ][10012] Completed state [/srv/kubernetes] at time 16:17:10.551968 duration_in_ms=1.634
2019-08-28 16:17:10,552 [salt.state       :1780][INFO    ][10012] Running state [/srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml] at time 16:17:10.552842
2019-08-28 16:17:10,553 [salt.state       :1813][INFO    ][10012] Executing state file.managed for [/srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml]
2019-08-28 16:17:10,580 [salt.fileclient  :1219][INFO    ][10012] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/rolebinding.yml'
2019-08-28 16:17:10,597 [salt.state       :300 ][INFO    ][10012] File changed:
New file
2019-08-28 16:17:10,597 [salt.state       :1951][INFO    ][10012] Completed state [/srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml] at time 16:17:10.597540 duration_in_ms=44.697
2019-08-28 16:17:10,598 [salt.state       :1780][INFO    ][10012] Running state [kubectl apply -f /srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml] at time 16:17:10.598926
2019-08-28 16:17:10,599 [salt.state       :1813][INFO    ][10012] Executing state cmd.run for [kubectl apply -f /srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml]
2019-08-28 16:17:10,599 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10012] Executing command 'kubectl apply -f /srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml' in directory '/root'
2019-08-28 16:17:10,914 [salt.state       :300 ][INFO    ][10012] {'pid': 12518, 'retcode': 0, 'stderr': '', 'stdout': 'clusterrolebinding.rbac.authorization.k8s.io/root-cluster-admin-binding created'}
2019-08-28 16:17:10,914 [salt.state       :1951][INFO    ][10012] Completed state [kubectl apply -f /srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml] at time 16:17:10.914633 duration_in_ms=315.706
2019-08-28 16:17:10,918 [salt.minion      :1711][INFO    ][10012] Returning information for job: 20190828161630039595
2019-08-28 16:17:11,589 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command state.sls with jid 20190828161711578885
2019-08-28 16:17:11,609 [salt.minion      :1432][INFO    ][12543] Starting a new job with PID 12543
2019-08-28 16:17:17,898 [salt.state       :915 ][INFO    ][12543] Loading fresh modules for state activity
2019-08-28 16:17:18,046 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12543] 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-08-28 16:17:18,188 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12543] 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-08-28 16:17:18,335 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12543] 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-08-28 16:17:18,469 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12543] 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-08-28 16:17:18,629 [salt.state       :1780][INFO    ][12543] Running state [/etc/kubernetes/kubeconfig.sh] at time 16:17:18.629683
2019-08-28 16:17:18,630 [salt.state       :1813][INFO    ][12543] Executing state file.managed for [/etc/kubernetes/kubeconfig.sh]
2019-08-28 16:17:18,662 [salt.fileclient  :1219][INFO    ][12543] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubeconfig.sh'
2019-08-28 16:17:18,733 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12543] 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-08-28 16:17:18,902 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12543] 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-08-28 16:17:19,070 [salt.state       :300 ][INFO    ][12543] File changed:
New file
2019-08-28 16:17:19,070 [salt.state       :1951][INFO    ][12543] Completed state [/etc/kubernetes/kubeconfig.sh] at time 16:17:19.070610 duration_in_ms=440.926
2019-08-28 16:17:19,073 [salt.state       :1780][INFO    ][12543] Running state [/etc/kubernetes/kubeconfig.sh > /etc/kubernetes/admin-kube-config] at time 16:17:19.073100
2019-08-28 16:17:19,073 [salt.state       :1813][INFO    ][12543] Executing state cmd.run for [/etc/kubernetes/kubeconfig.sh > /etc/kubernetes/admin-kube-config]
2019-08-28 16:17:19,074 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12543] Executing command '/etc/kubernetes/kubeconfig.sh > /etc/kubernetes/admin-kube-config' in directory '/root'
2019-08-28 16:17:19,125 [salt.state       :300 ][INFO    ][12543] {'pid': 12682, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-28 16:17:19,126 [salt.state       :1951][INFO    ][12543] Completed state [/etc/kubernetes/kubeconfig.sh > /etc/kubernetes/admin-kube-config] at time 16:17:19.126612 duration_in_ms=53.511
2019-08-28 16:17:19,127 [salt.state       :1780][INFO    ][12543] Running state [/etc/kubernetes/addons/namespace.yaml] at time 16:17:19.127271
2019-08-28 16:17:19,127 [salt.state       :1813][INFO    ][12543] Executing state file.managed for [/etc/kubernetes/addons/namespace.yaml]
2019-08-28 16:17:19,155 [salt.fileclient  :1219][INFO    ][12543] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addon-manager/namespace.yaml'
2019-08-28 16:17:19,157 [salt.state       :300 ][INFO    ][12543] File changed:
New file
2019-08-28 16:17:19,157 [salt.state       :1951][INFO    ][12543] Completed state [/etc/kubernetes/addons/namespace.yaml] at time 16:17:19.157881 duration_in_ms=30.611
2019-08-28 16:17:19,158 [salt.state       :1780][INFO    ][12543] Running state [/etc/kubernetes/manifests/kube-addon-manager.yml] at time 16:17:19.158245
2019-08-28 16:17:19,158 [salt.state       :1813][INFO    ][12543] Executing state file.managed for [/etc/kubernetes/manifests/kube-addon-manager.yml]
2019-08-28 16:17:19,187 [salt.fileclient  :1219][INFO    ][12543] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/manifest/kube-addon-manager.yml'
2019-08-28 16:17:19,239 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12543] 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-08-28 16:17:19,381 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12543] 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-08-28 16:17:19,579 [salt.state       :300 ][INFO    ][12543] File changed:
New file
2019-08-28 16:17:19,580 [salt.state       :1951][INFO    ][12543] Completed state [/etc/kubernetes/manifests/kube-addon-manager.yml] at time 16:17:19.580111 duration_in_ms=421.865
2019-08-28 16:17:19,580 [salt.state       :1780][INFO    ][12543] Running state [/etc/default/kube-addon-manager] at time 16:17:19.580623
2019-08-28 16:17:19,581 [salt.state       :1813][INFO    ][12543] Executing state file.absent for [/etc/default/kube-addon-manager]
2019-08-28 16:17:19,581 [salt.state       :300 ][INFO    ][12543] File /etc/default/kube-addon-manager is not present
2019-08-28 16:17:19,582 [salt.state       :1951][INFO    ][12543] Completed state [/etc/default/kube-addon-manager] at time 16:17:19.582016 duration_in_ms=1.393
2019-08-28 16:17:19,582 [salt.state       :1780][INFO    ][12543] Running state [/usr/bin/kube-addons.sh] at time 16:17:19.582383
2019-08-28 16:17:19,582 [salt.state       :1813][INFO    ][12543] Executing state file.absent for [/usr/bin/kube-addons.sh]
2019-08-28 16:17:19,583 [salt.state       :300 ][INFO    ][12543] File /usr/bin/kube-addons.sh is not present
2019-08-28 16:17:19,583 [salt.state       :1951][INFO    ][12543] Completed state [/usr/bin/kube-addons.sh] at time 16:17:19.583622 duration_in_ms=1.24
2019-08-28 16:17:20,011 [salt.state       :1780][INFO    ][12543] Running state [kube-addon-manager] at time 16:17:20.011490
2019-08-28 16:17:20,011 [salt.state       :1813][INFO    ][12543] Executing state service.dead for [kube-addon-manager]
2019-08-28 16:17:20,012 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12543] Executing command ['systemctl', 'status', 'kube-addon-manager.service', '-n', '0'] in directory '/root'
2019-08-28 16:17:20,046 [salt.state       :300 ][INFO    ][12543] The named service kube-addon-manager is not available
2019-08-28 16:17:20,046 [salt.state       :1951][INFO    ][12543] Completed state [kube-addon-manager] at time 16:17:20.046826 duration_in_ms=35.336
2019-08-28 16:17:20,047 [salt.state       :1780][INFO    ][12543] Running state [/etc/systemd/system/kube-addon-manager.service] at time 16:17:20.047333
2019-08-28 16:17:20,047 [salt.state       :1813][INFO    ][12543] Executing state file.absent for [/etc/systemd/system/kube-addon-manager.service]
2019-08-28 16:17:20,048 [salt.state       :300 ][INFO    ][12543] File /etc/systemd/system/kube-addon-manager.service is not present
2019-08-28 16:17:20,048 [salt.state       :1951][INFO    ][12543] Completed state [/etc/systemd/system/kube-addon-manager.service] at time 16:17:20.048709 duration_in_ms=1.376
2019-08-28 16:17:20,049 [salt.state       :1780][INFO    ][12543] Running state [/srv/kubernetes/conformance.yml] at time 16:17:20.049039
2019-08-28 16:17:20,049 [salt.state       :1813][INFO    ][12543] Executing state file.managed for [/srv/kubernetes/conformance.yml]
2019-08-28 16:17:20,073 [salt.fileclient  :1219][INFO    ][12543] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/conformance/conformance.yml'
2019-08-28 16:17:20,124 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12543] 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-08-28 16:17:20,277 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12543] 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-08-28 16:17:20,416 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12543] 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-08-28 16:17:20,564 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12543] 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-08-28 16:17:20,725 [salt.state       :300 ][INFO    ][12543] File changed:
New file
2019-08-28 16:17:20,726 [salt.state       :1951][INFO    ][12543] Completed state [/srv/kubernetes/conformance.yml] at time 16:17:20.726070 duration_in_ms=677.031
2019-08-28 16:17:20,727 [salt.minion      :1711][INFO    ][12543] Returning information for job: 20190828161711578885
2019-08-28 16:17:21,278 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command cp.push with jid 20190828161721272209
2019-08-28 16:17:21,292 [salt.minion      :1432][INFO    ][12863] Starting a new job with PID 12863
2019-08-28 16:17:21,317 [salt.minion      :1711][INFO    ][12863] Returning information for job: 20190828161721272209
2019-08-28 16:17:22,073 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command cp.push_dir with jid 20190828161722060706
2019-08-28 16:17:22,092 [salt.minion      :1432][INFO    ][12870] Starting a new job with PID 12870
2019-08-28 16:17:22,951 [salt.minion      :1711][INFO    ][12870] Returning information for job: 20190828161722060706
2019-08-28 16:20:18,287 [salt.minion      :1308][INFO    ][4492] User sudo_ubuntu Executing command cp.push_dir with jid 20190828162018279276
2019-08-28 16:20:18,305 [salt.minion      :1432][INFO    ][14872] Starting a new job with PID 14872
