2019-10-26 13:50:21,172 [salt.utils       :831 ][ERROR   ][1705] DNS lookup or connection check of 'salt' failed.
2019-10-26 13:50:21,172 [salt.minion      :152 ][ERROR   ][1705] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-10-26 13:50:23,827 [salt.utils.parsers:1051][WARNING ][1705] Minion received a SIGTERM. Exiting.
2019-10-26 13:55:57,620 [salt.utils.decorators:613 ][WARNING ][2126] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-26 13:56:14,138 [salt.loaded.int.states.file:2298][WARNING ][2126] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:25,295 [salt.utils.decorators:613 ][WARNING ][2126] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-26 13:56:25,399 [salt.loaded.int.states.file:2298][WARNING ][2126] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:25,401 [salt.loaded.int.states.file:2298][WARNING ][2126] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:25,402 [salt.loaded.int.states.file:2298][WARNING ][2126] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:25,404 [salt.loaded.int.states.file:2298][WARNING ][2126] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:25,405 [salt.loaded.int.states.file:2298][WARNING ][2126] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:25,407 [salt.loaded.int.states.file:2298][WARNING ][2126] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:25,409 [salt.loaded.int.states.file:2298][WARNING ][2126] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:25,410 [salt.loaded.int.states.file:2298][WARNING ][2126] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:56:34,859 [salt.loaded.int.module.debian_ip:1964][WARNING ][2126] The network state sls is requiring a reboot of the system to properly apply network configuration.
2019-10-26 13:56:36,254 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][2126] Command '['umount', '/dev/shm']' failed with return code: 32
2019-10-26 13:56:36,255 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][2126] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-10-26 13:56:36,255 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][2126] retcode: 32
2019-10-26 13:56:37,760 [salt.utils.parsers:1051][WARNING ][1910] Minion received a SIGTERM. Exiting.
2019-10-26 13:57:50,452 [salt.utils.decorators:613 ][WARNING ][2733] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-26 13:57:52,640 [salt.loaded.int.states.file:2298][WARNING ][2733] State for file: /etc/kubernetes/ssl/ca-kubernetes.crt - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:57:55,171 [salt.loaded.int.states.file:2298][WARNING ][2733] State for file: /var/lib/etcd/ca.pem - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-10-26 13:58:12,938 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4476] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-10-26 13:58:12,971 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4476] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-10-26 13:58:12,991 [salt.utils.parsers:1051][WARNING ][2548] Minion received a SIGTERM. Exiting.
2019-10-26 13:58:13,986 [salt.cli.daemons :293 ][INFO    ][4529] Setting up the Salt Minion "ctl01.mcp-k8s-calico-noha.local"
2019-10-26 13:58:14,104 [salt.cli.daemons :82  ][INFO    ][4529] Starting up the Salt Minion
2019-10-26 13:58:14,105 [salt.utils.event :1017][INFO    ][4529] Starting pull socket on /var/run/salt/minion/minion_event_1899fe9592_pull.ipc
2019-10-26 13:58:14,930 [salt.minion      :976 ][INFO    ][4529] Creating minion process manager
2019-10-26 13:58:16,366 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][4529] Executing command ['date', '+%z'] in directory '/root'
2019-10-26 13:58:16,392 [salt.utils.schedule:568 ][INFO    ][4529] Updating job settings for scheduled job: __mine_interval
2019-10-26 13:58:16,394 [salt.minion      :1108][INFO    ][4529] Added mine.update to scheduler
2019-10-26 13:58:16,399 [salt.minion      :1975][INFO    ][4529] Minion is starting as user 'root'
2019-10-26 13:58:16,414 [salt.minion      :2336][INFO    ][4529] Minion is ready to receive requests!
2019-10-26 13:58:25,801 [salt.minion      :1308][INFO    ][4529] User sudo_ubuntu Executing command state.sls with jid 20191026135825793262
2019-10-26 13:58:25,817 [salt.minion      :1432][INFO    ][4617] Starting a new job with PID 4617
2019-10-26 13:58:26,924 [salt.state       :915 ][INFO    ][4617] Loading fresh modules for state activity
2019-10-26 13:58:26,975 [salt.fileclient  :1219][INFO    ][4617] Fetching file from saltenv 'base', ** done ** 'etcd/server/service.sls'
2019-10-26 13:58:27,030 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] Executing command '. /var/lib/etcd/configenv; etcdctl cluster-health > /dev/null 2>&1; echo $?' in directory '/root'
2019-10-26 13:58:27,045 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4617] Command '. /var/lib/etcd/configenv; etcdctl cluster-health > /dev/null 2>&1; echo $?' failed with return code: 2
2019-10-26 13:58:27,045 [salt.loaded.int.module.cmdmod:732 ][ERROR   ][4617] stdout: /bin/sh: 1: .: Can't open /var/lib/etcd/configenv
2019-10-26 13:58:27,045 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4617] retcode: 2
2019-10-26 13:58:27,742 [salt.state       :1780][INFO    ][4617] Running state [etcd_support_packages] at time 13:58:27.742575
2019-10-26 13:58:27,743 [salt.state       :1813][INFO    ][4617] Executing state pkg.installed for [etcd_support_packages]
2019-10-26 13:58:27,743 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-26 13:58:28,215 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] Executing command ['apt-cache', '-q', 'policy', 'python-etcd'] in directory '/root'
2019-10-26 13:58:28,309 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-10-26 13:58:30,843 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-10-26 13:58:30,871 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] 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-10-26 13:58:32,908 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-26 13:58:32,950 [salt.state       :300 ][INFO    ][4617] Made the following changes:
'python-etcd' changed from 'absent' to '0.4.2-2'

2019-10-26 13:58:32,979 [salt.state       :915 ][INFO    ][4617] Loading fresh modules for state activity
2019-10-26 13:58:33,063 [salt.state       :1951][INFO    ][4617] Completed state [etcd_support_packages] at time 13:58:33.063681 duration_in_ms=5321.106
2019-10-26 13:58:33,068 [salt.state       :1780][INFO    ][4617] Running state [/tmp/etcd/bin] at time 13:58:33.068757
2019-10-26 13:58:33,069 [salt.state       :1813][INFO    ][4617] Executing state archive.extracted for [/tmp/etcd/bin]
2019-10-26 13:58:35,803 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] 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-10-26 13:58:36,160 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] Executing command ['tar', '--version'] in directory '/root'
2019-10-26 13:58:36,178 [salt.state       :300 ][INFO    ][4617] {'extracted_files': 'no tar output so far', 'directories_created': ['/tmp/etcd/bin/']}
2019-10-26 13:58:36,178 [salt.state       :1951][INFO    ][4617] Completed state [/tmp/etcd/bin] at time 13:58:36.178604 duration_in_ms=3109.842
2019-10-26 13:58:36,179 [salt.state       :1780][INFO    ][4617] Running state [/usr/local/bin/etcd] at time 13:58:36.179614
2019-10-26 13:58:36,180 [salt.state       :1813][INFO    ][4617] Executing state file.managed for [/usr/local/bin/etcd]
2019-10-26 13:58:36,516 [salt.state       :300 ][INFO    ][4617] File changed:
New file
2019-10-26 13:58:36,516 [salt.state       :1951][INFO    ][4617] Completed state [/usr/local/bin/etcd] at time 13:58:36.516434 duration_in_ms=336.82
2019-10-26 13:58:36,516 [salt.state       :1780][INFO    ][4617] Running state [/usr/local/bin/etcdctl] at time 13:58:36.516805
2019-10-26 13:58:36,517 [salt.state       :1813][INFO    ][4617] Executing state file.managed for [/usr/local/bin/etcdctl]
2019-10-26 13:58:36,679 [salt.state       :300 ][INFO    ][4617] File changed:
New file
2019-10-26 13:58:36,679 [salt.state       :1951][INFO    ][4617] Completed state [/usr/local/bin/etcdctl] at time 13:58:36.679338 duration_in_ms=162.532
2019-10-26 13:58:36,681 [salt.state       :1780][INFO    ][4617] Running state [etcd] at time 13:58:36.681479
2019-10-26 13:58:36,682 [salt.state       :1813][INFO    ][4617] Executing state user.present for [etcd]
2019-10-26 13:58:36,692 [salt.state       :300 ][INFO    ][4617] User etcd is present and up to date
2019-10-26 13:58:36,692 [salt.state       :1951][INFO    ][4617] Completed state [etcd] at time 13:58:36.692581 duration_in_ms=11.101
2019-10-26 13:58:36,692 [salt.state       :1780][INFO    ][4617] Running state [/etc/systemd/system/etcd.service] at time 13:58:36.692796
2019-10-26 13:58:36,693 [salt.state       :1813][INFO    ][4617] Executing state file.managed for [/etc/systemd/system/etcd.service]
2019-10-26 13:58:36,714 [salt.fileclient  :1219][INFO    ][4617] Fetching file from saltenv 'base', ** done ** 'etcd/files/systemd/etcd.service'
2019-10-26 13:58:36,725 [salt.state       :300 ][INFO    ][4617] File changed:
New file
2019-10-26 13:58:36,725 [salt.state       :1951][INFO    ][4617] Completed state [/etc/systemd/system/etcd.service] at time 13:58:36.725534 duration_in_ms=32.737
2019-10-26 13:58:36,725 [salt.state       :1780][INFO    ][4617] Running state [/etc/default/etcd] at time 13:58:36.725782
2019-10-26 13:58:36,726 [salt.state       :1813][INFO    ][4617] Executing state file.managed for [/etc/default/etcd]
2019-10-26 13:58:36,747 [salt.fileclient  :1219][INFO    ][4617] Fetching file from saltenv 'base', ** done ** 'etcd/files/default'
2019-10-26 13:58:36,813 [salt.state       :300 ][INFO    ][4617] File changed:
New file
2019-10-26 13:58:36,813 [salt.state       :1951][INFO    ][4617] Completed state [/etc/default/etcd] at time 13:58:36.813838 duration_in_ms=88.055
2019-10-26 13:58:36,814 [salt.state       :1780][INFO    ][4617] Running state [/var/lib/etcd/] at time 13:58:36.814063
2019-10-26 13:58:36,814 [salt.state       :1813][INFO    ][4617] Executing state file.directory for [/var/lib/etcd/]
2019-10-26 13:58:36,818 [salt.state       :300 ][INFO    ][4617] Directory /var/lib/etcd is in the correct state
Directory /var/lib/etcd updated
2019-10-26 13:58:36,818 [salt.state       :1951][INFO    ][4617] Completed state [/var/lib/etcd/] at time 13:58:36.818833 duration_in_ms=4.77
2019-10-26 13:58:36,819 [salt.state       :1780][INFO    ][4617] Running state [/var/lib/etcd/configenv] at time 13:58:36.819365
2019-10-26 13:58:36,819 [salt.state       :1813][INFO    ][4617] Executing state file.managed for [/var/lib/etcd/configenv]
2019-10-26 13:58:36,841 [salt.fileclient  :1219][INFO    ][4617] Fetching file from saltenv 'base', ** done ** 'etcd/files/configenv'
2019-10-26 13:58:36,870 [salt.state       :300 ][INFO    ][4617] File changed:
New file
2019-10-26 13:58:36,870 [salt.state       :1951][INFO    ][4617] Completed state [/var/lib/etcd/configenv] at time 13:58:36.870852 duration_in_ms=51.486
2019-10-26 13:58:36,871 [salt.state       :1780][INFO    ][4617] Running state [/var/lib/etcd/configenvv3] at time 13:58:36.871209
2019-10-26 13:58:36,871 [salt.state       :1813][INFO    ][4617] Executing state file.managed for [/var/lib/etcd/configenvv3]
2019-10-26 13:58:36,891 [salt.fileclient  :1219][INFO    ][4617] Fetching file from saltenv 'base', ** done ** 'etcd/files/configenvv3'
2019-10-26 13:58:36,922 [salt.state       :300 ][INFO    ][4617] File changed:
New file
2019-10-26 13:58:36,922 [salt.state       :1951][INFO    ][4617] Completed state [/var/lib/etcd/configenvv3] at time 13:58:36.922805 duration_in_ms=51.596
2019-10-26 13:58:37,403 [salt.state       :1780][INFO    ][4617] Running state [etcd] at time 13:58:37.403510
2019-10-26 13:58:37,403 [salt.state       :1813][INFO    ][4617] Executing state service.running for [etcd]
2019-10-26 13:58:37,404 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] Executing command ['systemctl', 'status', 'etcd.service', '-n', '0'] in directory '/root'
2019-10-26 13:58:37,431 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] Executing command ['systemctl', 'is-active', 'etcd.service'] in directory '/root'
2019-10-26 13:58:37,449 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] Executing command ['systemctl', 'is-enabled', 'etcd.service'] in directory '/root'
2019-10-26 13:58:37,480 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'etcd.service'] in directory '/root'
2019-10-26 13:58:40,879 [salt.minion      :1308][INFO    ][4529] User sudo_ubuntu Executing command saltutil.find_job with jid 20191026135840864291
2019-10-26 13:58:40,901 [salt.minion      :1432][INFO    ][5233] Starting a new job with PID 5233
2019-10-26 13:58:40,920 [salt.minion      :1711][INFO    ][5233] Returning information for job: 20191026135840864291
2019-10-26 13:58:41,038 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] Executing command ['systemctl', 'is-active', 'etcd.service'] in directory '/root'
2019-10-26 13:58:41,054 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] Executing command ['systemctl', 'is-enabled', 'etcd.service'] in directory '/root'
2019-10-26 13:58:41,074 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] Executing command ['systemctl', 'is-enabled', 'etcd.service'] in directory '/root'
2019-10-26 13:58:41,103 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'etcd.service'] in directory '/root'
2019-10-26 13:58:41,243 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] Executing command ['systemctl', 'is-enabled', 'etcd.service'] in directory '/root'
2019-10-26 13:58:41,265 [salt.state       :300 ][INFO    ][4617] {'etcd': True}
2019-10-26 13:58:41,265 [salt.state       :1951][INFO    ][4617] Completed state [etcd] at time 13:58:41.265768 duration_in_ms=3862.257
2019-10-26 13:58:41,268 [salt.minion      :1711][INFO    ][4617] Returning information for job: 20191026135825793262
2019-10-26 13:58:41,947 [salt.minion      :1308][INFO    ][4529] User sudo_ubuntu Executing command cmd.run with jid 20191026135841937351
2019-10-26 13:58:41,970 [salt.minion      :1432][INFO    ][5285] Starting a new job with PID 5285
2019-10-26 13:58:41,979 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][5285] Executing command '. /var/lib/etcd/configenv && etcdctl cluster-health' in directory '/root'
2019-10-26 13:58:42,122 [salt.minion      :1711][INFO    ][5285] Returning information for job: 20191026135841937351
2019-10-26 13:58:42,753 [salt.minion      :1308][INFO    ][4529] User sudo_ubuntu Executing command state.sls with jid 20191026135842743315
2019-10-26 13:58:42,774 [salt.minion      :1432][INFO    ][5299] Starting a new job with PID 5299
2019-10-26 13:58:49,286 [salt.state       :915 ][INFO    ][5299] Loading fresh modules for state activity
2019-10-26 13:58:49,336 [salt.fileclient  :1219][INFO    ][5299] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/kube-addons.sls'
2019-10-26 13:58:49,466 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:49,486 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:49,513 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:49,531 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:49,552 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:49,569 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:49,641 [salt.state       :1780][INFO    ][5299] Running state [/etc/kubernetes/addons] at time 13:58:49.641855
2019-10-26 13:58:49,642 [salt.state       :1813][INFO    ][5299] Executing state file.directory for [/etc/kubernetes/addons]
2019-10-26 13:58:49,648 [salt.state       :300 ][INFO    ][5299] {'/etc/kubernetes/addons': 'New Dir'}
2019-10-26 13:58:49,648 [salt.state       :1951][INFO    ][5299] Completed state [/etc/kubernetes/addons] at time 13:58:49.648527 duration_in_ms=6.673
2019-10-26 13:58:49,648 [salt.state       :1780][INFO    ][5299] Running state [/etc/kubernetes/addons/calico/calico-kube-controllers.yml] at time 13:58:49.648770
2019-10-26 13:58:49,649 [salt.state       :1813][INFO    ][5299] Executing state file.managed for [/etc/kubernetes/addons/calico/calico-kube-controllers.yml]
2019-10-26 13:58:49,678 [salt.fileclient  :1219][INFO    ][5299] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/calico/calico-kube-controllers.yml'
2019-10-26 13:58:49,736 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:49,756 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:49,783 [salt.state       :300 ][INFO    ][5299] File changed:
New file
2019-10-26 13:58:49,783 [salt.state       :1951][INFO    ][5299] Completed state [/etc/kubernetes/addons/calico/calico-kube-controllers.yml] at time 13:58:49.783299 duration_in_ms=134.529
2019-10-26 13:58:49,783 [salt.state       :1780][INFO    ][5299] Running state [/etc/kubernetes/addons/calico/calico-rbac.yml] at time 13:58:49.783579
2019-10-26 13:58:49,783 [salt.state       :1813][INFO    ][5299] Executing state file.managed for [/etc/kubernetes/addons/calico/calico-rbac.yml]
2019-10-26 13:58:49,807 [salt.fileclient  :1219][INFO    ][5299] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/calico/calico-rbac.yml'
2019-10-26 13:58:49,838 [salt.state       :300 ][INFO    ][5299] File changed:
New file
2019-10-26 13:58:49,839 [salt.state       :1951][INFO    ][5299] Completed state [/etc/kubernetes/addons/calico/calico-rbac.yml] at time 13:58:49.839290 duration_in_ms=55.71
2019-10-26 13:58:49,839 [salt.state       :1780][INFO    ][5299] Running state [/etc/kubernetes/addons/netchecker/netchecker-svc.yml] at time 13:58:49.839719
2019-10-26 13:58:49,840 [salt.state       :1813][INFO    ][5299] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-svc.yml]
2019-10-26 13:58:49,863 [salt.fileclient  :1219][INFO    ][5299] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-svc.yml'
2019-10-26 13:58:49,915 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:49,934 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:49,960 [salt.state       :300 ][INFO    ][5299] File changed:
New file
2019-10-26 13:58:49,961 [salt.state       :1951][INFO    ][5299] Completed state [/etc/kubernetes/addons/netchecker/netchecker-svc.yml] at time 13:58:49.960863 duration_in_ms=121.142
2019-10-26 13:58:49,961 [salt.state       :1780][INFO    ][5299] Running state [/etc/kubernetes/addons/netchecker/netchecker-server.yml] at time 13:58:49.961604
2019-10-26 13:58:49,961 [salt.state       :1813][INFO    ][5299] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-server.yml]
2019-10-26 13:58:49,986 [salt.fileclient  :1219][INFO    ][5299] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-server.yml'
2019-10-26 13:58:50,044 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:50,060 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:50,080 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:50,100 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:50,121 [salt.state       :300 ][INFO    ][5299] File changed:
New file
2019-10-26 13:58:50,122 [salt.state       :1951][INFO    ][5299] Completed state [/etc/kubernetes/addons/netchecker/netchecker-server.yml] at time 13:58:50.121945 duration_in_ms=160.34
2019-10-26 13:58:50,122 [salt.state       :1780][INFO    ][5299] Running state [/etc/kubernetes/addons/netchecker/netchecker-agent.yml] at time 13:58:50.122465
2019-10-26 13:58:50,122 [salt.state       :1813][INFO    ][5299] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-agent.yml]
2019-10-26 13:58:50,146 [salt.fileclient  :1219][INFO    ][5299] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-agent.yml'
2019-10-26 13:58:50,198 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:50,217 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:50,240 [salt.state       :300 ][INFO    ][5299] File changed:
New file
2019-10-26 13:58:50,241 [salt.state       :1951][INFO    ][5299] Completed state [/etc/kubernetes/addons/netchecker/netchecker-agent.yml] at time 13:58:50.241246 duration_in_ms=118.78
2019-10-26 13:58:50,241 [salt.state       :1780][INFO    ][5299] Running state [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml] at time 13:58:50.241748
2019-10-26 13:58:50,242 [salt.state       :1813][INFO    ][5299] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml]
2019-10-26 13:58:50,265 [salt.fileclient  :1219][INFO    ][5299] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-serviceaccount.yml'
2019-10-26 13:58:50,276 [salt.state       :300 ][INFO    ][5299] File changed:
New file
2019-10-26 13:58:50,276 [salt.state       :1951][INFO    ][5299] Completed state [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml] at time 13:58:50.276218 duration_in_ms=34.47
2019-10-26 13:58:50,276 [salt.state       :1780][INFO    ][5299] Running state [/etc/kubernetes/addons/netchecker/netchecker-roles.yml] at time 13:58:50.276449
2019-10-26 13:58:50,276 [salt.state       :1813][INFO    ][5299] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-roles.yml]
2019-10-26 13:58:50,297 [salt.fileclient  :1219][INFO    ][5299] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-roles.yml'
2019-10-26 13:58:50,308 [salt.state       :300 ][INFO    ][5299] File changed:
New file
2019-10-26 13:58:50,308 [salt.state       :1951][INFO    ][5299] Completed state [/etc/kubernetes/addons/netchecker/netchecker-roles.yml] at time 13:58:50.308642 duration_in_ms=32.192
2019-10-26 13:58:50,308 [salt.state       :1780][INFO    ][5299] Running state [/etc/kubernetes/addons/prometheus/prometheus-roles.yml] at time 13:58:50.308931
2019-10-26 13:58:50,309 [salt.state       :1813][INFO    ][5299] Executing state file.managed for [/etc/kubernetes/addons/prometheus/prometheus-roles.yml]
2019-10-26 13:58:50,329 [salt.fileclient  :1219][INFO    ][5299] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/prometheus/prometheus-roles.yml'
2019-10-26 13:58:50,339 [salt.state       :300 ][INFO    ][5299] File changed:
New file
2019-10-26 13:58:50,339 [salt.state       :1951][INFO    ][5299] Completed state [/etc/kubernetes/addons/prometheus/prometheus-roles.yml] at time 13:58:50.339536 duration_in_ms=30.604
2019-10-26 13:58:50,339 [salt.state       :1780][INFO    ][5299] Running state [/etc/kubernetes/addons/coredns] at time 13:58:50.339772
2019-10-26 13:58:50,340 [salt.state       :1813][INFO    ][5299] Executing state file.absent for [/etc/kubernetes/addons/coredns]
2019-10-26 13:58:50,340 [salt.state       :300 ][INFO    ][5299] File /etc/kubernetes/addons/coredns is not present
2019-10-26 13:58:50,340 [salt.state       :1951][INFO    ][5299] Completed state [/etc/kubernetes/addons/coredns] at time 13:58:50.340597 duration_in_ms=0.825
2019-10-26 13:58:50,341 [salt.state       :1780][INFO    ][5299] Running state [kubectl -n kube-system delete svc coredns > /dev/null || echo "coredns is absent. OK" && true] at time 13:58:50.341665
2019-10-26 13:58:50,341 [salt.state       :1813][INFO    ][5299] Executing state cmd.run for [kubectl -n kube-system delete svc coredns > /dev/null || echo "coredns is absent. OK" && true]
2019-10-26 13:58:50,342 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command 'kubectl -n kube-system delete svc coredns > /dev/null || echo "coredns is absent. OK" && true' in directory '/root'
2019-10-26 13:58:50,361 [salt.state       :300 ][INFO    ][5299] {'pid': 5380, 'retcode': 0, 'stderr': '/bin/sh: 1: kubectl: not found', 'stdout': 'coredns is absent. OK'}
2019-10-26 13:58:50,361 [salt.state       :1951][INFO    ][5299] Completed state [kubectl -n kube-system delete svc coredns > /dev/null || echo "coredns is absent. OK" && true] at time 13:58:50.361642 duration_in_ms=19.977
2019-10-26 13:58:50,362 [salt.state       :1780][INFO    ][5299] Running state [/etc/kubernetes/addons/dns/kubedns-svc.yaml] at time 13:58:50.362227
2019-10-26 13:58:50,362 [salt.state       :1813][INFO    ][5299] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-svc.yaml]
2019-10-26 13:58:50,388 [salt.fileclient  :1219][INFO    ][5299] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-svc.yaml'
2019-10-26 13:58:50,434 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:50,452 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:50,477 [salt.state       :300 ][INFO    ][5299] File changed:
New file
2019-10-26 13:58:50,478 [salt.state       :1951][INFO    ][5299] Completed state [/etc/kubernetes/addons/dns/kubedns-svc.yaml] at time 13:58:50.477959 duration_in_ms=115.73
2019-10-26 13:58:50,478 [salt.state       :1780][INFO    ][5299] Running state [/etc/kubernetes/addons/dns/kubedns-rc.yaml] at time 13:58:50.478530
2019-10-26 13:58:50,479 [salt.state       :1813][INFO    ][5299] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-rc.yaml]
2019-10-26 13:58:50,501 [salt.fileclient  :1219][INFO    ][5299] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-rc.yaml'
2019-10-26 13:58:50,567 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:50,583 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:50,609 [salt.state       :300 ][INFO    ][5299] File changed:
New file
2019-10-26 13:58:50,610 [salt.state       :1951][INFO    ][5299] Completed state [/etc/kubernetes/addons/dns/kubedns-rc.yaml] at time 13:58:50.610002 duration_in_ms=131.47
2019-10-26 13:58:50,610 [salt.state       :1780][INFO    ][5299] Running state [/etc/kubernetes/addons/dns/kubedns-sa.yaml] at time 13:58:50.610485
2019-10-26 13:58:50,610 [salt.state       :1813][INFO    ][5299] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-sa.yaml]
2019-10-26 13:58:50,634 [salt.fileclient  :1219][INFO    ][5299] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-sa.yaml'
2019-10-26 13:58:50,643 [salt.state       :300 ][INFO    ][5299] File changed:
New file
2019-10-26 13:58:50,644 [salt.state       :1951][INFO    ][5299] Completed state [/etc/kubernetes/addons/dns/kubedns-sa.yaml] at time 13:58:50.644194 duration_in_ms=33.708
2019-10-26 13:58:50,644 [salt.state       :1780][INFO    ][5299] Running state [/etc/kubernetes/addons/dns/kubedns-autoscaler.yaml] at time 13:58:50.644450
2019-10-26 13:58:50,644 [salt.state       :1813][INFO    ][5299] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-autoscaler.yaml]
2019-10-26 13:58:50,664 [salt.fileclient  :1219][INFO    ][5299] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-autoscaler.yaml'
2019-10-26 13:58:50,717 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:50,737 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:50,757 [salt.state       :300 ][INFO    ][5299] File changed:
New file
2019-10-26 13:58:50,758 [salt.state       :1951][INFO    ][5299] Completed state [/etc/kubernetes/addons/dns/kubedns-autoscaler.yaml] at time 13:58:50.758002 duration_in_ms=113.551
2019-10-26 13:58:50,758 [salt.state       :1780][INFO    ][5299] Running state [/etc/kubernetes/addons/dns/kubedns-autoscaler-rbac.yaml] at time 13:58:50.758438
2019-10-26 13:58:50,758 [salt.state       :1813][INFO    ][5299] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-autoscaler-rbac.yaml]
2019-10-26 13:58:50,780 [salt.fileclient  :1219][INFO    ][5299] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-autoscaler-rbac.yaml'
2019-10-26 13:58:50,790 [salt.state       :300 ][INFO    ][5299] File changed:
New file
2019-10-26 13:58:50,790 [salt.state       :1951][INFO    ][5299] Completed state [/etc/kubernetes/addons/dns/kubedns-autoscaler-rbac.yaml] at time 13:58:50.790659 duration_in_ms=32.221
2019-10-26 13:58:50,790 [salt.state       :1780][INFO    ][5299] Running state [/etc/kubernetes/addons/dns/kubedns-clusterrole.yaml] at time 13:58:50.790897
2019-10-26 13:58:50,791 [salt.state       :1813][INFO    ][5299] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-clusterrole.yaml]
2019-10-26 13:58:50,811 [salt.fileclient  :1219][INFO    ][5299] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-clusterrole.yaml'
2019-10-26 13:58:50,821 [salt.state       :300 ][INFO    ][5299] File changed:
New file
2019-10-26 13:58:50,822 [salt.state       :1951][INFO    ][5299] Completed state [/etc/kubernetes/addons/dns/kubedns-clusterrole.yaml] at time 13:58:50.822014 duration_in_ms=31.116
2019-10-26 13:58:50,822 [salt.state       :1780][INFO    ][5299] Running state [/etc/kubernetes/addons/metrics-server] at time 13:58:50.822243
2019-10-26 13:58:50,822 [salt.state       :1813][INFO    ][5299] Executing state file.absent for [/etc/kubernetes/addons/metrics-server]
2019-10-26 13:58:50,822 [salt.state       :300 ][INFO    ][5299] File /etc/kubernetes/addons/metrics-server is not present
2019-10-26 13:58:50,823 [salt.state       :1951][INFO    ][5299] Completed state [/etc/kubernetes/addons/metrics-server] at time 13:58:50.823010 duration_in_ms=0.767
2019-10-26 13:58:50,826 [salt.minion      :1711][INFO    ][5299] Returning information for job: 20191026135842743315
2019-10-26 13:58:51,455 [salt.minion      :1308][INFO    ][4529] User sudo_ubuntu Executing command state.sls with jid 20191026135851446315
2019-10-26 13:58:51,476 [salt.minion      :1432][INFO    ][5418] Starting a new job with PID 5418
2019-10-26 13:58:52,520 [salt.state       :915 ][INFO    ][5418] Loading fresh modules for state activity
2019-10-26 13:58:52,573 [salt.fileclient  :1219][INFO    ][5418] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/init.sls'
2019-10-26 13:58:52,637 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:52,655 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:52,674 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:52,686 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:52,720 [salt.fileclient  :1219][INFO    ][5418] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/calico.sls'
2019-10-26 13:58:52,786 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:52,802 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:52,817 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:52,832 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:52,892 [salt.fileclient  :1219][INFO    ][5418] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/service.sls'
2019-10-26 13:58:52,937 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:52,956 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:52,976 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:52,990 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:53,018 [salt.fileclient  :1219][INFO    ][5418] Fetching file from saltenv 'base', ** done ** 'kubernetes/_common.sls'
2019-10-26 13:58:53,104 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:53,119 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:53,183 [salt.fileclient  :1219][INFO    ][5418] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/kube-proxy.sls'
2019-10-26 13:58:53,258 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:53,277 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:53,318 [salt.state       :1780][INFO    ][5418] Running state [/usr/bin/calicoctl] at time 13:58:53.318196
2019-10-26 13:58:53,318 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/usr/bin/calicoctl]
2019-10-26 13:58:54,677 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:58:54,678 [salt.state       :1951][INFO    ][5418] Completed state [/usr/bin/calicoctl] at time 13:58:54.678578 duration_in_ms=1360.383
2019-10-26 13:58:54,679 [salt.state       :1780][INFO    ][5418] Running state [/usr/bin/birdcl] at time 13:58:54.679042
2019-10-26 13:58:54,679 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/usr/bin/birdcl]
2019-10-26 13:58:55,016 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:58:55,017 [salt.state       :1951][INFO    ][5418] Completed state [/usr/bin/birdcl] at time 13:58:55.017431 duration_in_ms=338.378
2019-10-26 13:58:55,018 [salt.state       :1780][INFO    ][5418] Running state [/opt/cni/bin/calico] at time 13:58:55.018147
2019-10-26 13:58:55,018 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/opt/cni/bin/calico]
2019-10-26 13:58:56,161 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:58:56,162 [salt.state       :1951][INFO    ][5418] Completed state [/opt/cni/bin/calico] at time 13:58:56.161941 duration_in_ms=1143.792
2019-10-26 13:58:56,162 [salt.state       :1780][INFO    ][5418] Running state [/opt/cni/bin/calico-ipam] at time 13:58:56.162353
2019-10-26 13:58:56,162 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/opt/cni/bin/calico-ipam]
2019-10-26 13:58:57,196 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:58:57,196 [salt.state       :1951][INFO    ][5418] Completed state [/opt/cni/bin/calico-ipam] at time 13:58:57.196769 duration_in_ms=1034.413
2019-10-26 13:58:57,197 [salt.state       :1780][INFO    ][5418] Running state [/etc/cni/net.d/10-calico.conf] at time 13:58:57.197103
2019-10-26 13:58:57,197 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/etc/cni/net.d/10-calico.conf]
2019-10-26 13:58:57,218 [salt.fileclient  :1219][INFO    ][5418] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calico.conf'
2019-10-26 13:58:57,272 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:57,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:57,303 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:57,317 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:57,339 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:58:57,339 [salt.state       :1951][INFO    ][5418] Completed state [/etc/cni/net.d/10-calico.conf] at time 13:58:57.339796 duration_in_ms=142.691
2019-10-26 13:58:57,340 [salt.state       :1780][INFO    ][5418] Running state [/etc/calico/network-environment] at time 13:58:57.340277
2019-10-26 13:58:57,340 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/etc/calico/network-environment]
2019-10-26 13:58:57,361 [salt.fileclient  :1219][INFO    ][5418] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/network-environment.pool'
2019-10-26 13:58:57,408 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:57,420 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:57,438 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:58:57,438 [salt.state       :1951][INFO    ][5418] Completed state [/etc/calico/network-environment] at time 13:58:57.438868 duration_in_ms=98.59
2019-10-26 13:58:57,439 [salt.state       :1780][INFO    ][5418] Running state [/etc/calico/calicoctl.cfg] at time 13:58:57.439230
2019-10-26 13:58:57,439 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/etc/calico/calicoctl.cfg]
2019-10-26 13:58:57,456 [salt.fileclient  :1219][INFO    ][5418] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calicoctl.cfg.pool'
2019-10-26 13:58:57,507 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:58:57,522 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:58:57,540 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:58:57,540 [salt.state       :1951][INFO    ][5418] Completed state [/etc/calico/calicoctl.cfg] at time 13:58:57.540732 duration_in_ms=101.5
2019-10-26 13:58:58,223 [salt.state       :1780][INFO    ][5418] Running state [containerd=1.2.5-3~u16.04+mcp] at time 13:58:58.223456
2019-10-26 13:58:58,223 [salt.state       :1813][INFO    ][5418] Executing state pkg.installed for [containerd=1.2.5-3~u16.04+mcp]
2019-10-26 13:58:58,224 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-26 13:58:58,666 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['apt-cache', '-q', 'policy', 'containerd=1.2.5-3~u16.04+mcp'] in directory '/root'
2019-10-26 13:58:58,812 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-10-26 13:59:01,330 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-10-26 13:59:01,353 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'containerd=1.2.5-3~u16.04+mcp'] in directory '/root'
2019-10-26 13:59:06,584 [salt.minion      :1308][INFO    ][4529] User sudo_ubuntu Executing command saltutil.find_job with jid 20191026135906571083
2019-10-26 13:59:06,604 [salt.minion      :1432][INFO    ][5985] Starting a new job with PID 5985
2019-10-26 13:59:06,622 [salt.minion      :1711][INFO    ][5985] Returning information for job: 20191026135906571083
2019-10-26 13:59:14,692 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-26 13:59:14,736 [salt.state       :300 ][INFO    ][5418] Made the following changes:
'containerd' changed from 'absent' to '1.2.5-3~u16.04+mcp'
'runc' changed from 'absent' to '1.0.0~rc7+git20190403.029124da-0ubuntu1~16.04.4'

2019-10-26 13:59:14,756 [salt.state       :915 ][INFO    ][5418] Loading fresh modules for state activity
2019-10-26 13:59:14,795 [salt.state       :1951][INFO    ][5418] Completed state [containerd=1.2.5-3~u16.04+mcp] at time 13:59:14.795018 duration_in_ms=16571.562
2019-10-26 13:59:14,798 [salt.state       :1780][INFO    ][5418] Running state [/etc/containerd/config.toml] at time 13:59:14.798851
2019-10-26 13:59:14,799 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/etc/containerd/config.toml]
2019-10-26 13:59:14,821 [salt.fileclient  :1219][INFO    ][5418] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/containerd/config.toml'
2019-10-26 13:59:14,866 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:14,882 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:14,905 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:59:14,905 [salt.state       :1951][INFO    ][5418] Completed state [/etc/containerd/config.toml] at time 13:59:14.905272 duration_in_ms=106.42
2019-10-26 13:59:15,369 [salt.state       :1780][INFO    ][5418] Running state [containerd] at time 13:59:15.369498
2019-10-26 13:59:15,369 [salt.state       :1813][INFO    ][5418] Executing state service.running for [containerd]
2019-10-26 13:59:15,370 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'status', 'containerd.service', '-n', '0'] in directory '/root'
2019-10-26 13:59:15,387 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-10-26 13:59:15,401 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-enabled', 'containerd.service'] in directory '/root'
2019-10-26 13:59:15,416 [salt.state       :300 ][INFO    ][5418] The service containerd is already running
2019-10-26 13:59:15,416 [salt.state       :1951][INFO    ][5418] Completed state [containerd] at time 13:59:15.416720 duration_in_ms=47.222
2019-10-26 13:59:15,417 [salt.state       :1780][INFO    ][5418] Running state [containerd] at time 13:59:15.417204
2019-10-26 13:59:15,417 [salt.state       :1813][INFO    ][5418] Executing state service.mod_watch for [containerd]
2019-10-26 13:59:15,419 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-10-26 13:59:15,434 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'containerd.service'] in directory '/root'
2019-10-26 13:59:15,464 [salt.state       :300 ][INFO    ][5418] {'containerd': True}
2019-10-26 13:59:15,465 [salt.state       :1951][INFO    ][5418] Completed state [containerd] at time 13:59:15.464910 duration_in_ms=47.704
2019-10-26 13:59:15,466 [salt.state       :1780][INFO    ][5418] Running state [/etc/systemd/system/calico-node.service] at time 13:59:15.466020
2019-10-26 13:59:15,466 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/etc/systemd/system/calico-node.service]
2019-10-26 13:59:15,483 [salt.fileclient  :1219][INFO    ][5418] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calico-node.service.ctr'
2019-10-26 13:59:15,509 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:59:15,509 [salt.state       :1951][INFO    ][5418] Completed state [/etc/systemd/system/calico-node.service] at time 13:59:15.509454 duration_in_ms=43.434
2019-10-26 13:59:15,509 [salt.state       :1780][INFO    ][5418] Running state [/var/lib/calico] at time 13:59:15.509752
2019-10-26 13:59:15,510 [salt.state       :1813][INFO    ][5418] Executing state file.directory for [/var/lib/calico]
2019-10-26 13:59:15,511 [salt.state       :300 ][INFO    ][5418] {'/var/lib/calico': 'New Dir'}
2019-10-26 13:59:15,512 [salt.state       :1951][INFO    ][5418] Completed state [/var/lib/calico] at time 13:59:15.512048 duration_in_ms=2.297
2019-10-26 13:59:15,512 [salt.state       :1780][INFO    ][5418] Running state [/var/log/calico] at time 13:59:15.512319
2019-10-26 13:59:15,512 [salt.state       :1813][INFO    ][5418] Executing state file.directory for [/var/log/calico]
2019-10-26 13:59:15,513 [salt.state       :300 ][INFO    ][5418] {'/var/log/calico': 'New Dir'}
2019-10-26 13:59:15,514 [salt.state       :1951][INFO    ][5418] Completed state [/var/log/calico] at time 13:59:15.514028 duration_in_ms=1.708
2019-10-26 13:59:15,516 [salt.state       :1780][INFO    ][5418] Running state [calico-node] at time 13:59:15.516232
2019-10-26 13:59:15,516 [salt.state       :1813][INFO    ][5418] Executing state service.running for [calico-node]
2019-10-26 13:59:15,517 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'status', 'calico-node.service', '-n', '0'] in directory '/root'
2019-10-26 13:59:15,534 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:15,548 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:15,569 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:19,958 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:19,974 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:19,994 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:20,017 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:20,152 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-10-26 13:59:20,169 [salt.state       :300 ][INFO    ][5418] {'calico-node': True}
2019-10-26 13:59:20,169 [salt.state       :1951][INFO    ][5418] Completed state [calico-node] at time 13:59:20.169390 duration_in_ms=4653.158
2019-10-26 13:59:20,171 [salt.state       :1780][INFO    ][5418] Running state [curl] at time 13:59:20.171315
2019-10-26 13:59:20,171 [salt.state       :1813][INFO    ][5418] Executing state pkg.installed for [curl]
2019-10-26 13:59:20,368 [salt.state       :300 ][INFO    ][5418] All specified packages are already installed
2019-10-26 13:59:20,368 [salt.state       :1951][INFO    ][5418] Completed state [curl] at time 13:59:20.368636 duration_in_ms=197.321
2019-10-26 13:59:20,369 [salt.state       :1780][INFO    ][5418] Running state [git] at time 13:59:20.368962
2019-10-26 13:59:20,369 [salt.state       :1813][INFO    ][5418] Executing state pkg.installed for [git]
2019-10-26 13:59:20,382 [salt.state       :300 ][INFO    ][5418] All specified packages are already installed
2019-10-26 13:59:20,382 [salt.state       :1951][INFO    ][5418] Completed state [git] at time 13:59:20.382917 duration_in_ms=13.954
2019-10-26 13:59:20,383 [salt.state       :1780][INFO    ][5418] Running state [apt-transport-https] at time 13:59:20.383180
2019-10-26 13:59:20,383 [salt.state       :1813][INFO    ][5418] Executing state pkg.installed for [apt-transport-https]
2019-10-26 13:59:20,392 [salt.state       :300 ][INFO    ][5418] All specified packages are already installed
2019-10-26 13:59:20,392 [salt.state       :1951][INFO    ][5418] Completed state [apt-transport-https] at time 13:59:20.392576 duration_in_ms=9.396
2019-10-26 13:59:20,392 [salt.state       :1780][INFO    ][5418] Running state [python-apt] at time 13:59:20.392818
2019-10-26 13:59:20,393 [salt.state       :1813][INFO    ][5418] Executing state pkg.installed for [python-apt]
2019-10-26 13:59:20,401 [salt.state       :300 ][INFO    ][5418] All specified packages are already installed
2019-10-26 13:59:20,402 [salt.state       :1951][INFO    ][5418] Completed state [python-apt] at time 13:59:20.402175 duration_in_ms=9.357
2019-10-26 13:59:20,402 [salt.state       :1780][INFO    ][5418] Running state [socat] at time 13:59:20.402422
2019-10-26 13:59:20,402 [salt.state       :1813][INFO    ][5418] Executing state pkg.installed for [socat]
2019-10-26 13:59:20,428 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-10-26 13:59:20,454 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'socat'] in directory '/root'
2019-10-26 13:59:22,822 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-26 13:59:22,857 [salt.state       :300 ][INFO    ][5418] Made the following changes:
'socat' changed from 'absent' to '1.7.3.1-1'

2019-10-26 13:59:22,877 [salt.state       :915 ][INFO    ][5418] Loading fresh modules for state activity
2019-10-26 13:59:22,922 [salt.state       :1951][INFO    ][5418] Completed state [socat] at time 13:59:22.922471 duration_in_ms=2520.047
2019-10-26 13:59:22,928 [salt.state       :1780][INFO    ][5418] Running state [openssl] at time 13:59:22.928467
2019-10-26 13:59:22,928 [salt.state       :1813][INFO    ][5418] Executing state pkg.installed for [openssl]
2019-10-26 13:59:23,650 [salt.state       :300 ][INFO    ][5418] All specified packages are already installed
2019-10-26 13:59:23,651 [salt.state       :1951][INFO    ][5418] Completed state [openssl] at time 13:59:23.650937 duration_in_ms=722.469
2019-10-26 13:59:23,651 [salt.state       :1780][INFO    ][5418] Running state [conntrack] at time 13:59:23.651233
2019-10-26 13:59:23,651 [salt.state       :1813][INFO    ][5418] Executing state pkg.installed for [conntrack]
2019-10-26 13:59:23,677 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-10-26 13:59:23,698 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'conntrack'] in directory '/root'
2019-10-26 13:59:25,925 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-26 13:59:25,978 [salt.state       :300 ][INFO    ][5418] Made the following changes:
'conntrack' changed from 'absent' to '1:1.4.3-3'

2019-10-26 13:59:25,993 [salt.state       :915 ][INFO    ][5418] Loading fresh modules for state activity
2019-10-26 13:59:26,032 [salt.state       :1951][INFO    ][5418] Completed state [conntrack] at time 13:59:26.032697 duration_in_ms=2381.463
2019-10-26 13:59:26,036 [salt.state       :1780][INFO    ][5418] Running state [/usr/bin/hyperkube] at time 13:59:26.036320
2019-10-26 13:59:26,036 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/usr/bin/hyperkube]
2019-10-26 13:59:36,609 [salt.minion      :1308][INFO    ][4529] User sudo_ubuntu Executing command saltutil.find_job with jid 20191026135936602751
2019-10-26 13:59:36,623 [salt.minion      :1432][INFO    ][7723] Starting a new job with PID 7723
2019-10-26 13:59:36,645 [salt.minion      :1711][INFO    ][7723] Returning information for job: 20191026135936602751
2019-10-26 13:59:37,058 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:59:37,058 [salt.state       :1951][INFO    ][5418] Completed state [/usr/bin/hyperkube] at time 13:59:37.058790 duration_in_ms=11022.467
2019-10-26 13:59:37,060 [salt.state       :1780][INFO    ][5418] Running state [/usr/bin/kubectl] at time 13:59:37.060389
2019-10-26 13:59:37,060 [salt.state       :1813][INFO    ][5418] Executing state file.symlink for [/usr/bin/kubectl]
2019-10-26 13:59:37,068 [salt.state       :300 ][INFO    ][5418] {'new': '/usr/bin/kubectl'}
2019-10-26 13:59:37,080 [salt.state       :915 ][INFO    ][5418] Loading fresh modules for state activity
2019-10-26 13:59:37,153 [salt.state       :1951][INFO    ][5418] Completed state [/usr/bin/kubectl] at time 13:59:37.153089 duration_in_ms=92.7
2019-10-26 13:59:37,157 [salt.state       :1780][INFO    ][5418] Running state [/tmp/crictl] at time 13:59:37.157569
2019-10-26 13:59:37,157 [salt.state       :1813][INFO    ][5418] Executing state archive.extracted for [/tmp/crictl]
2019-10-26 13:59:39,332 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['tar', 'xz', '-f', '/var/cache/salt/minion/extrn_files/base/github.com/kubernetes-sigs/cri-tools/releases/download/v1.12.0/crictl-v1.12.0-linux-amd64.tar.gz'] in directory '/tmp/crictl/'
2019-10-26 13:59:39,608 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['tar', '--version'] in directory '/root'
2019-10-26 13:59:39,623 [salt.state       :300 ][INFO    ][5418] {'extracted_files': 'no tar output so far', 'directories_created': ['/tmp/crictl/']}
2019-10-26 13:59:39,624 [salt.state       :1951][INFO    ][5418] Completed state [/tmp/crictl] at time 13:59:39.624177 duration_in_ms=2466.604
2019-10-26 13:59:39,625 [salt.state       :1780][INFO    ][5418] Running state [/usr/local/bin/crictl] at time 13:59:39.625688
2019-10-26 13:59:39,626 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/usr/local/bin/crictl]
2019-10-26 13:59:39,626 [salt.loaded.int.states.file:1019][WARNING ][5418] Use of argument owner found, "owner" is invalid, please use "user"
2019-10-26 13:59:39,960 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:59:39,960 [salt.state       :1951][INFO    ][5418] Completed state [/usr/local/bin/crictl] at time 13:59:39.960655 duration_in_ms=334.966
2019-10-26 13:59:39,961 [salt.state       :1780][INFO    ][5418] Running state [/etc/crictl.yaml] at time 13:59:39.960951
2019-10-26 13:59:39,961 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/etc/crictl.yaml]
2019-10-26 13:59:39,964 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:59:39,964 [salt.state       :1951][INFO    ][5418] Completed state [/etc/crictl.yaml] at time 13:59:39.964770 duration_in_ms=3.817
2019-10-26 13:59:39,965 [salt.state       :1780][INFO    ][5418] Running state [/etc/criproxy] at time 13:59:39.965171
2019-10-26 13:59:39,965 [salt.state       :1813][INFO    ][5418] Executing state file.absent for [/etc/criproxy]
2019-10-26 13:59:39,966 [salt.state       :300 ][INFO    ][5418] File /etc/criproxy is not present
2019-10-26 13:59:39,966 [salt.state       :1951][INFO    ][5418] Completed state [/etc/criproxy] at time 13:59:39.966449 duration_in_ms=1.278
2019-10-26 13:59:40,466 [salt.state       :1780][INFO    ][5418] Running state [criproxy] at time 13:59:40.466305
2019-10-26 13:59:40,466 [salt.state       :1813][INFO    ][5418] Executing state service.dead for [criproxy]
2019-10-26 13:59:40,467 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'status', 'criproxy.service', '-n', '0'] in directory '/root'
2019-10-26 13:59:40,486 [salt.state       :300 ][INFO    ][5418] The named service criproxy is not available
2019-10-26 13:59:40,487 [salt.state       :1951][INFO    ][5418] Completed state [criproxy] at time 13:59:40.487330 duration_in_ms=21.025
2019-10-26 13:59:40,487 [salt.state       :1780][INFO    ][5418] Running state [/etc/systemd/system/kubelet.service] at time 13:59:40.487734
2019-10-26 13:59:40,488 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/etc/systemd/system/kubelet.service]
2019-10-26 13:59:40,506 [salt.fileclient  :1219][INFO    ][5418] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kubelet.service'
2019-10-26 13:59:40,552 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:40,707 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:40,849 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:59:40,850 [salt.state       :1951][INFO    ][5418] Completed state [/etc/systemd/system/kubelet.service] at time 13:59:40.850125 duration_in_ms=362.389
2019-10-26 13:59:40,850 [salt.state       :1780][INFO    ][5418] Running state [/etc/kubernetes/config] at time 13:59:40.850615
2019-10-26 13:59:40,851 [salt.state       :1813][INFO    ][5418] Executing state file.absent for [/etc/kubernetes/config]
2019-10-26 13:59:40,851 [salt.state       :300 ][INFO    ][5418] File /etc/kubernetes/config is not present
2019-10-26 13:59:40,852 [salt.state       :1951][INFO    ][5418] Completed state [/etc/kubernetes/config] at time 13:59:40.852126 duration_in_ms=1.511
2019-10-26 13:59:40,852 [salt.state       :1780][INFO    ][5418] Running state [/etc/default/kubelet] at time 13:59:40.852503
2019-10-26 13:59:40,852 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/etc/default/kubelet]
2019-10-26 13:59:40,874 [salt.fileclient  :1219][INFO    ][5418] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubelet/default.master'
2019-10-26 13:59:40,949 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:41,082 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:41,216 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:41,348 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:41,482 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:41,619 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:41,751 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:59:41,751 [salt.state       :1951][INFO    ][5418] Completed state [/etc/default/kubelet] at time 13:59:41.751686 duration_in_ms=899.181
2019-10-26 13:59:41,752 [salt.state       :1780][INFO    ][5418] Running state [/etc/kubernetes/kubelet.kubeconfig] at time 13:59:41.752164
2019-10-26 13:59:41,752 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/etc/kubernetes/kubelet.kubeconfig]
2019-10-26 13:59:41,770 [salt.fileclient  :1219][INFO    ][5418] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubelet/kubelet.kubeconfig.master'
2019-10-26 13:59:41,819 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:41,954 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:42,086 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:42,215 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:42,364 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:59:42,365 [salt.state       :1951][INFO    ][5418] Completed state [/etc/kubernetes/kubelet.kubeconfig] at time 13:59:42.364993 duration_in_ms=612.81
2019-10-26 13:59:42,365 [salt.state       :1780][INFO    ][5418] Running state [/etc/kubernetes/manifests] at time 13:59:42.365468
2019-10-26 13:59:42,365 [salt.state       :1813][INFO    ][5418] Executing state file.directory for [/etc/kubernetes/manifests]
2019-10-26 13:59:42,367 [salt.state       :300 ][INFO    ][5418] {'/etc/kubernetes/manifests': 'New Dir'}
2019-10-26 13:59:42,368 [salt.state       :1951][INFO    ][5418] Completed state [/etc/kubernetes/manifests] at time 13:59:42.368167 duration_in_ms=2.699
2019-10-26 13:59:42,370 [salt.state       :1780][INFO    ][5418] Running state [kubelet] at time 13:59:42.370615
2019-10-26 13:59:42,370 [salt.state       :1813][INFO    ][5418] Executing state service.running for [kubelet]
2019-10-26 13:59:42,371 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'status', 'kubelet.service', '-n', '0'] in directory '/root'
2019-10-26 13:59:42,391 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:42,405 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:42,427 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:42,451 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:42,469 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:42,488 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:42,513 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:42,614 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-10-26 13:59:42,628 [salt.state       :300 ][INFO    ][5418] {'kubelet': True}
2019-10-26 13:59:42,628 [salt.state       :1951][INFO    ][5418] Completed state [kubelet] at time 13:59:42.628543 duration_in_ms=257.927
2019-10-26 13:59:42,628 [salt.state       :1780][INFO    ][5418] Running state [/etc/logrotate.d/kubernetes] at time 13:59:42.628909
2019-10-26 13:59:42,629 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/etc/logrotate.d/kubernetes]
2019-10-26 13:59:42,646 [salt.fileclient  :1219][INFO    ][5418] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/logrotate'
2019-10-26 13:59:42,656 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:59:42,656 [salt.state       :1951][INFO    ][5418] Completed state [/etc/logrotate.d/kubernetes] at time 13:59:42.656296 duration_in_ms=27.386
2019-10-26 13:59:42,656 [salt.state       :1780][INFO    ][5418] Running state [/opt/cni/bin] at time 13:59:42.656551
2019-10-26 13:59:42,656 [salt.state       :1813][INFO    ][5418] Executing state archive.extracted for [/opt/cni/bin]
2019-10-26 13:59:44,950 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['tar', 'xz', '-f', '/var/cache/salt/minion/extrn_files/base/docker-prod-local.artifactory.mirantis.com/artifactory/binary-prod-local/mirantis/kubernetes/containernetworking-plugins/containernetworking-plugins_v0.7.2-173-g8db2808.tar.gz'] in directory '/opt/cni/bin/'
2019-10-26 13:59:45,549 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['tar', '--version'] in directory '/root'
2019-10-26 13:59:45,566 [salt.state       :300 ][INFO    ][5418] {'extracted_files': 'no tar output so far'}
2019-10-26 13:59:45,566 [salt.state       :1951][INFO    ][5418] Completed state [/opt/cni/bin] at time 13:59:45.566754 duration_in_ms=2910.198
2019-10-26 13:59:45,567 [salt.state       :1780][INFO    ][5418] Running state [/etc/kubernetes/proxy.kubeconfig] at time 13:59:45.567256
2019-10-26 13:59:45,567 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/etc/kubernetes/proxy.kubeconfig]
2019-10-26 13:59:45,592 [salt.fileclient  :1219][INFO    ][5418] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-proxy/proxy.kubeconfig'
2019-10-26 13:59:45,640 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:45,792 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:45,962 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:46,096 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:46,250 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:59:46,250 [salt.state       :1951][INFO    ][5418] Completed state [/etc/kubernetes/proxy.kubeconfig] at time 13:59:46.250529 duration_in_ms=683.271
2019-10-26 13:59:46,251 [salt.state       :1780][INFO    ][5418] Running state [/etc/systemd/system/kube-proxy.service] at time 13:59:46.251005
2019-10-26 13:59:46,251 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/etc/systemd/system/kube-proxy.service]
2019-10-26 13:59:46,272 [salt.fileclient  :1219][INFO    ][5418] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-proxy.service'
2019-10-26 13:59:46,283 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:59:46,283 [salt.state       :1951][INFO    ][5418] Completed state [/etc/systemd/system/kube-proxy.service] at time 13:59:46.283311 duration_in_ms=32.305
2019-10-26 13:59:46,283 [salt.state       :1780][INFO    ][5418] Running state [/etc/default/kube-proxy] at time 13:59:46.283553
2019-10-26 13:59:46,283 [salt.state       :1813][INFO    ][5418] Executing state file.managed for [/etc/default/kube-proxy]
2019-10-26 13:59:46,285 [salt.state       :300 ][INFO    ][5418] File changed:
New file
2019-10-26 13:59:46,285 [salt.state       :1951][INFO    ][5418] Completed state [/etc/default/kube-proxy] at time 13:59:46.285822 duration_in_ms=2.269
2019-10-26 13:59:46,287 [salt.state       :1780][INFO    ][5418] Running state [kube-proxy] at time 13:59:46.287540
2019-10-26 13:59:46,287 [salt.state       :1813][INFO    ][5418] Executing state service.running for [kube-proxy]
2019-10-26 13:59:46,288 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'status', 'kube-proxy.service', '-n', '0'] in directory '/root'
2019-10-26 13:59:46,304 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:46,323 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:46,352 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:46,379 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:46,393 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:46,412 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:46,434 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:46,548 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5418] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-10-26 13:59:46,568 [salt.state       :300 ][INFO    ][5418] {'kube-proxy': True}
2019-10-26 13:59:46,569 [salt.state       :1951][INFO    ][5418] Completed state [kube-proxy] at time 13:59:46.569534 duration_in_ms=281.984
2019-10-26 13:59:46,573 [salt.minion      :1711][INFO    ][5418] Returning information for job: 20191026135851446315
2019-10-26 13:59:47,246 [salt.minion      :1308][INFO    ][4529] User sudo_ubuntu Executing command cmd.run with jid 20191026135947236400
2019-10-26 13:59:47,264 [salt.minion      :1432][INFO    ][8347] Starting a new job with PID 8347
2019-10-26 13:59:47,275 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][8347] Executing command 'calicoctl node status' in directory '/root'
2019-10-26 13:59:47,329 [salt.minion      :1711][INFO    ][8347] Returning information for job: 20191026135947236400
2019-10-26 13:59:47,964 [salt.minion      :1308][INFO    ][4529] User sudo_ubuntu Executing command cmd.run with jid 20191026135947953882
2019-10-26 13:59:47,985 [salt.minion      :1432][INFO    ][8368] Starting a new job with PID 8368
2019-10-26 13:59:47,996 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][8368] Executing command 'calicoctl get ippool' in directory '/root'
2019-10-26 13:59:48,113 [salt.minion      :1711][INFO    ][8368] Returning information for job: 20191026135947953882
2019-10-26 13:59:48,748 [salt.minion      :1308][INFO    ][4529] User sudo_ubuntu Executing command state.sls with jid 20191026135948738320
2019-10-26 13:59:48,773 [salt.minion      :1432][INFO    ][8387] Starting a new job with PID 8387
2019-10-26 13:59:55,098 [salt.state       :915 ][INFO    ][8387] Loading fresh modules for state activity
2019-10-26 13:59:55,149 [salt.fileclient  :1219][INFO    ][8387] Fetching file from saltenv 'base', ** done ** 'etcd/server/setup.sls'
2019-10-26 13:59:55,191 [salt.state       :1780][INFO    ][8387] Running state [/calico/ipam/v2/assignment/ipv4/block/192.168.0.0-16] at time 13:59:55.191156
2019-10-26 13:59:55,191 [salt.state       :1813][INFO    ][8387] Executing state etcd.set for [/calico/ipam/v2/assignment/ipv4/block/192.168.0.0-16]
2019-10-26 13:59:55,193 [urllib3.connectionpool:208 ][INFO    ][8387] Starting new HTTP connection (1): 127.0.0.1
2019-10-26 13:59:55,196 [salt.loaded.int.utils.etcd_util:200 ][ERROR   ][8387] etcd: Key not found : /calico
2019-10-26 13:59:55,196 [urllib3.connectionpool:208 ][INFO    ][8387] Starting new HTTP connection (1): 127.0.0.1
2019-10-26 13:59:55,199 [salt.state       :300 ][INFO    ][8387] {'/calico/ipam/v2/assignment/ipv4/block/192.168.0.0-16': '{"masquerade":true,"cidr":"192.168.0.0/16"}'}
2019-10-26 13:59:55,199 [salt.state       :1951][INFO    ][8387] Completed state [/calico/ipam/v2/assignment/ipv4/block/192.168.0.0-16] at time 13:59:55.199392 duration_in_ms=8.236
2019-10-26 13:59:55,201 [salt.minion      :1711][INFO    ][8387] Returning information for job: 20191026135948738320
2019-10-26 13:59:55,816 [salt.minion      :1308][INFO    ][4529] User sudo_ubuntu Executing command state.sls with jid 20191026135955807384
2019-10-26 13:59:55,835 [salt.minion      :1432][INFO    ][8422] Starting a new job with PID 8422
2019-10-26 13:59:56,920 [salt.state       :915 ][INFO    ][8422] Loading fresh modules for state activity
2019-10-26 13:59:56,987 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/init.sls'
2019-10-26 13:59:57,032 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/init.sls'
2019-10-26 13:59:57,091 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:57,236 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:57,396 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/service.sls'
2019-10-26 13:59:57,443 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:57,610 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:57,761 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:57,919 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:58,174 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:58,326 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:58,671 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:58,829 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:58,990 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:59,136 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:59,299 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:59,468 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 13:59:59,690 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/controller.sls'
2019-10-26 13:59:59,816 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 13:59:59,956 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:00,102 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:00,248 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:00,398 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:00,566 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:00,712 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command 'date "+%FT%TZ"' in directory '/root'
2019-10-26 14:00:00,796 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/setup.sls'
2019-10-26 14:00:00,858 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:01,000 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:01,160 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:01,366 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:01,643 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:01,831 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:01,986 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:02,125 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:02,361 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:02,499 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:02,652 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:02,806 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:03,047 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:03,223 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:03,396 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:03,579 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:03,804 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:03,959 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:04,155 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/control/init.sls'
2019-10-26 14:00:04,207 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:04,372 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:04,554 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/control/service.sls'
2019-10-26 14:00:04,653 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:04,817 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:04,998 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/control/role.sls'
2019-10-26 14:00:05,066 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:05,220 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:06,231 [salt.state       :1780][INFO    ][8422] Running state [curl] at time 14:00:06.231865
2019-10-26 14:00:06,232 [salt.state       :1813][INFO    ][8422] Executing state pkg.installed for [curl]
2019-10-26 14:00:06,233 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-26 14:00:06,659 [salt.state       :300 ][INFO    ][8422] All specified packages are already installed
2019-10-26 14:00:06,659 [salt.state       :1951][INFO    ][8422] Completed state [curl] at time 14:00:06.659870 duration_in_ms=428.005
2019-10-26 14:00:06,660 [salt.state       :1780][INFO    ][8422] Running state [git] at time 14:00:06.660410
2019-10-26 14:00:06,660 [salt.state       :1813][INFO    ][8422] Executing state pkg.installed for [git]
2019-10-26 14:00:06,669 [salt.state       :300 ][INFO    ][8422] All specified packages are already installed
2019-10-26 14:00:06,670 [salt.state       :1951][INFO    ][8422] Completed state [git] at time 14:00:06.670215 duration_in_ms=9.804
2019-10-26 14:00:06,670 [salt.state       :1780][INFO    ][8422] Running state [apt-transport-https] at time 14:00:06.670641
2019-10-26 14:00:06,671 [salt.state       :1813][INFO    ][8422] Executing state pkg.installed for [apt-transport-https]
2019-10-26 14:00:06,679 [salt.state       :300 ][INFO    ][8422] All specified packages are already installed
2019-10-26 14:00:06,680 [salt.state       :1951][INFO    ][8422] Completed state [apt-transport-https] at time 14:00:06.680245 duration_in_ms=9.604
2019-10-26 14:00:06,680 [salt.state       :1780][INFO    ][8422] Running state [python-apt] at time 14:00:06.680670
2019-10-26 14:00:06,681 [salt.state       :1813][INFO    ][8422] Executing state pkg.installed for [python-apt]
2019-10-26 14:00:06,689 [salt.state       :300 ][INFO    ][8422] All specified packages are already installed
2019-10-26 14:00:06,690 [salt.state       :1951][INFO    ][8422] Completed state [python-apt] at time 14:00:06.690148 duration_in_ms=9.478
2019-10-26 14:00:06,690 [salt.state       :1780][INFO    ][8422] Running state [socat] at time 14:00:06.690579
2019-10-26 14:00:06,691 [salt.state       :1813][INFO    ][8422] Executing state pkg.installed for [socat]
2019-10-26 14:00:06,699 [salt.state       :300 ][INFO    ][8422] All specified packages are already installed
2019-10-26 14:00:06,700 [salt.state       :1951][INFO    ][8422] Completed state [socat] at time 14:00:06.700089 duration_in_ms=9.509
2019-10-26 14:00:06,700 [salt.state       :1780][INFO    ][8422] Running state [openssl] at time 14:00:06.700513
2019-10-26 14:00:06,700 [salt.state       :1813][INFO    ][8422] Executing state pkg.installed for [openssl]
2019-10-26 14:00:06,710 [salt.state       :300 ][INFO    ][8422] All specified packages are already installed
2019-10-26 14:00:06,710 [salt.state       :1951][INFO    ][8422] Completed state [openssl] at time 14:00:06.710581 duration_in_ms=10.069
2019-10-26 14:00:06,711 [salt.state       :1780][INFO    ][8422] Running state [conntrack] at time 14:00:06.710997
2019-10-26 14:00:06,711 [salt.state       :1813][INFO    ][8422] Executing state pkg.installed for [conntrack]
2019-10-26 14:00:06,720 [salt.state       :300 ][INFO    ][8422] All specified packages are already installed
2019-10-26 14:00:06,720 [salt.state       :1951][INFO    ][8422] Completed state [conntrack] at time 14:00:06.720384 duration_in_ms=9.387
2019-10-26 14:00:06,723 [salt.state       :1780][INFO    ][8422] Running state [/usr/bin/hyperkube] at time 14:00:06.723737
2019-10-26 14:00:06,724 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/usr/bin/hyperkube]
2019-10-26 14:00:07,692 [salt.state       :300 ][INFO    ][8422] File /usr/bin/hyperkube is in the correct state
2019-10-26 14:00:07,693 [salt.state       :1951][INFO    ][8422] Completed state [/usr/bin/hyperkube] at time 14:00:07.693281 duration_in_ms=969.543
2019-10-26 14:00:07,695 [salt.state       :1780][INFO    ][8422] Running state [/usr/bin/kubectl] at time 14:00:07.695648
2019-10-26 14:00:07,696 [salt.state       :1813][INFO    ][8422] Executing state file.symlink for [/usr/bin/kubectl]
2019-10-26 14:00:07,698 [salt.state       :300 ][INFO    ][8422] Symlink /usr/bin/kubectl is present and owned by root:root
2019-10-26 14:00:07,699 [salt.state       :1951][INFO    ][8422] Completed state [/usr/bin/kubectl] at time 14:00:07.698966 duration_in_ms=3.318
2019-10-26 14:00:07,699 [salt.state       :1780][INFO    ][8422] Running state [containerd=1.2.5-3~u16.04+mcp] at time 14:00:07.699307
2019-10-26 14:00:07,699 [salt.state       :1813][INFO    ][8422] Executing state pkg.installed for [containerd=1.2.5-3~u16.04+mcp]
2019-10-26 14:00:07,727 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['apt-cache', '-q', 'policy', 'containerd=1.2.5-3~u16.04+mcp'] in directory '/root'
2019-10-26 14:00:07,849 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-10-26 14:00:10,425 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-10-26 14:00:10,449 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'containerd=1.2.5-3~u16.04+mcp'] in directory '/root'
2019-10-26 14:00:10,764 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-26 14:00:10,806 [salt.state       :300 ][INFO    ][8422] 1 targeted package was installed/updated.
2019-10-26 14:00:10,806 [salt.state       :1951][INFO    ][8422] Completed state [containerd=1.2.5-3~u16.04+mcp] at time 14:00:10.806906 duration_in_ms=3107.6
2019-10-26 14:00:10,807 [salt.state       :1780][INFO    ][8422] Running state [/etc/containerd/config.toml] at time 14:00:10.807234
2019-10-26 14:00:10,807 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/containerd/config.toml]
2019-10-26 14:00:10,862 [salt.minion      :1308][INFO    ][4529] User sudo_ubuntu Executing command saltutil.find_job with jid 20191026140010851232
2019-10-26 14:00:10,872 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:10,877 [salt.minion      :1432][INFO    ][9704] Starting a new job with PID 9704
2019-10-26 14:00:10,896 [salt.minion      :1711][INFO    ][9704] Returning information for job: 20191026140010851232
2019-10-26 14:00:11,019 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:11,185 [salt.state       :300 ][INFO    ][8422] File /etc/containerd/config.toml is in the correct state
2019-10-26 14:00:11,186 [salt.state       :1951][INFO    ][8422] Completed state [/etc/containerd/config.toml] at time 14:00:11.186100 duration_in_ms=378.865
2019-10-26 14:00:11,190 [salt.state       :1780][INFO    ][8422] Running state [containerd] at time 14:00:11.190331
2019-10-26 14:00:11,190 [salt.state       :1813][INFO    ][8422] Executing state service.running for [containerd]
2019-10-26 14:00:11,191 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'status', 'containerd.service', '-n', '0'] in directory '/root'
2019-10-26 14:00:11,215 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-10-26 14:00:11,236 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'containerd.service'] in directory '/root'
2019-10-26 14:00:11,254 [salt.state       :300 ][INFO    ][8422] The service containerd is already running
2019-10-26 14:00:11,255 [salt.state       :1951][INFO    ][8422] Completed state [containerd] at time 14:00:11.255121 duration_in_ms=64.79
2019-10-26 14:00:11,258 [salt.state       :1780][INFO    ][8422] Running state [/tmp/crictl] at time 14:00:11.258322
2019-10-26 14:00:11,258 [salt.state       :1813][INFO    ][8422] Executing state archive.extracted for [/tmp/crictl]
2019-10-26 14:00:11,586 [salt.state       :300 ][INFO    ][8422] All files in archive are already present
2019-10-26 14:00:11,586 [salt.state       :1951][INFO    ][8422] Completed state [/tmp/crictl] at time 14:00:11.586927 duration_in_ms=328.605
2019-10-26 14:00:11,588 [salt.state       :1780][INFO    ][8422] Running state [/usr/local/bin/crictl] at time 14:00:11.588168
2019-10-26 14:00:11,588 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/usr/local/bin/crictl]
2019-10-26 14:00:11,588 [salt.loaded.int.states.file:1019][WARNING ][8422] Use of argument owner found, "owner" is invalid, please use "user"
2019-10-26 14:00:11,822 [salt.state       :300 ][INFO    ][8422] File /usr/local/bin/crictl is in the correct state
2019-10-26 14:00:11,823 [salt.state       :1951][INFO    ][8422] Completed state [/usr/local/bin/crictl] at time 14:00:11.823028 duration_in_ms=234.86
2019-10-26 14:00:11,823 [salt.state       :1780][INFO    ][8422] Running state [/etc/crictl.yaml] at time 14:00:11.823321
2019-10-26 14:00:11,823 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/crictl.yaml]
2019-10-26 14:00:11,847 [salt.state       :300 ][INFO    ][8422] File /etc/crictl.yaml is in the correct state
2019-10-26 14:00:11,847 [salt.state       :1951][INFO    ][8422] Completed state [/etc/crictl.yaml] at time 14:00:11.847318 duration_in_ms=23.997
2019-10-26 14:00:11,847 [salt.state       :1780][INFO    ][8422] Running state [/etc/criproxy] at time 14:00:11.847682
2019-10-26 14:00:11,848 [salt.state       :1813][INFO    ][8422] Executing state file.absent for [/etc/criproxy]
2019-10-26 14:00:11,848 [salt.state       :300 ][INFO    ][8422] File /etc/criproxy is not present
2019-10-26 14:00:11,848 [salt.state       :1951][INFO    ][8422] Completed state [/etc/criproxy] at time 14:00:11.848893 duration_in_ms=1.21
2019-10-26 14:00:11,849 [salt.state       :1780][INFO    ][8422] Running state [criproxy] at time 14:00:11.849293
2019-10-26 14:00:11,849 [salt.state       :1813][INFO    ][8422] Executing state service.dead for [criproxy]
2019-10-26 14:00:11,850 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'status', 'criproxy.service', '-n', '0'] in directory '/root'
2019-10-26 14:00:11,873 [salt.state       :300 ][INFO    ][8422] The named service criproxy is not available
2019-10-26 14:00:11,874 [salt.state       :1951][INFO    ][8422] Completed state [criproxy] at time 14:00:11.874034 duration_in_ms=24.74
2019-10-26 14:00:11,874 [salt.state       :1780][INFO    ][8422] Running state [/etc/systemd/system/kubelet.service] at time 14:00:11.874409
2019-10-26 14:00:11,874 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/systemd/system/kubelet.service]
2019-10-26 14:00:11,944 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:12,071 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:12,223 [salt.state       :300 ][INFO    ][8422] File /etc/systemd/system/kubelet.service is in the correct state
2019-10-26 14:00:12,224 [salt.state       :1951][INFO    ][8422] Completed state [/etc/systemd/system/kubelet.service] at time 14:00:12.224399 duration_in_ms=349.989
2019-10-26 14:00:12,224 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/config] at time 14:00:12.224908
2019-10-26 14:00:12,225 [salt.state       :1813][INFO    ][8422] Executing state file.absent for [/etc/kubernetes/config]
2019-10-26 14:00:12,225 [salt.state       :300 ][INFO    ][8422] File /etc/kubernetes/config is not present
2019-10-26 14:00:12,226 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/config] at time 14:00:12.226164 duration_in_ms=1.255
2019-10-26 14:00:12,226 [salt.state       :1780][INFO    ][8422] Running state [/etc/default/kubelet] at time 14:00:12.226531
2019-10-26 14:00:12,226 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/default/kubelet]
2019-10-26 14:00:12,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:12,451 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:12,611 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:12,751 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:12,902 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:13,080 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:13,214 [salt.state       :300 ][INFO    ][8422] File /etc/default/kubelet is in the correct state
2019-10-26 14:00:13,215 [salt.state       :1951][INFO    ][8422] Completed state [/etc/default/kubelet] at time 14:00:13.215169 duration_in_ms=988.638
2019-10-26 14:00:13,215 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/kubelet.kubeconfig] at time 14:00:13.215524
2019-10-26 14:00:13,215 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/kubelet.kubeconfig]
2019-10-26 14:00:13,279 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:13,458 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:13,619 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:13,766 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:13,921 [salt.state       :300 ][INFO    ][8422] File /etc/kubernetes/kubelet.kubeconfig is in the correct state
2019-10-26 14:00:13,921 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/kubelet.kubeconfig] at time 14:00:13.921420 duration_in_ms=705.895
2019-10-26 14:00:13,921 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/manifests] at time 14:00:13.921789
2019-10-26 14:00:13,922 [salt.state       :1813][INFO    ][8422] Executing state file.directory for [/etc/kubernetes/manifests]
2019-10-26 14:00:13,923 [salt.state       :300 ][INFO    ][8422] Directory /etc/kubernetes/manifests is in the correct state
Directory /etc/kubernetes/manifests updated
2019-10-26 14:00:13,923 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/manifests] at time 14:00:13.923344 duration_in_ms=1.555
2019-10-26 14:00:13,926 [salt.state       :1780][INFO    ][8422] Running state [kubelet] at time 14:00:13.926407
2019-10-26 14:00:13,926 [salt.state       :1813][INFO    ][8422] Executing state service.running for [kubelet]
2019-10-26 14:00:13,927 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'status', 'kubelet.service', '-n', '0'] in directory '/root'
2019-10-26 14:00:13,949 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-10-26 14:00:13,969 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-10-26 14:00:13,991 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kubelet.service'] in directory '/root'
2019-10-26 14:00:14,020 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-10-26 14:00:14,034 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-10-26 14:00:14,048 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-10-26 14:00:14,064 [salt.state       :300 ][INFO    ][8422] {'kubelet': True}
2019-10-26 14:00:14,064 [salt.state       :1951][INFO    ][8422] Completed state [kubelet] at time 14:00:14.064718 duration_in_ms=138.311
2019-10-26 14:00:14,065 [salt.state       :1780][INFO    ][8422] Running state [/etc/logrotate.d/kubernetes] at time 14:00:14.065140
2019-10-26 14:00:14,065 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/logrotate.d/kubernetes]
2019-10-26 14:00:14,087 [salt.state       :300 ][INFO    ][8422] File /etc/logrotate.d/kubernetes is in the correct state
2019-10-26 14:00:14,087 [salt.state       :1951][INFO    ][8422] Completed state [/etc/logrotate.d/kubernetes] at time 14:00:14.087888 duration_in_ms=22.747
2019-10-26 14:00:14,088 [salt.state       :1780][INFO    ][8422] Running state [/opt/cni/bin] at time 14:00:14.088319
2019-10-26 14:00:14,088 [salt.state       :1813][INFO    ][8422] Executing state archive.extracted for [/opt/cni/bin]
2019-10-26 14:00:15,022 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['tar', 'xz', '-f', '/var/cache/salt/minion/extrn_files/base/docker-prod-local.artifactory.mirantis.com/artifactory/binary-prod-local/mirantis/kubernetes/containernetworking-plugins/containernetworking-plugins_v0.7.2-173-g8db2808.tar.gz'] in directory '/opt/cni/bin/'
2019-10-26 14:00:15,712 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['tar', '--version'] in directory '/root'
2019-10-26 14:00:15,730 [salt.state       :300 ][INFO    ][8422] {'extracted_files': 'no tar output so far'}
2019-10-26 14:00:15,731 [salt.state       :1951][INFO    ][8422] Completed state [/opt/cni/bin] at time 14:00:15.730935 duration_in_ms=1642.615
2019-10-26 14:00:15,731 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/addons] at time 14:00:15.731670
2019-10-26 14:00:15,732 [salt.state       :1813][INFO    ][8422] Executing state file.directory for [/etc/kubernetes/addons]
2019-10-26 14:00:15,734 [salt.state       :300 ][INFO    ][8422] Directory /etc/kubernetes/addons is in the correct state
Directory /etc/kubernetes/addons updated
2019-10-26 14:00:15,734 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/addons] at time 14:00:15.734719 duration_in_ms=3.047
2019-10-26 14:00:15,735 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/addons/calico/calico-kube-controllers.yml] at time 14:00:15.735442
2019-10-26 14:00:15,736 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/addons/calico/calico-kube-controllers.yml]
2019-10-26 14:00:15,811 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:15,946 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:16,091 [salt.state       :300 ][INFO    ][8422] File /etc/kubernetes/addons/calico/calico-kube-controllers.yml is in the correct state
2019-10-26 14:00:16,092 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/addons/calico/calico-kube-controllers.yml] at time 14:00:16.092322 duration_in_ms=356.88
2019-10-26 14:00:16,093 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/addons/calico/calico-rbac.yml] at time 14:00:16.093022
2019-10-26 14:00:16,093 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/addons/calico/calico-rbac.yml]
2019-10-26 14:00:16,121 [salt.state       :300 ][INFO    ][8422] File /etc/kubernetes/addons/calico/calico-rbac.yml is in the correct state
2019-10-26 14:00:16,121 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/addons/calico/calico-rbac.yml] at time 14:00:16.121779 duration_in_ms=28.756
2019-10-26 14:00:16,122 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/addons/netchecker/netchecker-svc.yml] at time 14:00:16.122198
2019-10-26 14:00:16,122 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-svc.yml]
2019-10-26 14:00:16,182 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:16,320 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:16,469 [salt.state       :300 ][INFO    ][8422] File /etc/kubernetes/addons/netchecker/netchecker-svc.yml is in the correct state
2019-10-26 14:00:16,470 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/addons/netchecker/netchecker-svc.yml] at time 14:00:16.470222 duration_in_ms=348.023
2019-10-26 14:00:16,470 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/addons/netchecker/netchecker-server.yml] at time 14:00:16.470844
2019-10-26 14:00:16,471 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-server.yml]
2019-10-26 14:00:16,548 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:16,744 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:16,897 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:17,043 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:17,206 [salt.state       :300 ][INFO    ][8422] File /etc/kubernetes/addons/netchecker/netchecker-server.yml is in the correct state
2019-10-26 14:00:17,207 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/addons/netchecker/netchecker-server.yml] at time 14:00:17.207478 duration_in_ms=736.633
2019-10-26 14:00:17,208 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/addons/netchecker/netchecker-agent.yml] at time 14:00:17.207951
2019-10-26 14:00:17,208 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-agent.yml]
2019-10-26 14:00:17,280 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:17,424 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:17,610 [salt.state       :300 ][INFO    ][8422] File /etc/kubernetes/addons/netchecker/netchecker-agent.yml is in the correct state
2019-10-26 14:00:17,611 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/addons/netchecker/netchecker-agent.yml] at time 14:00:17.611536 duration_in_ms=403.583
2019-10-26 14:00:17,612 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml] at time 14:00:17.612444
2019-10-26 14:00:17,613 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml]
2019-10-26 14:00:17,638 [salt.state       :300 ][INFO    ][8422] File /etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml is in the correct state
2019-10-26 14:00:17,638 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml] at time 14:00:17.638807 duration_in_ms=26.363
2019-10-26 14:00:17,639 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/addons/netchecker/netchecker-roles.yml] at time 14:00:17.639115
2019-10-26 14:00:17,639 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-roles.yml]
2019-10-26 14:00:17,661 [salt.state       :300 ][INFO    ][8422] File /etc/kubernetes/addons/netchecker/netchecker-roles.yml is in the correct state
2019-10-26 14:00:17,661 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/addons/netchecker/netchecker-roles.yml] at time 14:00:17.661726 duration_in_ms=22.611
2019-10-26 14:00:17,662 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/addons/prometheus/prometheus-roles.yml] at time 14:00:17.662026
2019-10-26 14:00:17,662 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/addons/prometheus/prometheus-roles.yml]
2019-10-26 14:00:17,682 [salt.state       :300 ][INFO    ][8422] File /etc/kubernetes/addons/prometheus/prometheus-roles.yml is in the correct state
2019-10-26 14:00:17,683 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/addons/prometheus/prometheus-roles.yml] at time 14:00:17.683152 duration_in_ms=21.125
2019-10-26 14:00:17,683 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/addons/dns] at time 14:00:17.683501
2019-10-26 14:00:17,683 [salt.state       :1813][INFO    ][8422] Executing state file.absent for [/etc/kubernetes/addons/dns]
2019-10-26 14:00:17,684 [salt.state       :300 ][INFO    ][8422] {'removed': '/etc/kubernetes/addons/dns'}
2019-10-26 14:00:17,684 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/addons/dns] at time 14:00:17.684899 duration_in_ms=1.398
2019-10-26 14:00:17,686 [salt.state       :1780][INFO    ][8422] Running state [kubectl -n kube-system delete svc kube-dns > /dev/null || echo "kube-dns is absent. OK" && true] at time 14:00:17.686493
2019-10-26 14:00:17,686 [salt.state       :1813][INFO    ][8422] Executing state cmd.run for [kubectl -n kube-system delete svc kube-dns > /dev/null || echo "kube-dns is absent. OK" && true]
2019-10-26 14:00:17,687 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command 'kubectl -n kube-system delete svc kube-dns > /dev/null || echo "kube-dns is absent. OK" && true' in directory '/root'
2019-10-26 14:00:17,814 [salt.state       :300 ][INFO    ][8422] {'pid': 10225, '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-10-26 14:00:17,815 [salt.state       :1951][INFO    ][8422] Completed state [kubectl -n kube-system delete svc kube-dns > /dev/null || echo "kube-dns is absent. OK" && true] at time 14:00:17.815448 duration_in_ms=128.954
2019-10-26 14:00:17,815 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/addons/coredns/coredns-cm.yml] at time 14:00:17.815922
2019-10-26 14:00:17,816 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/addons/coredns/coredns-cm.yml]
2019-10-26 14:00:17,843 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/coredns/coredns-cm.yml'
2019-10-26 14:00:17,902 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:18,065 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:18,205 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:18,345 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:18,492 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:18,493 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/addons/coredns/coredns-cm.yml] at time 14:00:18.492902 duration_in_ms=676.979
2019-10-26 14:00:18,493 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/addons/coredns/coredns-deploy.yml] at time 14:00:18.493332
2019-10-26 14:00:18,493 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/addons/coredns/coredns-deploy.yml]
2019-10-26 14:00:18,517 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/coredns/coredns-deploy.yml'
2019-10-26 14:00:18,567 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:18,698 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:18,831 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:18,832 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/addons/coredns/coredns-deploy.yml] at time 14:00:18.832445 duration_in_ms=339.111
2019-10-26 14:00:18,832 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/addons/coredns/coredns-svc.yml] at time 14:00:18.832888
2019-10-26 14:00:18,833 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/addons/coredns/coredns-svc.yml]
2019-10-26 14:00:18,859 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/coredns/coredns-svc.yml'
2019-10-26 14:00:18,907 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:19,090 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:19,232 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:19,232 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/addons/coredns/coredns-svc.yml] at time 14:00:19.232653 duration_in_ms=399.764
2019-10-26 14:00:19,233 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/addons/coredns/coredns-rbac.yml] at time 14:00:19.233037
2019-10-26 14:00:19,233 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/addons/coredns/coredns-rbac.yml]
2019-10-26 14:00:19,256 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/coredns/coredns-rbac.yml'
2019-10-26 14:00:19,304 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:19,456 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:19,603 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:19,603 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/addons/coredns/coredns-rbac.yml] at time 14:00:19.603860 duration_in_ms=370.821
2019-10-26 14:00:19,604 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/addons/metrics-server] at time 14:00:19.604234
2019-10-26 14:00:19,604 [salt.state       :1813][INFO    ][8422] Executing state file.absent for [/etc/kubernetes/addons/metrics-server]
2019-10-26 14:00:19,605 [salt.state       :300 ][INFO    ][8422] File /etc/kubernetes/addons/metrics-server is not present
2019-10-26 14:00:19,605 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/addons/metrics-server] at time 14:00:19.605329 duration_in_ms=1.095
2019-10-26 14:00:19,605 [salt.state       :1780][INFO    ][8422] Running state [/srv/kubernetes/known_tokens.csv] at time 14:00:19.605609
2019-10-26 14:00:19,605 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/srv/kubernetes/known_tokens.csv]
2019-10-26 14:00:19,625 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/known_tokens.csv'
2019-10-26 14:00:19,681 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:19,816 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:19,969 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:20,111 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:20,263 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:20,263 [salt.state       :1951][INFO    ][8422] Completed state [/srv/kubernetes/known_tokens.csv] at time 14:00:20.263373 duration_in_ms=657.763
2019-10-26 14:00:20,263 [salt.state       :1780][INFO    ][8422] Running state [/srv/kubernetes/basic_auth.csv] at time 14:00:20.263732
2019-10-26 14:00:20,264 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/srv/kubernetes/basic_auth.csv]
2019-10-26 14:00:20,287 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/basic_auth.csv'
2019-10-26 14:00:20,388 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:20,532 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:20,709 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:20,710 [salt.state       :1951][INFO    ][8422] Completed state [/srv/kubernetes/basic_auth.csv] at time 14:00:20.710001 duration_in_ms=446.267
2019-10-26 14:00:20,710 [salt.state       :1780][INFO    ][8422] Running state [/etc/default/kube-apiserver] at time 14:00:20.710336
2019-10-26 14:00:20,710 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/default/kube-apiserver]
2019-10-26 14:00:20,712 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:20,712 [salt.state       :1951][INFO    ][8422] Completed state [/etc/default/kube-apiserver] at time 14:00:20.712781 duration_in_ms=2.445
2019-10-26 14:00:20,713 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/scheduler.kubeconfig] at time 14:00:20.713054
2019-10-26 14:00:20,713 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/scheduler.kubeconfig]
2019-10-26 14:00:20,737 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-scheduler/scheduler.kubeconfig'
2019-10-26 14:00:20,793 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:20,971 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:21,118 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:21,262 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:21,414 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:21,415 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/scheduler.kubeconfig] at time 14:00:21.415263 duration_in_ms=702.206
2019-10-26 14:00:21,415 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/controller-manager.kubeconfig] at time 14:00:21.415734
2019-10-26 14:00:21,416 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/controller-manager.kubeconfig]
2019-10-26 14:00:21,441 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-controller-manager/controller-manager.kubeconfig'
2019-10-26 14:00:21,495 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:21,640 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:21,809 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:21,948 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:22,107 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:22,107 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/controller-manager.kubeconfig] at time 14:00:22.107810 duration_in_ms=692.075
2019-10-26 14:00:22,108 [salt.state       :1780][INFO    ][8422] Running state [/etc/default/kube-controller-manager] at time 14:00:22.108152
2019-10-26 14:00:22,108 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/default/kube-controller-manager]
2019-10-26 14:00:22,110 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:22,110 [salt.state       :1951][INFO    ][8422] Completed state [/etc/default/kube-controller-manager] at time 14:00:22.110669 duration_in_ms=2.517
2019-10-26 14:00:22,110 [salt.state       :1780][INFO    ][8422] Running state [/etc/default/kube-scheduler] at time 14:00:22.110921
2019-10-26 14:00:22,111 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/default/kube-scheduler]
2019-10-26 14:00:22,112 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:22,113 [salt.state       :1951][INFO    ][8422] Completed state [/etc/default/kube-scheduler] at time 14:00:22.113169 duration_in_ms=2.248
2019-10-26 14:00:22,113 [salt.state       :1780][INFO    ][8422] Running state [/etc/systemd/system/kube-apiserver.service] at time 14:00:22.113423
2019-10-26 14:00:22,113 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/systemd/system/kube-apiserver.service]
2019-10-26 14:00:22,135 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-apiserver.service'
2019-10-26 14:00:22,145 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:22,146 [salt.state       :1951][INFO    ][8422] Completed state [/etc/systemd/system/kube-apiserver.service] at time 14:00:22.146050 duration_in_ms=32.626
2019-10-26 14:00:22,146 [salt.state       :1780][INFO    ][8422] Running state [/etc/systemd/system/kube-scheduler.service] at time 14:00:22.146365
2019-10-26 14:00:22,146 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/systemd/system/kube-scheduler.service]
2019-10-26 14:00:22,167 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-scheduler.service'
2019-10-26 14:00:22,178 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:22,178 [salt.state       :1951][INFO    ][8422] Completed state [/etc/systemd/system/kube-scheduler.service] at time 14:00:22.178757 duration_in_ms=32.391
2019-10-26 14:00:22,179 [salt.state       :1780][INFO    ][8422] Running state [/etc/systemd/system/kube-controller-manager.service] at time 14:00:22.178992
2019-10-26 14:00:22,179 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/systemd/system/kube-controller-manager.service]
2019-10-26 14:00:22,200 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-controller-manager.service'
2019-10-26 14:00:22,211 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:22,212 [salt.state       :1951][INFO    ][8422] Completed state [/etc/systemd/system/kube-controller-manager.service] at time 14:00:22.212268 duration_in_ms=33.275
2019-10-26 14:00:22,212 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/ssl/kubernetes-server.crt] at time 14:00:22.212707
2019-10-26 14:00:22,213 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/ssl/kubernetes-server.crt]
2019-10-26 14:00:22,236 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** '_certs/kubernetes/kubernetes-server.crt'
2019-10-26 14:00:22,239 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:22,239 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/ssl/kubernetes-server.crt] at time 14:00:22.239584 duration_in_ms=26.877
2019-10-26 14:00:22,240 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/ssl/kubernetes-server.key] at time 14:00:22.239968
2019-10-26 14:00:22,240 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/ssl/kubernetes-server.key]
2019-10-26 14:00:22,261 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** '_certs/kubernetes/kubernetes-server.key'
2019-10-26 14:00:22,263 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:22,263 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/ssl/kubernetes-server.key] at time 14:00:22.263553 duration_in_ms=23.585
2019-10-26 14:00:22,263 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/ssl/kubernetes-server.pem] at time 14:00:22.263865
2019-10-26 14:00:22,264 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/ssl/kubernetes-server.pem]
2019-10-26 14:00:22,286 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** '_certs/kubernetes/kubernetes-server.pem'
2019-10-26 14:00:22,288 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:22,288 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/ssl/kubernetes-server.pem] at time 14:00:22.288791 duration_in_ms=24.926
2019-10-26 14:00:22,296 [salt.state       :1780][INFO    ][8422] Running state [kube-apiserver] at time 14:00:22.296913
2019-10-26 14:00:22,297 [salt.state       :1813][INFO    ][8422] Executing state service.running for [kube-apiserver]
2019-10-26 14:00:22,297 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'status', 'kube-apiserver.service', '-n', '0'] in directory '/root'
2019-10-26 14:00:22,324 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-active', 'kube-apiserver.service'] in directory '/root'
2019-10-26 14:00:22,347 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'kube-apiserver.service'] in directory '/root'
2019-10-26 14:00:22,373 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-apiserver.service'] in directory '/root'
2019-10-26 14:00:35,888 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-active', 'kube-apiserver.service'] in directory '/root'
2019-10-26 14:00:35,906 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'kube-apiserver.service'] in directory '/root'
2019-10-26 14:00:35,923 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'kube-apiserver.service'] in directory '/root'
2019-10-26 14:00:35,950 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-apiserver.service'] in directory '/root'
2019-10-26 14:00:36,121 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'kube-apiserver.service'] in directory '/root'
2019-10-26 14:00:36,138 [salt.state       :300 ][INFO    ][8422] {'kube-apiserver': True}
2019-10-26 14:00:36,139 [salt.state       :1951][INFO    ][8422] Completed state [kube-apiserver] at time 14:00:36.139061 duration_in_ms=13842.148
2019-10-26 14:00:36,144 [salt.state       :1780][INFO    ][8422] Running state [kube-scheduler] at time 14:00:36.144018
2019-10-26 14:00:36,144 [salt.state       :1813][INFO    ][8422] Executing state service.running for [kube-scheduler]
2019-10-26 14:00:36,145 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'status', 'kube-scheduler.service', '-n', '0'] in directory '/root'
2019-10-26 14:00:36,161 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-active', 'kube-scheduler.service'] in directory '/root'
2019-10-26 14:00:36,175 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'kube-scheduler.service'] in directory '/root'
2019-10-26 14:00:36,194 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-scheduler.service'] in directory '/root'
2019-10-26 14:00:36,247 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-active', 'kube-scheduler.service'] in directory '/root'
2019-10-26 14:00:36,265 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'kube-scheduler.service'] in directory '/root'
2019-10-26 14:00:36,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'kube-scheduler.service'] in directory '/root'
2019-10-26 14:00:36,305 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-scheduler.service'] in directory '/root'
2019-10-26 14:00:36,428 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'kube-scheduler.service'] in directory '/root'
2019-10-26 14:00:36,444 [salt.state       :300 ][INFO    ][8422] {'kube-scheduler': True}
2019-10-26 14:00:36,444 [salt.state       :1951][INFO    ][8422] Completed state [kube-scheduler] at time 14:00:36.444648 duration_in_ms=300.631
2019-10-26 14:00:36,449 [salt.state       :1780][INFO    ][8422] Running state [kube-controller-manager] at time 14:00:36.449542
2019-10-26 14:00:36,449 [salt.state       :1813][INFO    ][8422] Executing state service.running for [kube-controller-manager]
2019-10-26 14:00:36,450 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'status', 'kube-controller-manager.service', '-n', '0'] in directory '/root'
2019-10-26 14:00:36,467 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-active', 'kube-controller-manager.service'] in directory '/root'
2019-10-26 14:00:36,484 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'kube-controller-manager.service'] in directory '/root'
2019-10-26 14:00:36,511 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-controller-manager.service'] in directory '/root'
2019-10-26 14:00:36,565 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-active', 'kube-controller-manager.service'] in directory '/root'
2019-10-26 14:00:36,581 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'kube-controller-manager.service'] in directory '/root'
2019-10-26 14:00:36,602 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'kube-controller-manager.service'] in directory '/root'
2019-10-26 14:00:36,630 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-controller-manager.service'] in directory '/root'
2019-10-26 14:00:36,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'kube-controller-manager.service'] in directory '/root'
2019-10-26 14:00:36,757 [salt.state       :300 ][INFO    ][8422] {'kube-controller-manager': True}
2019-10-26 14:00:36,757 [salt.state       :1951][INFO    ][8422] Completed state [kube-controller-manager] at time 14:00:36.757780 duration_in_ms=308.237
2019-10-26 14:00:36,758 [salt.state       :1780][INFO    ][8422] Running state [kubectl create ns "netchecker"] at time 14:00:36.758152
2019-10-26 14:00:36,758 [salt.state       :1813][INFO    ][8422] Executing state cmd.run for [kubectl create ns "netchecker"]
2019-10-26 14:00:36,759 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command 'kubectl get ns -o=custom-columns=NAME:.metadata.name | grep -v NAME | grep "netchecker"' in directory '/root'
2019-10-26 14:00:37,021 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command 'kubectl create ns "netchecker"' in directory '/root'
2019-10-26 14:00:37,175 [salt.state       :300 ][INFO    ][8422] {'pid': 11043, 'retcode': 0, 'stderr': '', 'stdout': 'namespace/netchecker created'}
2019-10-26 14:00:37,175 [salt.state       :1951][INFO    ][8422] Completed state [kubectl create ns "netchecker"] at time 14:00:37.175810 duration_in_ms=417.657
2019-10-26 14:00:37,176 [salt.state       :1780][INFO    ][8422] Running state [/usr/bin/calicoctl] at time 14:00:37.176287
2019-10-26 14:00:37,176 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/usr/bin/calicoctl]
2019-10-26 14:00:37,330 [salt.state       :300 ][INFO    ][8422] File /usr/bin/calicoctl is in the correct state
2019-10-26 14:00:37,330 [salt.state       :1951][INFO    ][8422] Completed state [/usr/bin/calicoctl] at time 14:00:37.330885 duration_in_ms=154.597
2019-10-26 14:00:37,331 [salt.state       :1780][INFO    ][8422] Running state [/usr/bin/birdcl] at time 14:00:37.331173
2019-10-26 14:00:37,331 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/usr/bin/birdcl]
2019-10-26 14:00:37,334 [salt.state       :300 ][INFO    ][8422] File /usr/bin/birdcl is in the correct state
2019-10-26 14:00:37,335 [salt.state       :1951][INFO    ][8422] Completed state [/usr/bin/birdcl] at time 14:00:37.334979 duration_in_ms=3.806
2019-10-26 14:00:37,335 [salt.state       :1780][INFO    ][8422] Running state [/opt/cni/bin/calico] at time 14:00:37.335212
2019-10-26 14:00:37,335 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/opt/cni/bin/calico]
2019-10-26 14:00:37,455 [salt.state       :300 ][INFO    ][8422] File /opt/cni/bin/calico is in the correct state
2019-10-26 14:00:37,455 [salt.state       :1951][INFO    ][8422] Completed state [/opt/cni/bin/calico] at time 14:00:37.455490 duration_in_ms=120.277
2019-10-26 14:00:37,455 [salt.state       :1780][INFO    ][8422] Running state [/opt/cni/bin/calico-ipam] at time 14:00:37.455791
2019-10-26 14:00:37,456 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/opt/cni/bin/calico-ipam]
2019-10-26 14:00:37,571 [salt.state       :300 ][INFO    ][8422] File /opt/cni/bin/calico-ipam is in the correct state
2019-10-26 14:00:37,572 [salt.state       :1951][INFO    ][8422] Completed state [/opt/cni/bin/calico-ipam] at time 14:00:37.572195 duration_in_ms=116.403
2019-10-26 14:00:37,572 [salt.state       :1780][INFO    ][8422] Running state [/etc/cni/net.d/10-calico.conf] at time 14:00:37.572489
2019-10-26 14:00:37,572 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/cni/net.d/10-calico.conf]
2019-10-26 14:00:37,640 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:37,795 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:37,967 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:38,115 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:38,258 [salt.state       :300 ][INFO    ][8422] File /etc/cni/net.d/10-calico.conf is in the correct state
2019-10-26 14:00:38,259 [salt.state       :1951][INFO    ][8422] Completed state [/etc/cni/net.d/10-calico.conf] at time 14:00:38.259246 duration_in_ms=686.755
2019-10-26 14:00:38,259 [salt.state       :1780][INFO    ][8422] Running state [/etc/calico/network-environment] at time 14:00:38.259597
2019-10-26 14:00:38,259 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/calico/network-environment]
2019-10-26 14:00:38,341 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:38,500 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:38,664 [salt.state       :300 ][INFO    ][8422] File /etc/calico/network-environment is in the correct state
2019-10-26 14:00:38,665 [salt.state       :1951][INFO    ][8422] Completed state [/etc/calico/network-environment] at time 14:00:38.665280 duration_in_ms=405.682
2019-10-26 14:00:38,665 [salt.state       :1780][INFO    ][8422] Running state [/etc/calico/calicoctl.cfg] at time 14:00:38.665685
2019-10-26 14:00:38,666 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/calico/calicoctl.cfg]
2019-10-26 14:00:38,733 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:38,894 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:39,060 [salt.state       :300 ][INFO    ][8422] File /etc/calico/calicoctl.cfg is in the correct state
2019-10-26 14:00:39,061 [salt.state       :1951][INFO    ][8422] Completed state [/etc/calico/calicoctl.cfg] at time 14:00:39.061184 duration_in_ms=395.498
2019-10-26 14:00:39,062 [salt.state       :1780][INFO    ][8422] Running state [/etc/systemd/system/calico-node.service] at time 14:00:39.062670
2019-10-26 14:00:39,063 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/systemd/system/calico-node.service]
2019-10-26 14:00:39,103 [salt.state       :300 ][INFO    ][8422] File /etc/systemd/system/calico-node.service is in the correct state
2019-10-26 14:00:39,103 [salt.state       :1951][INFO    ][8422] Completed state [/etc/systemd/system/calico-node.service] at time 14:00:39.103871 duration_in_ms=41.201
2019-10-26 14:00:39,104 [salt.state       :1780][INFO    ][8422] Running state [/var/lib/calico] at time 14:00:39.104193
2019-10-26 14:00:39,104 [salt.state       :1813][INFO    ][8422] Executing state file.directory for [/var/lib/calico]
2019-10-26 14:00:39,105 [salt.state       :300 ][INFO    ][8422] Directory /var/lib/calico is in the correct state
Directory /var/lib/calico updated
2019-10-26 14:00:39,105 [salt.state       :1951][INFO    ][8422] Completed state [/var/lib/calico] at time 14:00:39.105743 duration_in_ms=1.55
2019-10-26 14:00:39,106 [salt.state       :1780][INFO    ][8422] Running state [/var/log/calico] at time 14:00:39.106028
2019-10-26 14:00:39,106 [salt.state       :1813][INFO    ][8422] Executing state file.directory for [/var/log/calico]
2019-10-26 14:00:39,107 [salt.state       :300 ][INFO    ][8422] Directory /var/log/calico is in the correct state
Directory /var/log/calico updated
2019-10-26 14:00:39,107 [salt.state       :1951][INFO    ][8422] Completed state [/var/log/calico] at time 14:00:39.107532 duration_in_ms=1.504
2019-10-26 14:00:39,113 [salt.state       :1780][INFO    ][8422] Running state [calico-node] at time 14:00:39.113231
2019-10-26 14:00:39,113 [salt.state       :1813][INFO    ][8422] Executing state service.running for [calico-node]
2019-10-26 14:00:39,114 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'status', 'calico-node.service', '-n', '0'] in directory '/root'
2019-10-26 14:00:39,135 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-10-26 14:00:39,154 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-10-26 14:00:39,175 [salt.state       :300 ][INFO    ][8422] The service calico-node is already running
2019-10-26 14:00:39,175 [salt.state       :1951][INFO    ][8422] Completed state [calico-node] at time 14:00:39.175720 duration_in_ms=62.488
2019-10-26 14:00:39,176 [salt.state       :1780][INFO    ][8422] Running state [/etc/kubernetes/proxy.kubeconfig] at time 14:00:39.176492
2019-10-26 14:00:39,177 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/kubernetes/proxy.kubeconfig]
2019-10-26 14:00:39,250 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:39,396 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:39,570 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:39,752 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:39,897 [salt.state       :300 ][INFO    ][8422] File /etc/kubernetes/proxy.kubeconfig is in the correct state
2019-10-26 14:00:39,897 [salt.state       :1951][INFO    ][8422] Completed state [/etc/kubernetes/proxy.kubeconfig] at time 14:00:39.897756 duration_in_ms=721.264
2019-10-26 14:00:39,910 [salt.state       :1780][INFO    ][8422] Running state [/etc/systemd/system/kube-proxy.service] at time 14:00:39.910885
2019-10-26 14:00:39,911 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/systemd/system/kube-proxy.service]
2019-10-26 14:00:39,943 [salt.state       :300 ][INFO    ][8422] File /etc/systemd/system/kube-proxy.service is in the correct state
2019-10-26 14:00:39,944 [salt.state       :1951][INFO    ][8422] Completed state [/etc/systemd/system/kube-proxy.service] at time 14:00:39.944018 duration_in_ms=33.133
2019-10-26 14:00:39,944 [salt.state       :1780][INFO    ][8422] Running state [/etc/default/kube-proxy] at time 14:00:39.944289
2019-10-26 14:00:39,944 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/etc/default/kube-proxy]
2019-10-26 14:00:39,945 [salt.state       :300 ][INFO    ][8422] File /etc/default/kube-proxy is in the correct state
2019-10-26 14:00:39,946 [salt.state       :1951][INFO    ][8422] Completed state [/etc/default/kube-proxy] at time 14:00:39.946114 duration_in_ms=1.825
2019-10-26 14:00:39,948 [salt.state       :1780][INFO    ][8422] Running state [kube-proxy] at time 14:00:39.948639
2019-10-26 14:00:39,948 [salt.state       :1813][INFO    ][8422] Executing state service.running for [kube-proxy]
2019-10-26 14:00:39,949 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'status', 'kube-proxy.service', '-n', '0'] in directory '/root'
2019-10-26 14:00:39,969 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-10-26 14:00:39,985 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-10-26 14:00:39,998 [salt.state       :300 ][INFO    ][8422] The service kube-proxy is already running
2019-10-26 14:00:39,998 [salt.state       :1951][INFO    ][8422] Completed state [kube-proxy] at time 14:00:39.998537 duration_in_ms=49.898
2019-10-26 14:00:40,000 [salt.state       :1780][INFO    ][8422] Running state [/srv/kubernetes] at time 14:00:40.000438
2019-10-26 14:00:40,000 [salt.state       :1813][INFO    ][8422] Executing state file.directory for [/srv/kubernetes]
2019-10-26 14:00:40,001 [salt.state       :300 ][INFO    ][8422] Directory /srv/kubernetes is in the correct state
Directory /srv/kubernetes updated
2019-10-26 14:00:40,001 [salt.state       :1951][INFO    ][8422] Completed state [/srv/kubernetes] at time 14:00:40.001927 duration_in_ms=1.489
2019-10-26 14:00:40,002 [salt.state       :1780][INFO    ][8422] Running state [/srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml] at time 14:00:40.002761
2019-10-26 14:00:40,003 [salt.state       :1813][INFO    ][8422] Executing state file.managed for [/srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml]
2019-10-26 14:00:40,021 [salt.fileclient  :1219][INFO    ][8422] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/rolebinding.yml'
2019-10-26 14:00:40,037 [salt.state       :300 ][INFO    ][8422] File changed:
New file
2019-10-26 14:00:40,037 [salt.state       :1951][INFO    ][8422] Completed state [/srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml] at time 14:00:40.037284 duration_in_ms=34.523
2019-10-26 14:00:40,038 [salt.state       :1780][INFO    ][8422] Running state [kubectl apply -f /srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml] at time 14:00:40.038640
2019-10-26 14:00:40,038 [salt.state       :1813][INFO    ][8422] Executing state cmd.run for [kubectl apply -f /srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml]
2019-10-26 14:00:40,039 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8422] Executing command 'kubectl apply -f /srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml' in directory '/root'
2019-10-26 14:00:40,386 [salt.state       :300 ][INFO    ][8422] {'pid': 11350, 'retcode': 0, 'stderr': '', 'stdout': 'clusterrolebinding.rbac.authorization.k8s.io/root-cluster-admin-binding created'}
2019-10-26 14:00:40,387 [salt.state       :1951][INFO    ][8422] Completed state [kubectl apply -f /srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml] at time 14:00:40.387558 duration_in_ms=348.914
2019-10-26 14:00:40,393 [salt.minion      :1711][INFO    ][8422] Returning information for job: 20191026135955807384
2019-10-26 14:00:41,099 [salt.minion      :1308][INFO    ][4529] User sudo_ubuntu Executing command state.sls with jid 20191026140041088885
2019-10-26 14:00:41,116 [salt.minion      :1432][INFO    ][11372] Starting a new job with PID 11372
2019-10-26 14:00:47,512 [salt.state       :915 ][INFO    ][11372] Loading fresh modules for state activity
2019-10-26 14:00:47,624 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11372] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:47,766 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11372] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:47,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11372] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:48,139 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11372] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:48,316 [salt.state       :1780][INFO    ][11372] Running state [/etc/kubernetes/kubeconfig.sh] at time 14:00:48.316135
2019-10-26 14:00:48,316 [salt.state       :1813][INFO    ][11372] Executing state file.managed for [/etc/kubernetes/kubeconfig.sh]
2019-10-26 14:00:48,355 [salt.fileclient  :1219][INFO    ][11372] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubeconfig.sh'
2019-10-26 14:00:48,403 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11372] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:48,564 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11372] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:48,707 [salt.state       :300 ][INFO    ][11372] File changed:
New file
2019-10-26 14:00:48,708 [salt.state       :1951][INFO    ][11372] Completed state [/etc/kubernetes/kubeconfig.sh] at time 14:00:48.708087 duration_in_ms=391.952
2019-10-26 14:00:48,711 [salt.state       :1780][INFO    ][11372] Running state [/etc/kubernetes/kubeconfig.sh > /etc/kubernetes/admin-kube-config] at time 14:00:48.711077
2019-10-26 14:00:48,711 [salt.state       :1813][INFO    ][11372] Executing state cmd.run for [/etc/kubernetes/kubeconfig.sh > /etc/kubernetes/admin-kube-config]
2019-10-26 14:00:48,712 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11372] Executing command '/etc/kubernetes/kubeconfig.sh > /etc/kubernetes/admin-kube-config' in directory '/root'
2019-10-26 14:00:48,760 [salt.state       :300 ][INFO    ][11372] {'pid': 11497, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-10-26 14:00:48,760 [salt.state       :1951][INFO    ][11372] Completed state [/etc/kubernetes/kubeconfig.sh > /etc/kubernetes/admin-kube-config] at time 14:00:48.760805 duration_in_ms=49.73
2019-10-26 14:00:48,761 [salt.state       :1780][INFO    ][11372] Running state [/etc/kubernetes/addons/namespace.yaml] at time 14:00:48.761398
2019-10-26 14:00:48,762 [salt.state       :1813][INFO    ][11372] Executing state file.managed for [/etc/kubernetes/addons/namespace.yaml]
2019-10-26 14:00:48,788 [salt.fileclient  :1219][INFO    ][11372] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addon-manager/namespace.yaml'
2019-10-26 14:00:48,790 [salt.state       :300 ][INFO    ][11372] File changed:
New file
2019-10-26 14:00:48,791 [salt.state       :1951][INFO    ][11372] Completed state [/etc/kubernetes/addons/namespace.yaml] at time 14:00:48.791014 duration_in_ms=29.617
2019-10-26 14:00:48,791 [salt.state       :1780][INFO    ][11372] Running state [/etc/kubernetes/manifests/kube-addon-manager.yml] at time 14:00:48.791337
2019-10-26 14:00:48,791 [salt.state       :1813][INFO    ][11372] Executing state file.managed for [/etc/kubernetes/manifests/kube-addon-manager.yml]
2019-10-26 14:00:48,820 [salt.fileclient  :1219][INFO    ][11372] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/manifest/kube-addon-manager.yml'
2019-10-26 14:00:48,873 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11372] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:49,013 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11372] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:49,160 [salt.state       :300 ][INFO    ][11372] File changed:
New file
2019-10-26 14:00:49,160 [salt.state       :1951][INFO    ][11372] Completed state [/etc/kubernetes/manifests/kube-addon-manager.yml] at time 14:00:49.160469 duration_in_ms=369.13
2019-10-26 14:00:49,161 [salt.state       :1780][INFO    ][11372] Running state [/etc/default/kube-addon-manager] at time 14:00:49.160978
2019-10-26 14:00:49,161 [salt.state       :1813][INFO    ][11372] Executing state file.absent for [/etc/default/kube-addon-manager]
2019-10-26 14:00:49,162 [salt.state       :300 ][INFO    ][11372] File /etc/default/kube-addon-manager is not present
2019-10-26 14:00:49,162 [salt.state       :1951][INFO    ][11372] Completed state [/etc/default/kube-addon-manager] at time 14:00:49.162509 duration_in_ms=1.53
2019-10-26 14:00:49,162 [salt.state       :1780][INFO    ][11372] Running state [/usr/bin/kube-addons.sh] at time 14:00:49.162887
2019-10-26 14:00:49,163 [salt.state       :1813][INFO    ][11372] Executing state file.absent for [/usr/bin/kube-addons.sh]
2019-10-26 14:00:49,163 [salt.state       :300 ][INFO    ][11372] File /usr/bin/kube-addons.sh is not present
2019-10-26 14:00:49,164 [salt.state       :1951][INFO    ][11372] Completed state [/usr/bin/kube-addons.sh] at time 14:00:49.164077 duration_in_ms=1.189
2019-10-26 14:00:49,618 [salt.state       :1780][INFO    ][11372] Running state [kube-addon-manager] at time 14:00:49.618084
2019-10-26 14:00:49,618 [salt.state       :1813][INFO    ][11372] Executing state service.dead for [kube-addon-manager]
2019-10-26 14:00:49,619 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11372] Executing command ['systemctl', 'status', 'kube-addon-manager.service', '-n', '0'] in directory '/root'
2019-10-26 14:00:49,652 [salt.state       :300 ][INFO    ][11372] The named service kube-addon-manager is not available
2019-10-26 14:00:49,652 [salt.state       :1951][INFO    ][11372] Completed state [kube-addon-manager] at time 14:00:49.652660 duration_in_ms=34.576
2019-10-26 14:00:49,653 [salt.state       :1780][INFO    ][11372] Running state [/etc/systemd/system/kube-addon-manager.service] at time 14:00:49.653175
2019-10-26 14:00:49,653 [salt.state       :1813][INFO    ][11372] Executing state file.absent for [/etc/systemd/system/kube-addon-manager.service]
2019-10-26 14:00:49,654 [salt.state       :300 ][INFO    ][11372] File /etc/systemd/system/kube-addon-manager.service is not present
2019-10-26 14:00:49,654 [salt.state       :1951][INFO    ][11372] Completed state [/etc/systemd/system/kube-addon-manager.service] at time 14:00:49.654536 duration_in_ms=1.361
2019-10-26 14:00:49,654 [salt.state       :1780][INFO    ][11372] Running state [/srv/kubernetes/conformance.yml] at time 14:00:49.654927
2019-10-26 14:00:49,655 [salt.state       :1813][INFO    ][11372] Executing state file.managed for [/srv/kubernetes/conformance.yml]
2019-10-26 14:00:49,685 [salt.fileclient  :1219][INFO    ][11372] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/conformance/conformance.yml'
2019-10-26 14:00:49,735 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11372] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-10-26 14:00:49,869 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11372] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-10-26 14:00:50,009 [salt.state       :300 ][INFO    ][11372] File changed:
New file
2019-10-26 14:00:50,010 [salt.state       :1951][INFO    ][11372] Completed state [/srv/kubernetes/conformance.yml] at time 14:00:50.010148 duration_in_ms=355.22
2019-10-26 14:00:50,011 [salt.minion      :1711][INFO    ][11372] Returning information for job: 20191026140041088885
2019-10-26 14:00:50,740 [salt.minion      :1308][INFO    ][4529] User sudo_ubuntu Executing command cp.push with jid 20191026140050732566
2019-10-26 14:00:50,756 [salt.minion      :1432][INFO    ][11586] Starting a new job with PID 11586
2019-10-26 14:00:50,777 [salt.minion      :1711][INFO    ][11586] Returning information for job: 20191026140050732566
2019-10-26 14:00:51,458 [salt.minion      :1308][INFO    ][4529] User sudo_ubuntu Executing command cp.push_dir with jid 20191026140051450558
2019-10-26 14:00:51,472 [salt.minion      :1432][INFO    ][11642] Starting a new job with PID 11642
2019-10-26 14:00:51,867 [salt.minion      :1711][INFO    ][11642] Returning information for job: 20191026140051450558
2019-10-26 14:03:47,246 [salt.minion      :1308][INFO    ][4529] User sudo_ubuntu Executing command cp.push_dir with jid 20191026140347233165
2019-10-26 14:03:47,265 [salt.minion      :1432][INFO    ][13670] Starting a new job with PID 13670
