2019-05-18 16:28:26,952 [salt.utils       :831 ][ERROR   ][1677] DNS lookup or connection check of 'salt' failed.
2019-05-18 16:28:26,953 [salt.minion      :152 ][ERROR   ][1677] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-05-18 16:28:28,917 [salt.utils.parsers:1051][WARNING ][1677] Minion received a SIGTERM. Exiting.
2019-05-18 16:29:11,147 [salt.minion      :870 ][ERROR   ][1858] Error while bringing up minion for multi-master. Is master at 192.168.11.2 responding?
2019-05-18 16:34:24,991 [salt.utils.decorators:613 ][WARNING ][2100] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 16:34:39,351 [salt.loaded.int.states.file:2298][WARNING ][2100] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-18 16:34:49,828 [salt.utils.decorators:613 ][WARNING ][2100] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 16:34:49,947 [salt.loaded.int.states.file:2298][WARNING ][2100] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-18 16:34:49,950 [salt.loaded.int.states.file:2298][WARNING ][2100] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-18 16:34:49,951 [salt.loaded.int.states.file:2298][WARNING ][2100] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-18 16:34:49,953 [salt.loaded.int.states.file:2298][WARNING ][2100] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-18 16:34:49,954 [salt.loaded.int.states.file:2298][WARNING ][2100] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-18 16:34:49,955 [salt.loaded.int.states.file:2298][WARNING ][2100] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-18 16:34:49,956 [salt.loaded.int.states.file:2298][WARNING ][2100] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-18 16:34:49,958 [salt.loaded.int.states.file:2298][WARNING ][2100] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-18 16:34:58,826 [salt.loaded.int.module.debian_ip:1964][WARNING ][2100] The network state sls is requiring a reboot of the system to properly apply network configuration.
2019-05-18 16:35:00,041 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][2100] Command '['umount', '/dev/shm']' failed with return code: 32
2019-05-18 16:35:00,042 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][2100] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-05-18 16:35:00,042 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][2100] retcode: 32
2019-05-18 16:35:01,498 [salt.utils.parsers:1051][WARNING ][1858] Minion received a SIGTERM. Exiting.
2019-05-18 16:36:21,217 [salt.utils.decorators:613 ][WARNING ][3129] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 16:36:24,049 [salt.loaded.int.states.file:2298][WARNING ][3129] State for file: /etc/kubernetes/ssl/ca-kubernetes.crt - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-18 16:36:26,399 [salt.loaded.int.states.file:2298][WARNING ][3129] State for file: /var/lib/etcd/ca.pem - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-18 16:36:44,124 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4965] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-18 16:36:44,154 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4965] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-18 16:36:44,175 [salt.utils.parsers:1051][WARNING ][2527] Minion received a SIGTERM. Exiting.
2019-05-18 16:36:45,047 [salt.cli.daemons :293 ][INFO    ][5017] Setting up the Salt Minion "ctl01.mcp-k8s-calico-noha.local"
2019-05-18 16:36:45,162 [salt.cli.daemons :82  ][INFO    ][5017] Starting up the Salt Minion
2019-05-18 16:36:45,163 [salt.utils.event :1017][INFO    ][5017] Starting pull socket on /var/run/salt/minion/minion_event_1899fe9592_pull.ipc
2019-05-18 16:36:45,968 [salt.minion      :976 ][INFO    ][5017] Creating minion process manager
2019-05-18 16:36:47,537 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][5017] Executing command ['date', '+%z'] in directory '/root'
2019-05-18 16:36:47,553 [salt.utils.schedule:568 ][INFO    ][5017] Updating job settings for scheduled job: __mine_interval
2019-05-18 16:36:47,554 [salt.minion      :1108][INFO    ][5017] Added mine.update to scheduler
2019-05-18 16:36:47,559 [salt.minion      :1975][INFO    ][5017] Minion is starting as user 'root'
2019-05-18 16:36:47,571 [salt.minion      :2336][INFO    ][5017] Minion is ready to receive requests!
2019-05-18 16:36:56,906 [salt.minion      :1308][INFO    ][5017] User sudo_ubuntu Executing command state.sls with jid 20190518163656898519
2019-05-18 16:36:56,925 [salt.minion      :1432][INFO    ][5105] Starting a new job with PID 5105
2019-05-18 16:36:58,030 [salt.state       :915 ][INFO    ][5105] Loading fresh modules for state activity
2019-05-18 16:36:58,082 [salt.fileclient  :1219][INFO    ][5105] Fetching file from saltenv 'base', ** done ** 'etcd/server/service.sls'
2019-05-18 16:36:58,144 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] Executing command '. /var/lib/etcd/configenv; etcdctl cluster-health > /dev/null 2>&1; echo $?' in directory '/root'
2019-05-18 16:36:58,156 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][5105] Command '. /var/lib/etcd/configenv; etcdctl cluster-health > /dev/null 2>&1; echo $?' failed with return code: 2
2019-05-18 16:36:58,157 [salt.loaded.int.module.cmdmod:732 ][ERROR   ][5105] stdout: /bin/sh: 1: .: Can't open /var/lib/etcd/configenv
2019-05-18 16:36:58,157 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][5105] retcode: 2
2019-05-18 16:36:58,835 [salt.state       :1780][INFO    ][5105] Running state [etcd_support_packages] at time 16:36:58.835804
2019-05-18 16:36:58,836 [salt.state       :1813][INFO    ][5105] Executing state pkg.installed for [etcd_support_packages]
2019-05-18 16:36:58,836 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-18 16:36:59,218 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] Executing command ['apt-cache', '-q', 'policy', 'python-etcd'] in directory '/root'
2019-05-18 16:36:59,296 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-05-18 16:37:01,618 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-05-18 16:37:01,653 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] 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-05-18 16:37:03,768 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-18 16:37:03,810 [salt.state       :300 ][INFO    ][5105] Made the following changes:
'python-etcd' changed from 'absent' to '0.4.2-2'

2019-05-18 16:37:03,827 [salt.state       :915 ][INFO    ][5105] Loading fresh modules for state activity
2019-05-18 16:37:03,868 [salt.state       :1951][INFO    ][5105] Completed state [etcd_support_packages] at time 16:37:03.868276 duration_in_ms=5032.473
2019-05-18 16:37:03,871 [salt.state       :1780][INFO    ][5105] Running state [/tmp/etcd/bin] at time 16:37:03.871355
2019-05-18 16:37:03,871 [salt.state       :1813][INFO    ][5105] Executing state archive.extracted for [/tmp/etcd/bin]
2019-05-18 16:37:06,423 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] 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-05-18 16:37:06,835 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] Executing command ['tar', '--version'] in directory '/root'
2019-05-18 16:37:06,852 [salt.state       :300 ][INFO    ][5105] {'extracted_files': 'no tar output so far', 'directories_created': ['/tmp/etcd/bin/']}
2019-05-18 16:37:06,853 [salt.state       :1951][INFO    ][5105] Completed state [/tmp/etcd/bin] at time 16:37:06.853233 duration_in_ms=2981.87
2019-05-18 16:37:06,854 [salt.state       :1780][INFO    ][5105] Running state [/usr/local/bin/etcd] at time 16:37:06.854239
2019-05-18 16:37:06,854 [salt.state       :1813][INFO    ][5105] Executing state file.managed for [/usr/local/bin/etcd]
2019-05-18 16:37:07,174 [salt.state       :300 ][INFO    ][5105] File changed:
New file
2019-05-18 16:37:07,175 [salt.state       :1951][INFO    ][5105] Completed state [/usr/local/bin/etcd] at time 16:37:07.175199 duration_in_ms=320.96
2019-05-18 16:37:07,175 [salt.state       :1780][INFO    ][5105] Running state [/usr/local/bin/etcdctl] at time 16:37:07.175636
2019-05-18 16:37:07,175 [salt.state       :1813][INFO    ][5105] Executing state file.managed for [/usr/local/bin/etcdctl]
2019-05-18 16:37:07,327 [salt.state       :300 ][INFO    ][5105] File changed:
New file
2019-05-18 16:37:07,327 [salt.state       :1951][INFO    ][5105] Completed state [/usr/local/bin/etcdctl] at time 16:37:07.327660 duration_in_ms=152.024
2019-05-18 16:37:07,329 [salt.state       :1780][INFO    ][5105] Running state [etcd] at time 16:37:07.329395
2019-05-18 16:37:07,329 [salt.state       :1813][INFO    ][5105] Executing state user.present for [etcd]
2019-05-18 16:37:07,339 [salt.state       :300 ][INFO    ][5105] User etcd is present and up to date
2019-05-18 16:37:07,339 [salt.state       :1951][INFO    ][5105] Completed state [etcd] at time 16:37:07.339579 duration_in_ms=10.184
2019-05-18 16:37:07,339 [salt.state       :1780][INFO    ][5105] Running state [/etc/systemd/system/etcd.service] at time 16:37:07.339816
2019-05-18 16:37:07,340 [salt.state       :1813][INFO    ][5105] Executing state file.managed for [/etc/systemd/system/etcd.service]
2019-05-18 16:37:07,364 [salt.fileclient  :1219][INFO    ][5105] Fetching file from saltenv 'base', ** done ** 'etcd/files/systemd/etcd.service'
2019-05-18 16:37:07,376 [salt.state       :300 ][INFO    ][5105] File changed:
New file
2019-05-18 16:37:07,376 [salt.state       :1951][INFO    ][5105] Completed state [/etc/systemd/system/etcd.service] at time 16:37:07.376539 duration_in_ms=36.723
2019-05-18 16:37:07,376 [salt.state       :1780][INFO    ][5105] Running state [/etc/default/etcd] at time 16:37:07.376771
2019-05-18 16:37:07,377 [salt.state       :1813][INFO    ][5105] Executing state file.managed for [/etc/default/etcd]
2019-05-18 16:37:07,401 [salt.fileclient  :1219][INFO    ][5105] Fetching file from saltenv 'base', ** done ** 'etcd/files/default'
2019-05-18 16:37:07,468 [salt.state       :300 ][INFO    ][5105] File changed:
New file
2019-05-18 16:37:07,468 [salt.state       :1951][INFO    ][5105] Completed state [/etc/default/etcd] at time 16:37:07.468607 duration_in_ms=91.836
2019-05-18 16:37:07,468 [salt.state       :1780][INFO    ][5105] Running state [/var/lib/etcd/] at time 16:37:07.468867
2019-05-18 16:37:07,469 [salt.state       :1813][INFO    ][5105] Executing state file.directory for [/var/lib/etcd/]
2019-05-18 16:37:07,473 [salt.state       :300 ][INFO    ][5105] Directory /var/lib/etcd is in the correct state
Directory /var/lib/etcd updated
2019-05-18 16:37:07,473 [salt.state       :1951][INFO    ][5105] Completed state [/var/lib/etcd/] at time 16:37:07.473799 duration_in_ms=4.933
2019-05-18 16:37:07,474 [salt.state       :1780][INFO    ][5105] Running state [/var/lib/etcd/configenv] at time 16:37:07.474334
2019-05-18 16:37:07,474 [salt.state       :1813][INFO    ][5105] Executing state file.managed for [/var/lib/etcd/configenv]
2019-05-18 16:37:07,496 [salt.fileclient  :1219][INFO    ][5105] Fetching file from saltenv 'base', ** done ** 'etcd/files/configenv'
2019-05-18 16:37:07,530 [salt.state       :300 ][INFO    ][5105] File changed:
New file
2019-05-18 16:37:07,530 [salt.state       :1951][INFO    ][5105] Completed state [/var/lib/etcd/configenv] at time 16:37:07.530796 duration_in_ms=56.462
2019-05-18 16:37:07,531 [salt.state       :1780][INFO    ][5105] Running state [/var/lib/etcd/configenvv3] at time 16:37:07.531136
2019-05-18 16:37:07,531 [salt.state       :1813][INFO    ][5105] Executing state file.managed for [/var/lib/etcd/configenvv3]
2019-05-18 16:37:07,552 [salt.fileclient  :1219][INFO    ][5105] Fetching file from saltenv 'base', ** done ** 'etcd/files/configenvv3'
2019-05-18 16:37:07,582 [salt.state       :300 ][INFO    ][5105] File changed:
New file
2019-05-18 16:37:07,582 [salt.state       :1951][INFO    ][5105] Completed state [/var/lib/etcd/configenvv3] at time 16:37:07.582310 duration_in_ms=51.174
2019-05-18 16:37:08,060 [salt.state       :1780][INFO    ][5105] Running state [etcd] at time 16:37:08.060307
2019-05-18 16:37:08,060 [salt.state       :1813][INFO    ][5105] Executing state service.running for [etcd]
2019-05-18 16:37:08,061 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] Executing command ['systemctl', 'status', 'etcd.service', '-n', '0'] in directory '/root'
2019-05-18 16:37:08,118 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] Executing command ['systemctl', 'is-active', 'etcd.service'] in directory '/root'
2019-05-18 16:37:08,139 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] Executing command ['systemctl', 'is-enabled', 'etcd.service'] in directory '/root'
2019-05-18 16:37:08,162 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'etcd.service'] in directory '/root'
2019-05-18 16:37:08,730 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] Executing command ['systemctl', 'is-active', 'etcd.service'] in directory '/root'
2019-05-18 16:37:08,746 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] Executing command ['systemctl', 'is-enabled', 'etcd.service'] in directory '/root'
2019-05-18 16:37:08,762 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] Executing command ['systemctl', 'is-enabled', 'etcd.service'] in directory '/root'
2019-05-18 16:37:08,784 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'etcd.service'] in directory '/root'
2019-05-18 16:37:08,910 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] Executing command ['systemctl', 'is-enabled', 'etcd.service'] in directory '/root'
2019-05-18 16:37:08,927 [salt.state       :300 ][INFO    ][5105] {'etcd': True}
2019-05-18 16:37:08,927 [salt.state       :1951][INFO    ][5105] Completed state [etcd] at time 16:37:08.927694 duration_in_ms=867.388
2019-05-18 16:37:08,929 [salt.minion      :1711][INFO    ][5105] Returning information for job: 20190518163656898519
2019-05-18 16:37:09,623 [salt.minion      :1308][INFO    ][5017] User sudo_ubuntu Executing command cmd.run with jid 20190518163709613169
2019-05-18 16:37:09,645 [salt.minion      :1432][INFO    ][5761] Starting a new job with PID 5761
2019-05-18 16:37:09,655 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][5761] Executing command '. /var/lib/etcd/configenv && etcdctl cluster-health' in directory '/root'
2019-05-18 16:37:09,815 [salt.minion      :1711][INFO    ][5761] Returning information for job: 20190518163709613169
2019-05-18 16:37:10,399 [salt.minion      :1308][INFO    ][5017] User sudo_ubuntu Executing command state.sls with jid 20190518163710380922
2019-05-18 16:37:10,419 [salt.minion      :1432][INFO    ][5777] Starting a new job with PID 5777
2019-05-18 16:37:16,869 [salt.state       :915 ][INFO    ][5777] Loading fresh modules for state activity
2019-05-18 16:37:16,933 [salt.fileclient  :1219][INFO    ][5777] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/kube-addons.sls'
2019-05-18 16:37:17,062 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:17,076 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:17,095 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:17,108 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:17,124 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:17,138 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:17,205 [salt.state       :1780][INFO    ][5777] Running state [/etc/kubernetes/addons] at time 16:37:17.205533
2019-05-18 16:37:17,205 [salt.state       :1813][INFO    ][5777] Executing state file.directory for [/etc/kubernetes/addons]
2019-05-18 16:37:17,211 [salt.state       :300 ][INFO    ][5777] {'/etc/kubernetes/addons': 'New Dir'}
2019-05-18 16:37:17,212 [salt.state       :1951][INFO    ][5777] Completed state [/etc/kubernetes/addons] at time 16:37:17.211971 duration_in_ms=6.438
2019-05-18 16:37:17,212 [salt.state       :1780][INFO    ][5777] Running state [/etc/kubernetes/addons/calico/calico-kube-controllers.yml] at time 16:37:17.212230
2019-05-18 16:37:17,212 [salt.state       :1813][INFO    ][5777] Executing state file.managed for [/etc/kubernetes/addons/calico/calico-kube-controllers.yml]
2019-05-18 16:37:17,241 [salt.fileclient  :1219][INFO    ][5777] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/calico/calico-kube-controllers.yml'
2019-05-18 16:37:17,294 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:17,307 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:17,326 [salt.state       :300 ][INFO    ][5777] File changed:
New file
2019-05-18 16:37:17,327 [salt.state       :1951][INFO    ][5777] Completed state [/etc/kubernetes/addons/calico/calico-kube-controllers.yml] at time 16:37:17.327067 duration_in_ms=114.836
2019-05-18 16:37:17,327 [salt.state       :1780][INFO    ][5777] Running state [/etc/kubernetes/addons/calico/calico-rbac.yml] at time 16:37:17.327511
2019-05-18 16:37:17,327 [salt.state       :1813][INFO    ][5777] Executing state file.managed for [/etc/kubernetes/addons/calico/calico-rbac.yml]
2019-05-18 16:37:17,350 [salt.fileclient  :1219][INFO    ][5777] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/calico/calico-rbac.yml'
2019-05-18 16:37:17,376 [salt.state       :300 ][INFO    ][5777] File changed:
New file
2019-05-18 16:37:17,376 [salt.state       :1951][INFO    ][5777] Completed state [/etc/kubernetes/addons/calico/calico-rbac.yml] at time 16:37:17.376392 duration_in_ms=48.88
2019-05-18 16:37:17,376 [salt.state       :1780][INFO    ][5777] Running state [/etc/kubernetes/addons/netchecker/netchecker-svc.yml] at time 16:37:17.376756
2019-05-18 16:37:17,377 [salt.state       :1813][INFO    ][5777] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-svc.yml]
2019-05-18 16:37:17,401 [salt.fileclient  :1219][INFO    ][5777] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-svc.yml'
2019-05-18 16:37:17,447 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:17,461 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:17,479 [salt.state       :300 ][INFO    ][5777] File changed:
New file
2019-05-18 16:37:17,480 [salt.state       :1951][INFO    ][5777] Completed state [/etc/kubernetes/addons/netchecker/netchecker-svc.yml] at time 16:37:17.479926 duration_in_ms=103.169
2019-05-18 16:37:17,480 [salt.state       :1780][INFO    ][5777] Running state [/etc/kubernetes/addons/netchecker/netchecker-server.yml] at time 16:37:17.480417
2019-05-18 16:37:17,480 [salt.state       :1813][INFO    ][5777] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-server.yml]
2019-05-18 16:37:17,500 [salt.fileclient  :1219][INFO    ][5777] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-server.yml'
2019-05-18 16:37:17,557 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:17,570 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:17,585 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:17,598 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:17,614 [salt.state       :300 ][INFO    ][5777] File changed:
New file
2019-05-18 16:37:17,614 [salt.state       :1951][INFO    ][5777] Completed state [/etc/kubernetes/addons/netchecker/netchecker-server.yml] at time 16:37:17.614822 duration_in_ms=134.406
2019-05-18 16:37:17,615 [salt.state       :1780][INFO    ][5777] Running state [/etc/kubernetes/addons/netchecker/netchecker-agent.yml] at time 16:37:17.615114
2019-05-18 16:37:17,615 [salt.state       :1813][INFO    ][5777] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-agent.yml]
2019-05-18 16:37:17,636 [salt.fileclient  :1219][INFO    ][5777] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-agent.yml'
2019-05-18 16:37:17,690 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:17,703 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:17,719 [salt.state       :300 ][INFO    ][5777] File changed:
New file
2019-05-18 16:37:17,719 [salt.state       :1951][INFO    ][5777] Completed state [/etc/kubernetes/addons/netchecker/netchecker-agent.yml] at time 16:37:17.719790 duration_in_ms=104.676
2019-05-18 16:37:17,720 [salt.state       :1780][INFO    ][5777] Running state [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml] at time 16:37:17.720079
2019-05-18 16:37:17,720 [salt.state       :1813][INFO    ][5777] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml]
2019-05-18 16:37:17,739 [salt.fileclient  :1219][INFO    ][5777] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-serviceaccount.yml'
2019-05-18 16:37:17,747 [salt.state       :300 ][INFO    ][5777] File changed:
New file
2019-05-18 16:37:17,748 [salt.state       :1951][INFO    ][5777] Completed state [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml] at time 16:37:17.748142 duration_in_ms=28.063
2019-05-18 16:37:17,748 [salt.state       :1780][INFO    ][5777] Running state [/etc/kubernetes/addons/netchecker/netchecker-roles.yml] at time 16:37:17.748405
2019-05-18 16:37:17,748 [salt.state       :1813][INFO    ][5777] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-roles.yml]
2019-05-18 16:37:17,766 [salt.fileclient  :1219][INFO    ][5777] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/netchecker/netchecker-roles.yml'
2019-05-18 16:37:17,777 [salt.state       :300 ][INFO    ][5777] File changed:
New file
2019-05-18 16:37:17,777 [salt.state       :1951][INFO    ][5777] Completed state [/etc/kubernetes/addons/netchecker/netchecker-roles.yml] at time 16:37:17.777538 duration_in_ms=29.132
2019-05-18 16:37:17,777 [salt.state       :1780][INFO    ][5777] Running state [/etc/kubernetes/addons/prometheus/prometheus-roles.yml] at time 16:37:17.777858
2019-05-18 16:37:17,778 [salt.state       :1813][INFO    ][5777] Executing state file.managed for [/etc/kubernetes/addons/prometheus/prometheus-roles.yml]
2019-05-18 16:37:17,799 [salt.fileclient  :1219][INFO    ][5777] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/prometheus/prometheus-roles.yml'
2019-05-18 16:37:17,808 [salt.state       :300 ][INFO    ][5777] File changed:
New file
2019-05-18 16:37:17,809 [salt.state       :1951][INFO    ][5777] Completed state [/etc/kubernetes/addons/prometheus/prometheus-roles.yml] at time 16:37:17.809019 duration_in_ms=31.162
2019-05-18 16:37:17,809 [salt.state       :1780][INFO    ][5777] Running state [/etc/kubernetes/addons/coredns] at time 16:37:17.809282
2019-05-18 16:37:17,809 [salt.state       :1813][INFO    ][5777] Executing state file.absent for [/etc/kubernetes/addons/coredns]
2019-05-18 16:37:17,809 [salt.state       :300 ][INFO    ][5777] File /etc/kubernetes/addons/coredns is not present
2019-05-18 16:37:17,810 [salt.state       :1951][INFO    ][5777] Completed state [/etc/kubernetes/addons/coredns] at time 16:37:17.810176 duration_in_ms=0.894
2019-05-18 16:37:17,811 [salt.state       :1780][INFO    ][5777] Running state [kubectl -n kube-system delete svc coredns > /dev/null || echo "coredns is absent. OK" && true] at time 16:37:17.811231
2019-05-18 16:37:17,811 [salt.state       :1813][INFO    ][5777] Executing state cmd.run for [kubectl -n kube-system delete svc coredns > /dev/null || echo "coredns is absent. OK" && true]
2019-05-18 16:37:17,812 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command 'kubectl -n kube-system delete svc coredns > /dev/null || echo "coredns is absent. OK" && true' in directory '/root'
2019-05-18 16:37:17,824 [salt.state       :300 ][INFO    ][5777] {'pid': 5858, 'retcode': 0, 'stderr': '/bin/sh: 1: kubectl: not found', 'stdout': 'coredns is absent. OK'}
2019-05-18 16:37:17,824 [salt.state       :1951][INFO    ][5777] Completed state [kubectl -n kube-system delete svc coredns > /dev/null || echo "coredns is absent. OK" && true] at time 16:37:17.824375 duration_in_ms=13.144
2019-05-18 16:37:17,824 [salt.state       :1780][INFO    ][5777] Running state [/etc/kubernetes/addons/dns/kubedns-svc.yaml] at time 16:37:17.824699
2019-05-18 16:37:17,825 [salt.state       :1813][INFO    ][5777] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-svc.yaml]
2019-05-18 16:37:17,847 [salt.fileclient  :1219][INFO    ][5777] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-svc.yaml'
2019-05-18 16:37:17,895 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:17,912 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:17,931 [salt.state       :300 ][INFO    ][5777] File changed:
New file
2019-05-18 16:37:17,932 [salt.state       :1951][INFO    ][5777] Completed state [/etc/kubernetes/addons/dns/kubedns-svc.yaml] at time 16:37:17.932141 duration_in_ms=107.441
2019-05-18 16:37:17,932 [salt.state       :1780][INFO    ][5777] Running state [/etc/kubernetes/addons/dns/kubedns-rc.yaml] at time 16:37:17.932629
2019-05-18 16:37:17,933 [salt.state       :1813][INFO    ][5777] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-rc.yaml]
2019-05-18 16:37:17,955 [salt.fileclient  :1219][INFO    ][5777] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-rc.yaml'
2019-05-18 16:37:18,017 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:18,059 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:18,080 [salt.state       :300 ][INFO    ][5777] File changed:
New file
2019-05-18 16:37:18,081 [salt.state       :1951][INFO    ][5777] Completed state [/etc/kubernetes/addons/dns/kubedns-rc.yaml] at time 16:37:18.081229 duration_in_ms=148.6
2019-05-18 16:37:18,081 [salt.state       :1780][INFO    ][5777] Running state [/etc/kubernetes/addons/dns/kubedns-sa.yaml] at time 16:37:18.081592
2019-05-18 16:37:18,082 [salt.state       :1813][INFO    ][5777] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-sa.yaml]
2019-05-18 16:37:18,101 [salt.fileclient  :1219][INFO    ][5777] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-sa.yaml'
2019-05-18 16:37:18,110 [salt.state       :300 ][INFO    ][5777] File changed:
New file
2019-05-18 16:37:18,111 [salt.state       :1951][INFO    ][5777] Completed state [/etc/kubernetes/addons/dns/kubedns-sa.yaml] at time 16:37:18.111043 duration_in_ms=29.451
2019-05-18 16:37:18,111 [salt.state       :1780][INFO    ][5777] Running state [/etc/kubernetes/addons/dns/kubedns-autoscaler.yaml] at time 16:37:18.111286
2019-05-18 16:37:18,111 [salt.state       :1813][INFO    ][5777] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-autoscaler.yaml]
2019-05-18 16:37:18,129 [salt.fileclient  :1219][INFO    ][5777] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-autoscaler.yaml'
2019-05-18 16:37:18,191 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:18,204 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5777] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:18,219 [salt.state       :300 ][INFO    ][5777] File changed:
New file
2019-05-18 16:37:18,219 [salt.state       :1951][INFO    ][5777] Completed state [/etc/kubernetes/addons/dns/kubedns-autoscaler.yaml] at time 16:37:18.219442 duration_in_ms=108.155
2019-05-18 16:37:18,219 [salt.state       :1780][INFO    ][5777] Running state [/etc/kubernetes/addons/dns/kubedns-autoscaler-rbac.yaml] at time 16:37:18.219738
2019-05-18 16:37:18,220 [salt.state       :1813][INFO    ][5777] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-autoscaler-rbac.yaml]
2019-05-18 16:37:18,240 [salt.fileclient  :1219][INFO    ][5777] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-autoscaler-rbac.yaml'
2019-05-18 16:37:18,251 [salt.state       :300 ][INFO    ][5777] File changed:
New file
2019-05-18 16:37:18,252 [salt.state       :1951][INFO    ][5777] Completed state [/etc/kubernetes/addons/dns/kubedns-autoscaler-rbac.yaml] at time 16:37:18.252156 duration_in_ms=32.417
2019-05-18 16:37:18,252 [salt.state       :1780][INFO    ][5777] Running state [/etc/kubernetes/addons/dns/kubedns-clusterrole.yaml] at time 16:37:18.252493
2019-05-18 16:37:18,252 [salt.state       :1813][INFO    ][5777] Executing state file.managed for [/etc/kubernetes/addons/dns/kubedns-clusterrole.yaml]
2019-05-18 16:37:18,271 [salt.fileclient  :1219][INFO    ][5777] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/dns/kubedns-clusterrole.yaml'
2019-05-18 16:37:18,281 [salt.state       :300 ][INFO    ][5777] File changed:
New file
2019-05-18 16:37:18,281 [salt.state       :1951][INFO    ][5777] Completed state [/etc/kubernetes/addons/dns/kubedns-clusterrole.yaml] at time 16:37:18.281689 duration_in_ms=29.196
2019-05-18 16:37:18,281 [salt.state       :1780][INFO    ][5777] Running state [/etc/kubernetes/addons/metrics-server] at time 16:37:18.281923
2019-05-18 16:37:18,282 [salt.state       :1813][INFO    ][5777] Executing state file.absent for [/etc/kubernetes/addons/metrics-server]
2019-05-18 16:37:18,282 [salt.state       :300 ][INFO    ][5777] File /etc/kubernetes/addons/metrics-server is not present
2019-05-18 16:37:18,282 [salt.state       :1951][INFO    ][5777] Completed state [/etc/kubernetes/addons/metrics-server] at time 16:37:18.282708 duration_in_ms=0.785
2019-05-18 16:37:18,285 [salt.minion      :1711][INFO    ][5777] Returning information for job: 20190518163710380922
2019-05-18 16:37:18,886 [salt.minion      :1308][INFO    ][5017] User sudo_ubuntu Executing command state.sls with jid 20190518163718874370
2019-05-18 16:37:18,902 [salt.minion      :1432][INFO    ][5896] Starting a new job with PID 5896
2019-05-18 16:37:20,018 [salt.state       :915 ][INFO    ][5896] Loading fresh modules for state activity
2019-05-18 16:37:20,068 [salt.fileclient  :1219][INFO    ][5896] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/init.sls'
2019-05-18 16:37:20,127 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:20,141 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:20,156 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:20,166 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:20,192 [salt.fileclient  :1219][INFO    ][5896] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/calico.sls'
2019-05-18 16:37:20,251 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:20,267 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:20,279 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:20,289 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:20,345 [salt.fileclient  :1219][INFO    ][5896] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/service.sls'
2019-05-18 16:37:20,391 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:20,404 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:20,419 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:20,430 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:20,459 [salt.fileclient  :1219][INFO    ][5896] Fetching file from saltenv 'base', ** done ** 'kubernetes/_common.sls'
2019-05-18 16:37:20,538 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:20,553 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:20,611 [salt.fileclient  :1219][INFO    ][5896] Fetching file from saltenv 'base', ** done ** 'kubernetes/pool/kube-proxy.sls'
2019-05-18 16:37:20,679 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:20,693 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:20,726 [salt.state       :1780][INFO    ][5896] Running state [/usr/bin/calicoctl] at time 16:37:20.726897
2019-05-18 16:37:20,727 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/usr/bin/calicoctl]
2019-05-18 16:37:29,126 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:37:29,126 [salt.state       :1951][INFO    ][5896] Completed state [/usr/bin/calicoctl] at time 16:37:29.126805 duration_in_ms=8399.908
2019-05-18 16:37:29,127 [salt.state       :1780][INFO    ][5896] Running state [/usr/bin/birdcl] at time 16:37:29.127077
2019-05-18 16:37:29,127 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/usr/bin/birdcl]
2019-05-18 16:37:29,474 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:37:29,474 [salt.state       :1951][INFO    ][5896] Completed state [/usr/bin/birdcl] at time 16:37:29.474271 duration_in_ms=347.193
2019-05-18 16:37:29,474 [salt.state       :1780][INFO    ][5896] Running state [/opt/cni/bin/calico] at time 16:37:29.474531
2019-05-18 16:37:29,474 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/opt/cni/bin/calico]
2019-05-18 16:37:33,945 [salt.minion      :1308][INFO    ][5017] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518163733934997
2019-05-18 16:37:33,962 [salt.minion      :1432][INFO    ][5972] Starting a new job with PID 5972
2019-05-18 16:37:33,981 [salt.minion      :1711][INFO    ][5972] Returning information for job: 20190518163733934997
2019-05-18 16:37:36,991 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:37:36,991 [salt.state       :1951][INFO    ][5896] Completed state [/opt/cni/bin/calico] at time 16:37:36.991334 duration_in_ms=7516.801
2019-05-18 16:37:36,991 [salt.state       :1780][INFO    ][5896] Running state [/opt/cni/bin/calico-ipam] at time 16:37:36.991794
2019-05-18 16:37:36,992 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/opt/cni/bin/calico-ipam]
2019-05-18 16:37:44,249 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:37:44,249 [salt.state       :1951][INFO    ][5896] Completed state [/opt/cni/bin/calico-ipam] at time 16:37:44.249616 duration_in_ms=7257.822
2019-05-18 16:37:44,249 [salt.state       :1780][INFO    ][5896] Running state [/etc/cni/net.d/10-calico.conf] at time 16:37:44.249886
2019-05-18 16:37:44,250 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/etc/cni/net.d/10-calico.conf]
2019-05-18 16:37:44,267 [salt.fileclient  :1219][INFO    ][5896] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calico.conf'
2019-05-18 16:37:44,321 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:44,331 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:44,342 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:44,351 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:44,368 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:37:44,368 [salt.state       :1951][INFO    ][5896] Completed state [/etc/cni/net.d/10-calico.conf] at time 16:37:44.368503 duration_in_ms=118.616
2019-05-18 16:37:44,368 [salt.state       :1780][INFO    ][5896] Running state [/etc/calico/network-environment] at time 16:37:44.368836
2019-05-18 16:37:44,369 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/etc/calico/network-environment]
2019-05-18 16:37:44,387 [salt.fileclient  :1219][INFO    ][5896] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/network-environment.pool'
2019-05-18 16:37:44,436 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:44,446 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:44,459 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:37:44,459 [salt.state       :1951][INFO    ][5896] Completed state [/etc/calico/network-environment] at time 16:37:44.459415 duration_in_ms=90.58
2019-05-18 16:37:44,459 [salt.state       :1780][INFO    ][5896] Running state [/etc/calico/calicoctl.cfg] at time 16:37:44.459705
2019-05-18 16:37:44,460 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/etc/calico/calicoctl.cfg]
2019-05-18 16:37:44,474 [salt.fileclient  :1219][INFO    ][5896] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calicoctl.cfg.pool'
2019-05-18 16:37:44,524 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:44,534 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:44,546 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:37:44,547 [salt.state       :1951][INFO    ][5896] Completed state [/etc/calico/calicoctl.cfg] at time 16:37:44.547209 duration_in_ms=87.504
2019-05-18 16:37:45,208 [salt.state       :1780][INFO    ][5896] Running state [containerd=1.2.5-3~u16.04+mcp] at time 16:37:45.208868
2019-05-18 16:37:45,209 [salt.state       :1813][INFO    ][5896] Executing state pkg.installed for [containerd=1.2.5-3~u16.04+mcp]
2019-05-18 16:37:45,209 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-18 16:37:45,583 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['apt-cache', '-q', 'policy', 'containerd=1.2.5-3~u16.04+mcp'] in directory '/root'
2019-05-18 16:37:45,683 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-05-18 16:37:47,839 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-05-18 16:37:47,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'containerd=1.2.5-3~u16.04+mcp'] in directory '/root'
2019-05-18 16:37:55,933 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-18 16:37:55,970 [salt.state       :300 ][INFO    ][5896] Made the following changes:
'containerd' changed from 'absent' to '1.2.5-3~u16.04+mcp'
'runc' changed from 'absent' to '1.0.0~rc7-2~u16.04+mcp'

2019-05-18 16:37:55,987 [salt.state       :915 ][INFO    ][5896] Loading fresh modules for state activity
2019-05-18 16:37:56,034 [salt.state       :1951][INFO    ][5896] Completed state [containerd=1.2.5-3~u16.04+mcp] at time 16:37:56.034750 duration_in_ms=10825.882
2019-05-18 16:37:56,041 [salt.state       :1780][INFO    ][5896] Running state [/etc/containerd/config.toml] at time 16:37:56.041260
2019-05-18 16:37:56,041 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/etc/containerd/config.toml]
2019-05-18 16:37:56,066 [salt.fileclient  :1219][INFO    ][5896] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/containerd/config.toml'
2019-05-18 16:37:56,117 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:37:56,127 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:37:56,146 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:37:56,147 [salt.state       :1951][INFO    ][5896] Completed state [/etc/containerd/config.toml] at time 16:37:56.147102 duration_in_ms=105.842
2019-05-18 16:37:56,593 [salt.state       :1780][INFO    ][5896] Running state [containerd] at time 16:37:56.593620
2019-05-18 16:37:56,594 [salt.state       :1813][INFO    ][5896] Executing state service.running for [containerd]
2019-05-18 16:37:56,594 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'status', 'containerd.service', '-n', '0'] in directory '/root'
2019-05-18 16:37:56,607 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-05-18 16:37:56,617 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-enabled', 'containerd.service'] in directory '/root'
2019-05-18 16:37:56,627 [salt.state       :300 ][INFO    ][5896] The service containerd is already running
2019-05-18 16:37:56,628 [salt.state       :1951][INFO    ][5896] Completed state [containerd] at time 16:37:56.628187 duration_in_ms=34.567
2019-05-18 16:37:56,628 [salt.state       :1780][INFO    ][5896] Running state [containerd] at time 16:37:56.628449
2019-05-18 16:37:56,628 [salt.state       :1813][INFO    ][5896] Executing state service.mod_watch for [containerd]
2019-05-18 16:37:56,629 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-05-18 16:37:56,639 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'containerd.service'] in directory '/root'
2019-05-18 16:37:56,664 [salt.state       :300 ][INFO    ][5896] {'containerd': True}
2019-05-18 16:37:56,664 [salt.state       :1951][INFO    ][5896] Completed state [containerd] at time 16:37:56.664615 duration_in_ms=36.166
2019-05-18 16:37:56,665 [salt.state       :1780][INFO    ][5896] Running state [/etc/systemd/system/calico-node.service] at time 16:37:56.665372
2019-05-18 16:37:56,665 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/etc/systemd/system/calico-node.service]
2019-05-18 16:37:56,683 [salt.fileclient  :1219][INFO    ][5896] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/calico/calico-node.service.ctr'
2019-05-18 16:37:56,705 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:37:56,705 [salt.state       :1951][INFO    ][5896] Completed state [/etc/systemd/system/calico-node.service] at time 16:37:56.705665 duration_in_ms=40.291
2019-05-18 16:37:56,705 [salt.state       :1780][INFO    ][5896] Running state [/var/lib/calico] at time 16:37:56.705928
2019-05-18 16:37:56,706 [salt.state       :1813][INFO    ][5896] Executing state file.directory for [/var/lib/calico]
2019-05-18 16:37:56,707 [salt.state       :300 ][INFO    ][5896] {'/var/lib/calico': 'New Dir'}
2019-05-18 16:37:56,707 [salt.state       :1951][INFO    ][5896] Completed state [/var/lib/calico] at time 16:37:56.707580 duration_in_ms=1.651
2019-05-18 16:37:56,707 [salt.state       :1780][INFO    ][5896] Running state [/var/log/calico] at time 16:37:56.707802
2019-05-18 16:37:56,708 [salt.state       :1813][INFO    ][5896] Executing state file.directory for [/var/log/calico]
2019-05-18 16:37:56,709 [salt.state       :300 ][INFO    ][5896] {'/var/log/calico': 'New Dir'}
2019-05-18 16:37:56,709 [salt.state       :1951][INFO    ][5896] Completed state [/var/log/calico] at time 16:37:56.709355 duration_in_ms=1.553
2019-05-18 16:37:56,711 [salt.state       :1780][INFO    ][5896] Running state [calico-node] at time 16:37:56.711516
2019-05-18 16:37:56,711 [salt.state       :1813][INFO    ][5896] Executing state service.running for [calico-node]
2019-05-18 16:37:56,712 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'status', 'calico-node.service', '-n', '0'] in directory '/root'
2019-05-18 16:37:56,726 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-05-18 16:37:56,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-05-18 16:37:56,757 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'calico-node.service'] in directory '/root'
2019-05-18 16:38:04,170 [salt.minion      :1308][INFO    ][5017] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518163804157635
2019-05-18 16:38:04,188 [salt.minion      :1432][INFO    ][7086] Starting a new job with PID 7086
2019-05-18 16:38:04,204 [salt.minion      :1711][INFO    ][7086] Returning information for job: 20190518163804157635
2019-05-18 16:38:05,623 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-05-18 16:38:05,636 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-05-18 16:38:05,659 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-05-18 16:38:05,680 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'calico-node.service'] in directory '/root'
2019-05-18 16:38:05,782 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-05-18 16:38:05,796 [salt.state       :300 ][INFO    ][5896] {'calico-node': True}
2019-05-18 16:38:05,796 [salt.state       :1951][INFO    ][5896] Completed state [calico-node] at time 16:38:05.796498 duration_in_ms=9084.981
2019-05-18 16:38:05,798 [salt.state       :1780][INFO    ][5896] Running state [curl] at time 16:38:05.798324
2019-05-18 16:38:05,798 [salt.state       :1813][INFO    ][5896] Executing state pkg.installed for [curl]
2019-05-18 16:38:06,030 [salt.state       :300 ][INFO    ][5896] All specified packages are already installed
2019-05-18 16:38:06,030 [salt.state       :1951][INFO    ][5896] Completed state [curl] at time 16:38:06.030413 duration_in_ms=232.088
2019-05-18 16:38:06,030 [salt.state       :1780][INFO    ][5896] Running state [git] at time 16:38:06.030730
2019-05-18 16:38:06,031 [salt.state       :1813][INFO    ][5896] Executing state pkg.installed for [git]
2019-05-18 16:38:06,040 [salt.state       :300 ][INFO    ][5896] All specified packages are already installed
2019-05-18 16:38:06,040 [salt.state       :1951][INFO    ][5896] Completed state [git] at time 16:38:06.040296 duration_in_ms=9.565
2019-05-18 16:38:06,040 [salt.state       :1780][INFO    ][5896] Running state [apt-transport-https] at time 16:38:06.040535
2019-05-18 16:38:06,040 [salt.state       :1813][INFO    ][5896] Executing state pkg.installed for [apt-transport-https]
2019-05-18 16:38:06,052 [salt.state       :300 ][INFO    ][5896] All specified packages are already installed
2019-05-18 16:38:06,052 [salt.state       :1951][INFO    ][5896] Completed state [apt-transport-https] at time 16:38:06.052504 duration_in_ms=11.969
2019-05-18 16:38:06,052 [salt.state       :1780][INFO    ][5896] Running state [python-apt] at time 16:38:06.052749
2019-05-18 16:38:06,053 [salt.state       :1813][INFO    ][5896] Executing state pkg.installed for [python-apt]
2019-05-18 16:38:06,061 [salt.state       :300 ][INFO    ][5896] All specified packages are already installed
2019-05-18 16:38:06,062 [salt.state       :1951][INFO    ][5896] Completed state [python-apt] at time 16:38:06.062103 duration_in_ms=9.354
2019-05-18 16:38:06,062 [salt.state       :1780][INFO    ][5896] Running state [socat] at time 16:38:06.062352
2019-05-18 16:38:06,062 [salt.state       :1813][INFO    ][5896] Executing state pkg.installed for [socat]
2019-05-18 16:38:06,088 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-05-18 16:38:06,112 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'socat'] in directory '/root'
2019-05-18 16:38:08,480 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-18 16:38:08,512 [salt.state       :300 ][INFO    ][5896] Made the following changes:
'socat' changed from 'absent' to '1.7.3.1-1'

2019-05-18 16:38:08,526 [salt.state       :915 ][INFO    ][5896] Loading fresh modules for state activity
2019-05-18 16:38:08,572 [salt.state       :1951][INFO    ][5896] Completed state [socat] at time 16:38:08.571952 duration_in_ms=2509.599
2019-05-18 16:38:08,578 [salt.state       :1780][INFO    ][5896] Running state [openssl] at time 16:38:08.578113
2019-05-18 16:38:08,578 [salt.state       :1813][INFO    ][5896] Executing state pkg.installed for [openssl]
2019-05-18 16:38:09,310 [salt.state       :300 ][INFO    ][5896] All specified packages are already installed
2019-05-18 16:38:09,311 [salt.state       :1951][INFO    ][5896] Completed state [openssl] at time 16:38:09.311256 duration_in_ms=733.142
2019-05-18 16:38:09,311 [salt.state       :1780][INFO    ][5896] Running state [conntrack] at time 16:38:09.311596
2019-05-18 16:38:09,311 [salt.state       :1813][INFO    ][5896] Executing state pkg.installed for [conntrack]
2019-05-18 16:38:09,338 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-05-18 16:38:09,359 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'conntrack'] in directory '/root'
2019-05-18 16:38:11,456 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-18 16:38:11,490 [salt.state       :300 ][INFO    ][5896] Made the following changes:
'conntrack' changed from 'absent' to '1:1.4.3-3'

2019-05-18 16:38:11,506 [salt.state       :915 ][INFO    ][5896] Loading fresh modules for state activity
2019-05-18 16:38:11,557 [salt.state       :1951][INFO    ][5896] Completed state [conntrack] at time 16:38:11.557457 duration_in_ms=2245.859
2019-05-18 16:38:11,562 [salt.state       :1780][INFO    ][5896] Running state [/usr/bin/hyperkube] at time 16:38:11.562433
2019-05-18 16:38:11,562 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/usr/bin/hyperkube]
2019-05-18 16:38:34,248 [salt.minion      :1308][INFO    ][5017] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518163834237263
2019-05-18 16:38:34,263 [salt.minion      :1432][INFO    ][8203] Starting a new job with PID 8203
2019-05-18 16:38:34,284 [salt.minion      :1711][INFO    ][8203] Returning information for job: 20190518163834237263
2019-05-18 16:38:43,565 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:38:43,566 [salt.state       :1951][INFO    ][5896] Completed state [/usr/bin/hyperkube] at time 16:38:43.566390 duration_in_ms=32003.956
2019-05-18 16:38:43,567 [salt.state       :1780][INFO    ][5896] Running state [/usr/bin/kubectl] at time 16:38:43.567323
2019-05-18 16:38:43,567 [salt.state       :1813][INFO    ][5896] Executing state file.symlink for [/usr/bin/kubectl]
2019-05-18 16:38:43,575 [salt.state       :300 ][INFO    ][5896] {'new': '/usr/bin/kubectl'}
2019-05-18 16:38:43,586 [salt.state       :915 ][INFO    ][5896] Loading fresh modules for state activity
2019-05-18 16:38:43,626 [salt.state       :1951][INFO    ][5896] Completed state [/usr/bin/kubectl] at time 16:38:43.626283 duration_in_ms=58.959
2019-05-18 16:38:43,630 [salt.state       :1780][INFO    ][5896] Running state [/tmp/crictl] at time 16:38:43.630941
2019-05-18 16:38:43,631 [salt.state       :1813][INFO    ][5896] Executing state archive.extracted for [/tmp/crictl]
2019-05-18 16:38:47,857 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['tar', 'xz', '-f', '/var/cache/salt/minion/extrn_files/base/github.com/kubernetes-sigs/cri-tools/releases/download/v1.12.0/crictl-v1.12.0-linux-amd64.tar.gz'] in directory '/tmp/crictl/'
2019-05-18 16:38:48,105 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['tar', '--version'] in directory '/root'
2019-05-18 16:38:48,117 [salt.state       :300 ][INFO    ][5896] {'extracted_files': 'no tar output so far', 'directories_created': ['/tmp/crictl/']}
2019-05-18 16:38:48,118 [salt.state       :1951][INFO    ][5896] Completed state [/tmp/crictl] at time 16:38:48.118177 duration_in_ms=4487.229
2019-05-18 16:38:48,119 [salt.state       :1780][INFO    ][5896] Running state [/usr/local/bin/crictl] at time 16:38:48.119345
2019-05-18 16:38:48,119 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/usr/local/bin/crictl]
2019-05-18 16:38:48,120 [salt.loaded.int.states.file:1019][WARNING ][5896] Use of argument owner found, "owner" is invalid, please use "user"
2019-05-18 16:38:48,405 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:38:48,405 [salt.state       :1951][INFO    ][5896] Completed state [/usr/local/bin/crictl] at time 16:38:48.405744 duration_in_ms=286.399
2019-05-18 16:38:48,406 [salt.state       :1780][INFO    ][5896] Running state [/etc/crictl.yaml] at time 16:38:48.406018
2019-05-18 16:38:48,406 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/etc/crictl.yaml]
2019-05-18 16:38:48,434 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:38:48,434 [salt.state       :1951][INFO    ][5896] Completed state [/etc/crictl.yaml] at time 16:38:48.434648 duration_in_ms=28.629
2019-05-18 16:38:48,435 [salt.state       :1780][INFO    ][5896] Running state [/etc/criproxy] at time 16:38:48.434978
2019-05-18 16:38:48,435 [salt.state       :1813][INFO    ][5896] Executing state file.absent for [/etc/criproxy]
2019-05-18 16:38:48,435 [salt.state       :300 ][INFO    ][5896] File /etc/criproxy is not present
2019-05-18 16:38:48,436 [salt.state       :1951][INFO    ][5896] Completed state [/etc/criproxy] at time 16:38:48.436087 duration_in_ms=1.108
2019-05-18 16:38:48,919 [salt.state       :1780][INFO    ][5896] Running state [criproxy] at time 16:38:48.919172
2019-05-18 16:38:48,919 [salt.state       :1813][INFO    ][5896] Executing state service.dead for [criproxy]
2019-05-18 16:38:48,920 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'status', 'criproxy.service', '-n', '0'] in directory '/root'
2019-05-18 16:38:48,933 [salt.state       :300 ][INFO    ][5896] The named service criproxy is not available
2019-05-18 16:38:48,934 [salt.state       :1951][INFO    ][5896] Completed state [criproxy] at time 16:38:48.933953 duration_in_ms=14.782
2019-05-18 16:38:48,934 [salt.state       :1780][INFO    ][5896] Running state [/etc/systemd/system/kubelet.service] at time 16:38:48.934311
2019-05-18 16:38:48,934 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/etc/systemd/system/kubelet.service]
2019-05-18 16:38:48,954 [salt.fileclient  :1219][INFO    ][5896] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kubelet.service'
2019-05-18 16:38:48,999 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:38:49,123 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:38:49,244 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:38:49,245 [salt.state       :1951][INFO    ][5896] Completed state [/etc/systemd/system/kubelet.service] at time 16:38:49.244939 duration_in_ms=310.627
2019-05-18 16:38:49,245 [salt.state       :1780][INFO    ][5896] Running state [/etc/kubernetes/config] at time 16:38:49.245269
2019-05-18 16:38:49,245 [salt.state       :1813][INFO    ][5896] Executing state file.absent for [/etc/kubernetes/config]
2019-05-18 16:38:49,246 [salt.state       :300 ][INFO    ][5896] File /etc/kubernetes/config is not present
2019-05-18 16:38:49,246 [salt.state       :1951][INFO    ][5896] Completed state [/etc/kubernetes/config] at time 16:38:49.246860 duration_in_ms=1.591
2019-05-18 16:38:49,247 [salt.state       :1780][INFO    ][5896] Running state [/etc/default/kubelet] at time 16:38:49.247309
2019-05-18 16:38:49,247 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/etc/default/kubelet]
2019-05-18 16:38:49,267 [salt.fileclient  :1219][INFO    ][5896] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubelet/default.master'
2019-05-18 16:38:49,335 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:38:49,466 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:38:49,599 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:38:49,743 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:38:49,864 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:38:49,990 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:38:50,125 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:38:50,126 [salt.state       :1951][INFO    ][5896] Completed state [/etc/default/kubelet] at time 16:38:50.126675 duration_in_ms=879.365
2019-05-18 16:38:50,127 [salt.state       :1780][INFO    ][5896] Running state [/etc/kubernetes/kubelet.kubeconfig] at time 16:38:50.127171
2019-05-18 16:38:50,127 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/etc/kubernetes/kubelet.kubeconfig]
2019-05-18 16:38:50,153 [salt.fileclient  :1219][INFO    ][5896] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubelet/kubelet.kubeconfig.master'
2019-05-18 16:38:50,206 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:38:50,347 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:38:50,502 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:38:50,620 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:38:50,747 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:38:50,749 [salt.state       :1951][INFO    ][5896] Completed state [/etc/kubernetes/kubelet.kubeconfig] at time 16:38:50.748950 duration_in_ms=621.777
2019-05-18 16:38:50,749 [salt.state       :1780][INFO    ][5896] Running state [/etc/kubernetes/manifests] at time 16:38:50.749651
2019-05-18 16:38:50,750 [salt.state       :1813][INFO    ][5896] Executing state file.directory for [/etc/kubernetes/manifests]
2019-05-18 16:38:50,752 [salt.state       :300 ][INFO    ][5896] {'/etc/kubernetes/manifests': 'New Dir'}
2019-05-18 16:38:50,752 [salt.state       :1951][INFO    ][5896] Completed state [/etc/kubernetes/manifests] at time 16:38:50.752392 duration_in_ms=2.742
2019-05-18 16:38:50,754 [salt.state       :1780][INFO    ][5896] Running state [kubelet] at time 16:38:50.754655
2019-05-18 16:38:50,755 [salt.state       :1813][INFO    ][5896] Executing state service.running for [kubelet]
2019-05-18 16:38:50,756 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'status', 'kubelet.service', '-n', '0'] in directory '/root'
2019-05-18 16:38:50,774 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-05-18 16:38:50,790 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-05-18 16:38:50,808 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kubelet.service'] in directory '/root'
2019-05-18 16:38:50,830 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-05-18 16:38:50,843 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-05-18 16:38:50,858 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-05-18 16:38:50,877 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kubelet.service'] in directory '/root'
2019-05-18 16:38:50,986 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-05-18 16:38:50,998 [salt.state       :300 ][INFO    ][5896] {'kubelet': True}
2019-05-18 16:38:50,998 [salt.state       :1951][INFO    ][5896] Completed state [kubelet] at time 16:38:50.998394 duration_in_ms=243.738
2019-05-18 16:38:50,998 [salt.state       :1780][INFO    ][5896] Running state [/etc/logrotate.d/kubernetes] at time 16:38:50.998815
2019-05-18 16:38:50,999 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/etc/logrotate.d/kubernetes]
2019-05-18 16:38:51,018 [salt.fileclient  :1219][INFO    ][5896] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/logrotate'
2019-05-18 16:38:51,028 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:38:51,028 [salt.state       :1951][INFO    ][5896] Completed state [/etc/logrotate.d/kubernetes] at time 16:38:51.028621 duration_in_ms=29.806
2019-05-18 16:38:51,029 [salt.state       :1780][INFO    ][5896] Running state [/opt/cni/bin] at time 16:38:51.028932
2019-05-18 16:38:51,029 [salt.state       :1813][INFO    ][5896] Executing state archive.extracted for [/opt/cni/bin]
2019-05-18 16:38:57,821 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['tar', 'xz', '-f', '/var/cache/salt/minion/extrn_files/base/docker-prod-local.artifactory.mirantis.com/artifactory/binary-prod-local/mirantis/kubernetes/containernetworking-plugins/containernetworking-plugins_v0.7.2-173-g8db2808.tar.gz'] in directory '/opt/cni/bin/'
2019-05-18 16:38:58,404 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['tar', '--version'] in directory '/root'
2019-05-18 16:38:58,418 [salt.state       :300 ][INFO    ][5896] {'extracted_files': 'no tar output so far'}
2019-05-18 16:38:58,419 [salt.state       :1951][INFO    ][5896] Completed state [/opt/cni/bin] at time 16:38:58.419176 duration_in_ms=7390.238
2019-05-18 16:38:58,419 [salt.state       :1780][INFO    ][5896] Running state [/etc/kubernetes/proxy.kubeconfig] at time 16:38:58.419547
2019-05-18 16:38:58,419 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/etc/kubernetes/proxy.kubeconfig]
2019-05-18 16:38:58,441 [salt.fileclient  :1219][INFO    ][5896] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-proxy/proxy.kubeconfig'
2019-05-18 16:38:58,487 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:38:58,621 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:38:58,758 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:38:58,877 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:38:59,008 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:38:59,008 [salt.state       :1951][INFO    ][5896] Completed state [/etc/kubernetes/proxy.kubeconfig] at time 16:38:59.008462 duration_in_ms=588.913
2019-05-18 16:38:59,008 [salt.state       :1780][INFO    ][5896] Running state [/etc/systemd/system/kube-proxy.service] at time 16:38:59.008883
2019-05-18 16:38:59,009 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/etc/systemd/system/kube-proxy.service]
2019-05-18 16:38:59,029 [salt.fileclient  :1219][INFO    ][5896] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-proxy.service'
2019-05-18 16:38:59,039 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:38:59,039 [salt.state       :1951][INFO    ][5896] Completed state [/etc/systemd/system/kube-proxy.service] at time 16:38:59.039921 duration_in_ms=31.038
2019-05-18 16:38:59,040 [salt.state       :1780][INFO    ][5896] Running state [/etc/default/kube-proxy] at time 16:38:59.040158
2019-05-18 16:38:59,040 [salt.state       :1813][INFO    ][5896] Executing state file.managed for [/etc/default/kube-proxy]
2019-05-18 16:38:59,042 [salt.state       :300 ][INFO    ][5896] File changed:
New file
2019-05-18 16:38:59,042 [salt.state       :1951][INFO    ][5896] Completed state [/etc/default/kube-proxy] at time 16:38:59.042275 duration_in_ms=2.117
2019-05-18 16:38:59,043 [salt.state       :1780][INFO    ][5896] Running state [kube-proxy] at time 16:38:59.043928
2019-05-18 16:38:59,044 [salt.state       :1813][INFO    ][5896] Executing state service.running for [kube-proxy]
2019-05-18 16:38:59,044 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'status', 'kube-proxy.service', '-n', '0'] in directory '/root'
2019-05-18 16:38:59,059 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:38:59,070 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:38:59,090 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:38:59,108 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:38:59,120 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:38:59,137 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:38:59,160 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:38:59,261 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5896] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:38:59,278 [salt.state       :300 ][INFO    ][5896] {'kube-proxy': True}
2019-05-18 16:38:59,278 [salt.state       :1951][INFO    ][5896] Completed state [kube-proxy] at time 16:38:59.278795 duration_in_ms=234.866
2019-05-18 16:38:59,282 [salt.minion      :1711][INFO    ][5896] Returning information for job: 20190518163718874370
2019-05-18 16:39:14,668 [salt.minion      :1308][INFO    ][5017] User sudo_ubuntu Executing command cmd.run with jid 20190518163914657623
2019-05-18 16:39:14,686 [salt.minion      :1432][INFO    ][8840] Starting a new job with PID 8840
2019-05-18 16:39:14,696 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][8840] Executing command 'calicoctl node status' in directory '/root'
2019-05-18 16:39:14,745 [salt.minion      :1711][INFO    ][8840] Returning information for job: 20190518163914657623
2019-05-18 16:39:15,390 [salt.minion      :1308][INFO    ][5017] User sudo_ubuntu Executing command cmd.run with jid 20190518163915381705
2019-05-18 16:39:15,403 [salt.minion      :1432][INFO    ][8860] Starting a new job with PID 8860
2019-05-18 16:39:15,414 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][8860] Executing command 'calicoctl get ippool' in directory '/root'
2019-05-18 16:39:15,532 [salt.minion      :1711][INFO    ][8860] Returning information for job: 20190518163915381705
2019-05-18 16:39:16,188 [salt.minion      :1308][INFO    ][5017] User sudo_ubuntu Executing command state.sls with jid 20190518163916176622
2019-05-18 16:39:16,209 [salt.minion      :1432][INFO    ][8884] Starting a new job with PID 8884
2019-05-18 16:39:22,434 [salt.state       :915 ][INFO    ][8884] Loading fresh modules for state activity
2019-05-18 16:39:22,484 [salt.fileclient  :1219][INFO    ][8884] Fetching file from saltenv 'base', ** done ** 'etcd/server/setup.sls'
2019-05-18 16:39:22,532 [salt.state       :1780][INFO    ][8884] Running state [/calico/ipam/v2/assignment/ipv4/block/192.168.0.0-16] at time 16:39:22.532694
2019-05-18 16:39:22,533 [salt.state       :1813][INFO    ][8884] Executing state etcd.set for [/calico/ipam/v2/assignment/ipv4/block/192.168.0.0-16]
2019-05-18 16:39:22,534 [urllib3.connectionpool:208 ][INFO    ][8884] Starting new HTTP connection (1): 127.0.0.1
2019-05-18 16:39:22,537 [salt.loaded.int.utils.etcd_util:200 ][ERROR   ][8884] etcd: Key not found : /calico
2019-05-18 16:39:22,537 [urllib3.connectionpool:208 ][INFO    ][8884] Starting new HTTP connection (1): 127.0.0.1
2019-05-18 16:39:22,540 [salt.state       :300 ][INFO    ][8884] {'/calico/ipam/v2/assignment/ipv4/block/192.168.0.0-16': '{"masquerade":true,"cidr":"192.168.0.0/16"}'}
2019-05-18 16:39:22,540 [salt.state       :1951][INFO    ][8884] Completed state [/calico/ipam/v2/assignment/ipv4/block/192.168.0.0-16] at time 16:39:22.540209 duration_in_ms=7.516
2019-05-18 16:39:22,542 [salt.minion      :1711][INFO    ][8884] Returning information for job: 20190518163916176622
2019-05-18 16:39:23,189 [salt.minion      :1308][INFO    ][5017] User sudo_ubuntu Executing command state.sls with jid 20190518163923178309
2019-05-18 16:39:23,209 [salt.minion      :1432][INFO    ][8901] Starting a new job with PID 8901
2019-05-18 16:39:24,324 [salt.state       :915 ][INFO    ][8901] Loading fresh modules for state activity
2019-05-18 16:39:24,383 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/init.sls'
2019-05-18 16:39:24,427 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/init.sls'
2019-05-18 16:39:24,484 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:24,628 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:24,784 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/service.sls'
2019-05-18 16:39:24,833 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:24,985 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:25,186 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:25,336 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:25,597 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:25,778 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:26,126 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:26,276 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:26,417 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:26,559 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:26,710 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:26,889 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:27,094 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/controller.sls'
2019-05-18 16:39:27,231 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:27,376 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:27,516 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:27,667 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:27,812 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:27,960 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:28,146 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command 'date "+%FT%TZ"' in directory '/root'
2019-05-18 16:39:28,219 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/master/setup.sls'
2019-05-18 16:39:28,278 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:28,414 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:28,556 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:28,710 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:28,994 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:29,126 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:29,260 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:29,418 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:29,635 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:29,799 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:29,953 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:30,120 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:30,388 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:30,574 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:30,720 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:30,912 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:31,111 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:31,248 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:31,433 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/control/init.sls'
2019-05-18 16:39:31,486 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:31,691 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:31,846 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/control/service.sls'
2019-05-18 16:39:31,928 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:32,074 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:32,259 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/control/role.sls'
2019-05-18 16:39:32,329 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:32,496 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:33,454 [salt.state       :1780][INFO    ][8901] Running state [curl] at time 16:39:33.453942
2019-05-18 16:39:33,454 [salt.state       :1813][INFO    ][8901] Executing state pkg.installed for [curl]
2019-05-18 16:39:33,455 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-18 16:39:33,822 [salt.state       :300 ][INFO    ][8901] All specified packages are already installed
2019-05-18 16:39:33,822 [salt.state       :1951][INFO    ][8901] Completed state [curl] at time 16:39:33.822693 duration_in_ms=368.75
2019-05-18 16:39:33,823 [salt.state       :1780][INFO    ][8901] Running state [git] at time 16:39:33.823090
2019-05-18 16:39:33,823 [salt.state       :1813][INFO    ][8901] Executing state pkg.installed for [git]
2019-05-18 16:39:33,835 [salt.state       :300 ][INFO    ][8901] All specified packages are already installed
2019-05-18 16:39:33,835 [salt.state       :1951][INFO    ][8901] Completed state [git] at time 16:39:33.835413 duration_in_ms=12.323
2019-05-18 16:39:33,835 [salt.state       :1780][INFO    ][8901] Running state [apt-transport-https] at time 16:39:33.835705
2019-05-18 16:39:33,836 [salt.state       :1813][INFO    ][8901] Executing state pkg.installed for [apt-transport-https]
2019-05-18 16:39:33,847 [salt.state       :300 ][INFO    ][8901] All specified packages are already installed
2019-05-18 16:39:33,847 [salt.state       :1951][INFO    ][8901] Completed state [apt-transport-https] at time 16:39:33.847746 duration_in_ms=12.041
2019-05-18 16:39:33,848 [salt.state       :1780][INFO    ][8901] Running state [python-apt] at time 16:39:33.848042
2019-05-18 16:39:33,848 [salt.state       :1813][INFO    ][8901] Executing state pkg.installed for [python-apt]
2019-05-18 16:39:33,859 [salt.state       :300 ][INFO    ][8901] All specified packages are already installed
2019-05-18 16:39:33,859 [salt.state       :1951][INFO    ][8901] Completed state [python-apt] at time 16:39:33.859794 duration_in_ms=11.752
2019-05-18 16:39:33,860 [salt.state       :1780][INFO    ][8901] Running state [socat] at time 16:39:33.860103
2019-05-18 16:39:33,860 [salt.state       :1813][INFO    ][8901] Executing state pkg.installed for [socat]
2019-05-18 16:39:33,871 [salt.state       :300 ][INFO    ][8901] All specified packages are already installed
2019-05-18 16:39:33,872 [salt.state       :1951][INFO    ][8901] Completed state [socat] at time 16:39:33.872016 duration_in_ms=11.913
2019-05-18 16:39:33,872 [salt.state       :1780][INFO    ][8901] Running state [openssl] at time 16:39:33.872302
2019-05-18 16:39:33,872 [salt.state       :1813][INFO    ][8901] Executing state pkg.installed for [openssl]
2019-05-18 16:39:33,884 [salt.state       :300 ][INFO    ][8901] All specified packages are already installed
2019-05-18 16:39:33,884 [salt.state       :1951][INFO    ][8901] Completed state [openssl] at time 16:39:33.884221 duration_in_ms=11.918
2019-05-18 16:39:33,884 [salt.state       :1780][INFO    ][8901] Running state [conntrack] at time 16:39:33.884508
2019-05-18 16:39:33,884 [salt.state       :1813][INFO    ][8901] Executing state pkg.installed for [conntrack]
2019-05-18 16:39:33,894 [salt.state       :300 ][INFO    ][8901] All specified packages are already installed
2019-05-18 16:39:33,894 [salt.state       :1951][INFO    ][8901] Completed state [conntrack] at time 16:39:33.894645 duration_in_ms=10.137
2019-05-18 16:39:33,897 [salt.state       :1780][INFO    ][8901] Running state [/usr/bin/hyperkube] at time 16:39:33.897444
2019-05-18 16:39:33,897 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/usr/bin/hyperkube]
2019-05-18 16:39:34,630 [salt.state       :300 ][INFO    ][8901] File /usr/bin/hyperkube is in the correct state
2019-05-18 16:39:34,630 [salt.state       :1951][INFO    ][8901] Completed state [/usr/bin/hyperkube] at time 16:39:34.630510 duration_in_ms=733.066
2019-05-18 16:39:34,632 [salt.state       :1780][INFO    ][8901] Running state [/usr/bin/kubectl] at time 16:39:34.631957
2019-05-18 16:39:34,632 [salt.state       :1813][INFO    ][8901] Executing state file.symlink for [/usr/bin/kubectl]
2019-05-18 16:39:34,633 [salt.state       :300 ][INFO    ][8901] Symlink /usr/bin/kubectl is present and owned by root:root
2019-05-18 16:39:34,634 [salt.state       :1951][INFO    ][8901] Completed state [/usr/bin/kubectl] at time 16:39:34.634013 duration_in_ms=2.056
2019-05-18 16:39:34,634 [salt.state       :1780][INFO    ][8901] Running state [containerd=1.2.5-3~u16.04+mcp] at time 16:39:34.634255
2019-05-18 16:39:34,634 [salt.state       :1813][INFO    ][8901] Executing state pkg.installed for [containerd=1.2.5-3~u16.04+mcp]
2019-05-18 16:39:34,657 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['apt-cache', '-q', 'policy', 'containerd=1.2.5-3~u16.04+mcp'] in directory '/root'
2019-05-18 16:39:34,759 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-05-18 16:39:37,019 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-05-18 16:39:37,044 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'containerd=1.2.5-3~u16.04+mcp'] in directory '/root'
2019-05-18 16:39:37,382 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-18 16:39:37,416 [salt.state       :300 ][INFO    ][8901] 1 targeted package was installed/updated.
2019-05-18 16:39:37,416 [salt.state       :1951][INFO    ][8901] Completed state [containerd=1.2.5-3~u16.04+mcp] at time 16:39:37.416440 duration_in_ms=2782.185
2019-05-18 16:39:37,416 [salt.state       :1780][INFO    ][8901] Running state [/etc/containerd/config.toml] at time 16:39:37.416812
2019-05-18 16:39:37,417 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/containerd/config.toml]
2019-05-18 16:39:37,476 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:37,650 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:37,794 [salt.state       :300 ][INFO    ][8901] File /etc/containerd/config.toml is in the correct state
2019-05-18 16:39:37,794 [salt.state       :1951][INFO    ][8901] Completed state [/etc/containerd/config.toml] at time 16:39:37.794667 duration_in_ms=377.854
2019-05-18 16:39:37,797 [salt.state       :1780][INFO    ][8901] Running state [containerd] at time 16:39:37.797023
2019-05-18 16:39:37,797 [salt.state       :1813][INFO    ][8901] Executing state service.running for [containerd]
2019-05-18 16:39:37,798 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'status', 'containerd.service', '-n', '0'] in directory '/root'
2019-05-18 16:39:37,816 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-active', 'containerd.service'] in directory '/root'
2019-05-18 16:39:37,832 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-enabled', 'containerd.service'] in directory '/root'
2019-05-18 16:39:37,851 [salt.state       :300 ][INFO    ][8901] The service containerd is already running
2019-05-18 16:39:37,852 [salt.state       :1951][INFO    ][8901] Completed state [containerd] at time 16:39:37.852216 duration_in_ms=55.192
2019-05-18 16:39:37,855 [salt.state       :1780][INFO    ][8901] Running state [/tmp/crictl] at time 16:39:37.855472
2019-05-18 16:39:37,855 [salt.state       :1813][INFO    ][8901] Executing state archive.extracted for [/tmp/crictl]
2019-05-18 16:39:38,247 [salt.state       :300 ][INFO    ][8901] All files in archive are already present
2019-05-18 16:39:38,247 [salt.state       :1951][INFO    ][8901] Completed state [/tmp/crictl] at time 16:39:38.247872 duration_in_ms=392.4
2019-05-18 16:39:38,249 [salt.state       :1780][INFO    ][8901] Running state [/usr/local/bin/crictl] at time 16:39:38.249258
2019-05-18 16:39:38,249 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/usr/local/bin/crictl]
2019-05-18 16:39:38,250 [salt.loaded.int.states.file:1019][WARNING ][8901] Use of argument owner found, "owner" is invalid, please use "user"
2019-05-18 16:39:38,270 [salt.minion      :1308][INFO    ][5017] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518163938262435
2019-05-18 16:39:38,282 [salt.minion      :1432][INFO    ][10203] Starting a new job with PID 10203
2019-05-18 16:39:38,298 [salt.minion      :1711][INFO    ][10203] Returning information for job: 20190518163938262435
2019-05-18 16:39:38,473 [salt.state       :300 ][INFO    ][8901] File /usr/local/bin/crictl is in the correct state
2019-05-18 16:39:38,473 [salt.state       :1951][INFO    ][8901] Completed state [/usr/local/bin/crictl] at time 16:39:38.473675 duration_in_ms=224.417
2019-05-18 16:39:38,473 [salt.state       :1780][INFO    ][8901] Running state [/etc/crictl.yaml] at time 16:39:38.473946
2019-05-18 16:39:38,474 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/crictl.yaml]
2019-05-18 16:39:38,475 [salt.state       :300 ][INFO    ][8901] File /etc/crictl.yaml is in the correct state
2019-05-18 16:39:38,476 [salt.state       :1951][INFO    ][8901] Completed state [/etc/crictl.yaml] at time 16:39:38.476046 duration_in_ms=2.1
2019-05-18 16:39:38,476 [salt.state       :1780][INFO    ][8901] Running state [/etc/criproxy] at time 16:39:38.476271
2019-05-18 16:39:38,476 [salt.state       :1813][INFO    ][8901] Executing state file.absent for [/etc/criproxy]
2019-05-18 16:39:38,476 [salt.state       :300 ][INFO    ][8901] File /etc/criproxy is not present
2019-05-18 16:39:38,477 [salt.state       :1951][INFO    ][8901] Completed state [/etc/criproxy] at time 16:39:38.477023 duration_in_ms=0.752
2019-05-18 16:39:38,477 [salt.state       :1780][INFO    ][8901] Running state [criproxy] at time 16:39:38.477249
2019-05-18 16:39:38,477 [salt.state       :1813][INFO    ][8901] Executing state service.dead for [criproxy]
2019-05-18 16:39:38,478 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'status', 'criproxy.service', '-n', '0'] in directory '/root'
2019-05-18 16:39:38,495 [salt.state       :300 ][INFO    ][8901] The named service criproxy is not available
2019-05-18 16:39:38,495 [salt.state       :1951][INFO    ][8901] Completed state [criproxy] at time 16:39:38.495691 duration_in_ms=18.44
2019-05-18 16:39:38,496 [salt.state       :1780][INFO    ][8901] Running state [/etc/systemd/system/kubelet.service] at time 16:39:38.496160
2019-05-18 16:39:38,496 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/systemd/system/kubelet.service]
2019-05-18 16:39:38,560 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:38,695 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:38,852 [salt.state       :300 ][INFO    ][8901] File /etc/systemd/system/kubelet.service is in the correct state
2019-05-18 16:39:38,852 [salt.state       :1951][INFO    ][8901] Completed state [/etc/systemd/system/kubelet.service] at time 16:39:38.852647 duration_in_ms=356.486
2019-05-18 16:39:38,853 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/config] at time 16:39:38.853184
2019-05-18 16:39:38,853 [salt.state       :1813][INFO    ][8901] Executing state file.absent for [/etc/kubernetes/config]
2019-05-18 16:39:38,854 [salt.state       :300 ][INFO    ][8901] File /etc/kubernetes/config is not present
2019-05-18 16:39:38,854 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/config] at time 16:39:38.854709 duration_in_ms=1.525
2019-05-18 16:39:38,855 [salt.state       :1780][INFO    ][8901] Running state [/etc/default/kubelet] at time 16:39:38.855097
2019-05-18 16:39:38,855 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/default/kubelet]
2019-05-18 16:39:38,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:39,126 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:39,253 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:39,376 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:39,499 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:39,616 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:39,747 [salt.state       :300 ][INFO    ][8901] File /etc/default/kubelet is in the correct state
2019-05-18 16:39:39,748 [salt.state       :1951][INFO    ][8901] Completed state [/etc/default/kubelet] at time 16:39:39.748149 duration_in_ms=893.052
2019-05-18 16:39:39,748 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/kubelet.kubeconfig] at time 16:39:39.748646
2019-05-18 16:39:39,749 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/kubelet.kubeconfig]
2019-05-18 16:39:39,818 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:39,965 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:40,124 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:40,248 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:40,376 [salt.state       :300 ][INFO    ][8901] File /etc/kubernetes/kubelet.kubeconfig is in the correct state
2019-05-18 16:39:40,377 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/kubelet.kubeconfig] at time 16:39:40.377092 duration_in_ms=628.446
2019-05-18 16:39:40,377 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/manifests] at time 16:39:40.377420
2019-05-18 16:39:40,377 [salt.state       :1813][INFO    ][8901] Executing state file.directory for [/etc/kubernetes/manifests]
2019-05-18 16:39:40,378 [salt.state       :300 ][INFO    ][8901] Directory /etc/kubernetes/manifests is in the correct state
Directory /etc/kubernetes/manifests updated
2019-05-18 16:39:40,378 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/manifests] at time 16:39:40.378908 duration_in_ms=1.489
2019-05-18 16:39:40,383 [salt.state       :1780][INFO    ][8901] Running state [kubelet] at time 16:39:40.383014
2019-05-18 16:39:40,383 [salt.state       :1813][INFO    ][8901] Executing state service.running for [kubelet]
2019-05-18 16:39:40,384 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'status', 'kubelet.service', '-n', '0'] in directory '/root'
2019-05-18 16:39:40,403 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-active', 'kubelet.service'] in directory '/root'
2019-05-18 16:39:40,416 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-enabled', 'kubelet.service'] in directory '/root'
2019-05-18 16:39:40,429 [salt.state       :300 ][INFO    ][8901] The service kubelet is already running
2019-05-18 16:39:40,429 [salt.state       :1951][INFO    ][8901] Completed state [kubelet] at time 16:39:40.429595 duration_in_ms=46.58
2019-05-18 16:39:40,430 [salt.state       :1780][INFO    ][8901] Running state [/etc/logrotate.d/kubernetes] at time 16:39:40.430182
2019-05-18 16:39:40,430 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/logrotate.d/kubernetes]
2019-05-18 16:39:40,457 [salt.state       :300 ][INFO    ][8901] File /etc/logrotate.d/kubernetes is in the correct state
2019-05-18 16:39:40,458 [salt.state       :1951][INFO    ][8901] Completed state [/etc/logrotate.d/kubernetes] at time 16:39:40.457958 duration_in_ms=27.776
2019-05-18 16:39:40,458 [salt.state       :1780][INFO    ][8901] Running state [/opt/cni/bin] at time 16:39:40.458311
2019-05-18 16:39:40,458 [salt.state       :1813][INFO    ][8901] Executing state archive.extracted for [/opt/cni/bin]
2019-05-18 16:39:41,388 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['tar', 'xz', '-f', '/var/cache/salt/minion/extrn_files/base/docker-prod-local.artifactory.mirantis.com/artifactory/binary-prod-local/mirantis/kubernetes/containernetworking-plugins/containernetworking-plugins_v0.7.2-173-g8db2808.tar.gz'] in directory '/opt/cni/bin/'
2019-05-18 16:39:41,976 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['tar', '--version'] in directory '/root'
2019-05-18 16:39:41,991 [salt.state       :300 ][INFO    ][8901] {'extracted_files': 'no tar output so far'}
2019-05-18 16:39:41,991 [salt.state       :1951][INFO    ][8901] Completed state [/opt/cni/bin] at time 16:39:41.991577 duration_in_ms=1533.266
2019-05-18 16:39:41,992 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/addons] at time 16:39:41.992090
2019-05-18 16:39:41,992 [salt.state       :1813][INFO    ][8901] Executing state file.directory for [/etc/kubernetes/addons]
2019-05-18 16:39:41,994 [salt.state       :300 ][INFO    ][8901] Directory /etc/kubernetes/addons is in the correct state
Directory /etc/kubernetes/addons updated
2019-05-18 16:39:41,994 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/addons] at time 16:39:41.994459 duration_in_ms=2.369
2019-05-18 16:39:41,994 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/addons/calico/calico-kube-controllers.yml] at time 16:39:41.994802
2019-05-18 16:39:41,995 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/addons/calico/calico-kube-controllers.yml]
2019-05-18 16:39:42,065 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:42,209 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:42,393 [salt.state       :300 ][INFO    ][8901] File /etc/kubernetes/addons/calico/calico-kube-controllers.yml is in the correct state
2019-05-18 16:39:42,394 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/addons/calico/calico-kube-controllers.yml] at time 16:39:42.394293 duration_in_ms=399.49
2019-05-18 16:39:42,394 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/addons/calico/calico-rbac.yml] at time 16:39:42.394683
2019-05-18 16:39:42,395 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/addons/calico/calico-rbac.yml]
2019-05-18 16:39:42,419 [salt.state       :300 ][INFO    ][8901] File /etc/kubernetes/addons/calico/calico-rbac.yml is in the correct state
2019-05-18 16:39:42,419 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/addons/calico/calico-rbac.yml] at time 16:39:42.419364 duration_in_ms=24.681
2019-05-18 16:39:42,419 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/addons/netchecker/netchecker-svc.yml] at time 16:39:42.419681
2019-05-18 16:39:42,419 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-svc.yml]
2019-05-18 16:39:42,479 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:42,672 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:42,808 [salt.state       :300 ][INFO    ][8901] File /etc/kubernetes/addons/netchecker/netchecker-svc.yml is in the correct state
2019-05-18 16:39:42,809 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/addons/netchecker/netchecker-svc.yml] at time 16:39:42.809198 duration_in_ms=389.516
2019-05-18 16:39:42,809 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/addons/netchecker/netchecker-server.yml] at time 16:39:42.809699
2019-05-18 16:39:42,810 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-server.yml]
2019-05-18 16:39:42,883 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:43,033 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:43,181 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:43,318 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:43,477 [salt.state       :300 ][INFO    ][8901] File /etc/kubernetes/addons/netchecker/netchecker-server.yml is in the correct state
2019-05-18 16:39:43,477 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/addons/netchecker/netchecker-server.yml] at time 16:39:43.477664 duration_in_ms=667.964
2019-05-18 16:39:43,478 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/addons/netchecker/netchecker-agent.yml] at time 16:39:43.478223
2019-05-18 16:39:43,478 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-agent.yml]
2019-05-18 16:39:43,553 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:43,693 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:43,840 [salt.state       :300 ][INFO    ][8901] File /etc/kubernetes/addons/netchecker/netchecker-agent.yml is in the correct state
2019-05-18 16:39:43,840 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/addons/netchecker/netchecker-agent.yml] at time 16:39:43.840650 duration_in_ms=362.426
2019-05-18 16:39:43,841 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml] at time 16:39:43.840974
2019-05-18 16:39:43,841 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml]
2019-05-18 16:39:43,859 [salt.state       :300 ][INFO    ][8901] File /etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml is in the correct state
2019-05-18 16:39:43,859 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/addons/netchecker/netchecker-serviceaccount.yml] at time 16:39:43.859732 duration_in_ms=18.758
2019-05-18 16:39:43,860 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/addons/netchecker/netchecker-roles.yml] at time 16:39:43.859979
2019-05-18 16:39:43,860 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/addons/netchecker/netchecker-roles.yml]
2019-05-18 16:39:43,877 [salt.state       :300 ][INFO    ][8901] File /etc/kubernetes/addons/netchecker/netchecker-roles.yml is in the correct state
2019-05-18 16:39:43,878 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/addons/netchecker/netchecker-roles.yml] at time 16:39:43.878154 duration_in_ms=18.175
2019-05-18 16:39:43,878 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/addons/prometheus/prometheus-roles.yml] at time 16:39:43.878508
2019-05-18 16:39:43,878 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/addons/prometheus/prometheus-roles.yml]
2019-05-18 16:39:43,900 [salt.state       :300 ][INFO    ][8901] File /etc/kubernetes/addons/prometheus/prometheus-roles.yml is in the correct state
2019-05-18 16:39:43,901 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/addons/prometheus/prometheus-roles.yml] at time 16:39:43.901117 duration_in_ms=22.609
2019-05-18 16:39:43,901 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/addons/dns] at time 16:39:43.901354
2019-05-18 16:39:43,901 [salt.state       :1813][INFO    ][8901] Executing state file.absent for [/etc/kubernetes/addons/dns]
2019-05-18 16:39:43,902 [salt.state       :300 ][INFO    ][8901] {'removed': '/etc/kubernetes/addons/dns'}
2019-05-18 16:39:43,902 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/addons/dns] at time 16:39:43.902392 duration_in_ms=1.039
2019-05-18 16:39:43,903 [salt.state       :1780][INFO    ][8901] Running state [kubectl -n kube-system delete svc kube-dns > /dev/null || echo "kube-dns is absent. OK" && true] at time 16:39:43.903712
2019-05-18 16:39:43,903 [salt.state       :1813][INFO    ][8901] Executing state cmd.run for [kubectl -n kube-system delete svc kube-dns > /dev/null || echo "kube-dns is absent. OK" && true]
2019-05-18 16:39:43,904 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command 'kubectl -n kube-system delete svc kube-dns > /dev/null || echo "kube-dns is absent. OK" && true' in directory '/root'
2019-05-18 16:39:44,037 [salt.state       :300 ][INFO    ][8901] {'pid': 10627, '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-05-18 16:39:44,038 [salt.state       :1951][INFO    ][8901] Completed state [kubectl -n kube-system delete svc kube-dns > /dev/null || echo "kube-dns is absent. OK" && true] at time 16:39:44.037922 duration_in_ms=134.209
2019-05-18 16:39:44,038 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/addons/coredns/coredns-cm.yml] at time 16:39:44.038391
2019-05-18 16:39:44,038 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/addons/coredns/coredns-cm.yml]
2019-05-18 16:39:44,060 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/coredns/coredns-cm.yml'
2019-05-18 16:39:44,118 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:44,243 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:44,370 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:44,489 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:44,616 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:39:44,616 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/addons/coredns/coredns-cm.yml] at time 16:39:44.616909 duration_in_ms=578.516
2019-05-18 16:39:44,617 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/addons/coredns/coredns-deploy.yml] at time 16:39:44.617307
2019-05-18 16:39:44,617 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/addons/coredns/coredns-deploy.yml]
2019-05-18 16:39:44,633 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/coredns/coredns-deploy.yml'
2019-05-18 16:39:44,685 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:44,826 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:44,987 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:39:44,987 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/addons/coredns/coredns-deploy.yml] at time 16:39:44.987867 duration_in_ms=370.56
2019-05-18 16:39:44,988 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/addons/coredns/coredns-svc.yml] at time 16:39:44.988183
2019-05-18 16:39:44,988 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/addons/coredns/coredns-svc.yml]
2019-05-18 16:39:45,006 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/coredns/coredns-svc.yml'
2019-05-18 16:39:45,055 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:45,186 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:45,339 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:39:45,340 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/addons/coredns/coredns-svc.yml] at time 16:39:45.340078 duration_in_ms=351.894
2019-05-18 16:39:45,340 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/addons/coredns/coredns-rbac.yml] at time 16:39:45.340522
2019-05-18 16:39:45,341 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/addons/coredns/coredns-rbac.yml]
2019-05-18 16:39:45,366 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addons/coredns/coredns-rbac.yml'
2019-05-18 16:39:45,419 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:45,548 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:45,685 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:39:45,686 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/addons/coredns/coredns-rbac.yml] at time 16:39:45.686315 duration_in_ms=345.793
2019-05-18 16:39:45,686 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/addons/metrics-server] at time 16:39:45.686633
2019-05-18 16:39:45,686 [salt.state       :1813][INFO    ][8901] Executing state file.absent for [/etc/kubernetes/addons/metrics-server]
2019-05-18 16:39:45,687 [salt.state       :300 ][INFO    ][8901] File /etc/kubernetes/addons/metrics-server is not present
2019-05-18 16:39:45,687 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/addons/metrics-server] at time 16:39:45.687582 duration_in_ms=0.949
2019-05-18 16:39:45,687 [salt.state       :1780][INFO    ][8901] Running state [/srv/kubernetes/known_tokens.csv] at time 16:39:45.687838
2019-05-18 16:39:45,688 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/srv/kubernetes/known_tokens.csv]
2019-05-18 16:39:45,710 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/known_tokens.csv'
2019-05-18 16:39:45,774 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:45,924 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:46,071 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:46,245 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:46,383 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:39:46,384 [salt.state       :1951][INFO    ][8901] Completed state [/srv/kubernetes/known_tokens.csv] at time 16:39:46.384018 duration_in_ms=696.179
2019-05-18 16:39:46,384 [salt.state       :1780][INFO    ][8901] Running state [/srv/kubernetes/basic_auth.csv] at time 16:39:46.384476
2019-05-18 16:39:46,385 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/srv/kubernetes/basic_auth.csv]
2019-05-18 16:39:46,410 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/basic_auth.csv'
2019-05-18 16:39:46,463 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:46,637 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:46,783 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:39:46,784 [salt.state       :1951][INFO    ][8901] Completed state [/srv/kubernetes/basic_auth.csv] at time 16:39:46.783998 duration_in_ms=399.521
2019-05-18 16:39:46,784 [salt.state       :1780][INFO    ][8901] Running state [/etc/default/kube-apiserver] at time 16:39:46.784508
2019-05-18 16:39:46,786 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/default/kube-apiserver]
2019-05-18 16:39:46,788 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:39:46,788 [salt.state       :1951][INFO    ][8901] Completed state [/etc/default/kube-apiserver] at time 16:39:46.788384 duration_in_ms=3.877
2019-05-18 16:39:46,788 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/scheduler.kubeconfig] at time 16:39:46.788623
2019-05-18 16:39:46,788 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/scheduler.kubeconfig]
2019-05-18 16:39:46,813 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-scheduler/scheduler.kubeconfig'
2019-05-18 16:39:46,904 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:47,045 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:47,186 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:47,327 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:47,483 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:39:47,484 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/scheduler.kubeconfig] at time 16:39:47.484206 duration_in_ms=695.582
2019-05-18 16:39:47,484 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/controller-manager.kubeconfig] at time 16:39:47.484638
2019-05-18 16:39:47,485 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/controller-manager.kubeconfig]
2019-05-18 16:39:47,508 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-controller-manager/controller-manager.kubeconfig'
2019-05-18 16:39:47,557 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:47,707 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:47,877 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:39:48,028 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:39:48,175 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:39:48,175 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/controller-manager.kubeconfig] at time 16:39:48.175577 duration_in_ms=690.938
2019-05-18 16:39:48,175 [salt.state       :1780][INFO    ][8901] Running state [/etc/default/kube-controller-manager] at time 16:39:48.175891
2019-05-18 16:39:48,176 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/default/kube-controller-manager]
2019-05-18 16:39:48,178 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:39:48,178 [salt.state       :1951][INFO    ][8901] Completed state [/etc/default/kube-controller-manager] at time 16:39:48.178234 duration_in_ms=2.343
2019-05-18 16:39:48,178 [salt.state       :1780][INFO    ][8901] Running state [/etc/default/kube-scheduler] at time 16:39:48.178506
2019-05-18 16:39:48,178 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/default/kube-scheduler]
2019-05-18 16:39:48,180 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:39:48,180 [salt.state       :1951][INFO    ][8901] Completed state [/etc/default/kube-scheduler] at time 16:39:48.180682 duration_in_ms=2.177
2019-05-18 16:39:48,180 [salt.state       :1780][INFO    ][8901] Running state [/etc/systemd/system/kube-apiserver.service] at time 16:39:48.180911
2019-05-18 16:39:48,181 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/systemd/system/kube-apiserver.service]
2019-05-18 16:39:48,201 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-apiserver.service'
2019-05-18 16:39:48,212 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:39:48,212 [salt.state       :1951][INFO    ][8901] Completed state [/etc/systemd/system/kube-apiserver.service] at time 16:39:48.212575 duration_in_ms=31.664
2019-05-18 16:39:48,212 [salt.state       :1780][INFO    ][8901] Running state [/etc/systemd/system/kube-scheduler.service] at time 16:39:48.212812
2019-05-18 16:39:48,213 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/systemd/system/kube-scheduler.service]
2019-05-18 16:39:48,234 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-scheduler.service'
2019-05-18 16:39:48,246 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:39:48,246 [salt.state       :1951][INFO    ][8901] Completed state [/etc/systemd/system/kube-scheduler.service] at time 16:39:48.246698 duration_in_ms=33.885
2019-05-18 16:39:48,246 [salt.state       :1780][INFO    ][8901] Running state [/etc/systemd/system/kube-controller-manager.service] at time 16:39:48.246932
2019-05-18 16:39:48,247 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/systemd/system/kube-controller-manager.service]
2019-05-18 16:39:48,271 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/systemd/kube-controller-manager.service'
2019-05-18 16:39:48,280 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:39:48,281 [salt.state       :1951][INFO    ][8901] Completed state [/etc/systemd/system/kube-controller-manager.service] at time 16:39:48.281153 duration_in_ms=34.221
2019-05-18 16:39:48,281 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/ssl/kubernetes-server.crt] at time 16:39:48.281422
2019-05-18 16:39:48,281 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/ssl/kubernetes-server.crt]
2019-05-18 16:39:48,303 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** '_certs/kubernetes/kubernetes-server.crt'
2019-05-18 16:39:48,305 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:39:48,305 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/ssl/kubernetes-server.crt] at time 16:39:48.305696 duration_in_ms=24.273
2019-05-18 16:39:48,306 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/ssl/kubernetes-server.key] at time 16:39:48.306022
2019-05-18 16:39:48,306 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/ssl/kubernetes-server.key]
2019-05-18 16:39:48,328 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** '_certs/kubernetes/kubernetes-server.key'
2019-05-18 16:39:48,330 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:39:48,330 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/ssl/kubernetes-server.key] at time 16:39:48.330462 duration_in_ms=24.44
2019-05-18 16:39:48,330 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/ssl/kubernetes-server.pem] at time 16:39:48.330810
2019-05-18 16:39:48,331 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/ssl/kubernetes-server.pem]
2019-05-18 16:39:48,351 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** '_certs/kubernetes/kubernetes-server.pem'
2019-05-18 16:39:48,353 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:39:48,354 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/ssl/kubernetes-server.pem] at time 16:39:48.354059 duration_in_ms=23.248
2019-05-18 16:39:48,362 [salt.state       :1780][INFO    ][8901] Running state [kube-apiserver] at time 16:39:48.362095
2019-05-18 16:39:48,362 [salt.state       :1813][INFO    ][8901] Executing state service.running for [kube-apiserver]
2019-05-18 16:39:48,362 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'status', 'kube-apiserver.service', '-n', '0'] in directory '/root'
2019-05-18 16:39:48,382 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-active', 'kube-apiserver.service'] in directory '/root'
2019-05-18 16:39:48,397 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-enabled', 'kube-apiserver.service'] in directory '/root'
2019-05-18 16:39:48,424 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-apiserver.service'] in directory '/root'
2019-05-18 16:40:00,608 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-active', 'kube-apiserver.service'] in directory '/root'
2019-05-18 16:40:00,621 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-enabled', 'kube-apiserver.service'] in directory '/root'
2019-05-18 16:40:00,640 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-enabled', 'kube-apiserver.service'] in directory '/root'
2019-05-18 16:40:00,664 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-apiserver.service'] in directory '/root'
2019-05-18 16:40:00,807 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-enabled', 'kube-apiserver.service'] in directory '/root'
2019-05-18 16:40:00,821 [salt.state       :300 ][INFO    ][8901] {'kube-apiserver': True}
2019-05-18 16:40:00,821 [salt.state       :1951][INFO    ][8901] Completed state [kube-apiserver] at time 16:40:00.821343 duration_in_ms=12459.247
2019-05-18 16:40:00,827 [salt.state       :1780][INFO    ][8901] Running state [kube-scheduler] at time 16:40:00.827728
2019-05-18 16:40:00,828 [salt.state       :1813][INFO    ][8901] Executing state service.running for [kube-scheduler]
2019-05-18 16:40:00,828 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'status', 'kube-scheduler.service', '-n', '0'] in directory '/root'
2019-05-18 16:40:00,845 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-active', 'kube-scheduler.service'] in directory '/root'
2019-05-18 16:40:00,860 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-enabled', 'kube-scheduler.service'] in directory '/root'
2019-05-18 16:40:00,876 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-scheduler.service'] in directory '/root'
2019-05-18 16:40:00,919 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-active', 'kube-scheduler.service'] in directory '/root'
2019-05-18 16:40:00,932 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-enabled', 'kube-scheduler.service'] in directory '/root'
2019-05-18 16:40:00,947 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-enabled', 'kube-scheduler.service'] in directory '/root'
2019-05-18 16:40:00,964 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-scheduler.service'] in directory '/root'
2019-05-18 16:40:01,081 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-enabled', 'kube-scheduler.service'] in directory '/root'
2019-05-18 16:40:01,093 [salt.state       :300 ][INFO    ][8901] {'kube-scheduler': True}
2019-05-18 16:40:01,093 [salt.state       :1951][INFO    ][8901] Completed state [kube-scheduler] at time 16:40:01.093781 duration_in_ms=266.053
2019-05-18 16:40:01,098 [salt.state       :1780][INFO    ][8901] Running state [kube-controller-manager] at time 16:40:01.098443
2019-05-18 16:40:01,098 [salt.state       :1813][INFO    ][8901] Executing state service.running for [kube-controller-manager]
2019-05-18 16:40:01,099 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'status', 'kube-controller-manager.service', '-n', '0'] in directory '/root'
2019-05-18 16:40:01,110 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-active', 'kube-controller-manager.service'] in directory '/root'
2019-05-18 16:40:01,121 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-enabled', 'kube-controller-manager.service'] in directory '/root'
2019-05-18 16:40:01,135 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'kube-controller-manager.service'] in directory '/root'
2019-05-18 16:40:01,177 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-active', 'kube-controller-manager.service'] in directory '/root'
2019-05-18 16:40:01,188 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-enabled', 'kube-controller-manager.service'] in directory '/root'
2019-05-18 16:40:01,203 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-enabled', 'kube-controller-manager.service'] in directory '/root'
2019-05-18 16:40:01,222 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemd-run', '--scope', 'systemctl', 'enable', 'kube-controller-manager.service'] in directory '/root'
2019-05-18 16:40:01,323 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-enabled', 'kube-controller-manager.service'] in directory '/root'
2019-05-18 16:40:01,338 [salt.state       :300 ][INFO    ][8901] {'kube-controller-manager': True}
2019-05-18 16:40:01,339 [salt.state       :1951][INFO    ][8901] Completed state [kube-controller-manager] at time 16:40:01.339140 duration_in_ms=240.696
2019-05-18 16:40:01,339 [salt.state       :1780][INFO    ][8901] Running state [kubectl create ns "netchecker"] at time 16:40:01.339832
2019-05-18 16:40:01,340 [salt.state       :1813][INFO    ][8901] Executing state cmd.run for [kubectl create ns "netchecker"]
2019-05-18 16:40:01,341 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command 'kubectl get ns -o=custom-columns=NAME:.metadata.name | grep -v NAME | grep "netchecker"' in directory '/root'
2019-05-18 16:40:01,612 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command 'kubectl create ns "netchecker"' in directory '/root'
2019-05-18 16:40:01,783 [salt.state       :300 ][INFO    ][8901] {'pid': 11349, 'retcode': 0, 'stderr': '', 'stdout': 'namespace/netchecker created'}
2019-05-18 16:40:01,784 [salt.state       :1951][INFO    ][8901] Completed state [kubectl create ns "netchecker"] at time 16:40:01.784651 duration_in_ms=444.819
2019-05-18 16:40:01,785 [salt.state       :1780][INFO    ][8901] Running state [/usr/bin/calicoctl] at time 16:40:01.785087
2019-05-18 16:40:01,785 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/usr/bin/calicoctl]
2019-05-18 16:40:01,921 [salt.state       :300 ][INFO    ][8901] File /usr/bin/calicoctl is in the correct state
2019-05-18 16:40:01,921 [salt.state       :1951][INFO    ][8901] Completed state [/usr/bin/calicoctl] at time 16:40:01.921665 duration_in_ms=136.577
2019-05-18 16:40:01,921 [salt.state       :1780][INFO    ][8901] Running state [/usr/bin/birdcl] at time 16:40:01.921949
2019-05-18 16:40:01,922 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/usr/bin/birdcl]
2019-05-18 16:40:01,925 [salt.state       :300 ][INFO    ][8901] File /usr/bin/birdcl is in the correct state
2019-05-18 16:40:01,925 [salt.state       :1951][INFO    ][8901] Completed state [/usr/bin/birdcl] at time 16:40:01.925745 duration_in_ms=3.796
2019-05-18 16:40:01,926 [salt.state       :1780][INFO    ][8901] Running state [/opt/cni/bin/calico] at time 16:40:01.925999
2019-05-18 16:40:01,926 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/opt/cni/bin/calico]
2019-05-18 16:40:02,049 [salt.state       :300 ][INFO    ][8901] File /opt/cni/bin/calico is in the correct state
2019-05-18 16:40:02,049 [salt.state       :1951][INFO    ][8901] Completed state [/opt/cni/bin/calico] at time 16:40:02.049282 duration_in_ms=123.281
2019-05-18 16:40:02,049 [salt.state       :1780][INFO    ][8901] Running state [/opt/cni/bin/calico-ipam] at time 16:40:02.049566
2019-05-18 16:40:02,049 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/opt/cni/bin/calico-ipam]
2019-05-18 16:40:02,168 [salt.state       :300 ][INFO    ][8901] File /opt/cni/bin/calico-ipam is in the correct state
2019-05-18 16:40:02,169 [salt.state       :1951][INFO    ][8901] Completed state [/opt/cni/bin/calico-ipam] at time 16:40:02.169166 duration_in_ms=119.599
2019-05-18 16:40:02,169 [salt.state       :1780][INFO    ][8901] Running state [/etc/cni/net.d/10-calico.conf] at time 16:40:02.169459
2019-05-18 16:40:02,169 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/cni/net.d/10-calico.conf]
2019-05-18 16:40:02,242 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:40:02,375 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:40:02,506 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:40:02,667 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:40:02,805 [salt.state       :300 ][INFO    ][8901] File /etc/cni/net.d/10-calico.conf is in the correct state
2019-05-18 16:40:02,806 [salt.state       :1951][INFO    ][8901] Completed state [/etc/cni/net.d/10-calico.conf] at time 16:40:02.806310 duration_in_ms=636.849
2019-05-18 16:40:02,806 [salt.state       :1780][INFO    ][8901] Running state [/etc/calico/network-environment] at time 16:40:02.806644
2019-05-18 16:40:02,806 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/calico/network-environment]
2019-05-18 16:40:02,865 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:40:03,009 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:40:03,181 [salt.state       :300 ][INFO    ][8901] File /etc/calico/network-environment is in the correct state
2019-05-18 16:40:03,181 [salt.state       :1951][INFO    ][8901] Completed state [/etc/calico/network-environment] at time 16:40:03.181463 duration_in_ms=374.818
2019-05-18 16:40:03,181 [salt.state       :1780][INFO    ][8901] Running state [/etc/calico/calicoctl.cfg] at time 16:40:03.181797
2019-05-18 16:40:03,182 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/calico/calicoctl.cfg]
2019-05-18 16:40:03,243 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:40:03,371 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:40:03,506 [salt.state       :300 ][INFO    ][8901] File /etc/calico/calicoctl.cfg is in the correct state
2019-05-18 16:40:03,507 [salt.state       :1951][INFO    ][8901] Completed state [/etc/calico/calicoctl.cfg] at time 16:40:03.507179 duration_in_ms=325.381
2019-05-18 16:40:03,508 [salt.state       :1780][INFO    ][8901] Running state [/etc/systemd/system/calico-node.service] at time 16:40:03.508475
2019-05-18 16:40:03,508 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/systemd/system/calico-node.service]
2019-05-18 16:40:03,543 [salt.state       :300 ][INFO    ][8901] File /etc/systemd/system/calico-node.service is in the correct state
2019-05-18 16:40:03,543 [salt.state       :1951][INFO    ][8901] Completed state [/etc/systemd/system/calico-node.service] at time 16:40:03.543263 duration_in_ms=34.788
2019-05-18 16:40:03,543 [salt.state       :1780][INFO    ][8901] Running state [/var/lib/calico] at time 16:40:03.543531
2019-05-18 16:40:03,543 [salt.state       :1813][INFO    ][8901] Executing state file.directory for [/var/lib/calico]
2019-05-18 16:40:03,544 [salt.state       :300 ][INFO    ][8901] Directory /var/lib/calico is in the correct state
Directory /var/lib/calico updated
2019-05-18 16:40:03,544 [salt.state       :1951][INFO    ][8901] Completed state [/var/lib/calico] at time 16:40:03.544804 duration_in_ms=1.273
2019-05-18 16:40:03,545 [salt.state       :1780][INFO    ][8901] Running state [/var/log/calico] at time 16:40:03.545028
2019-05-18 16:40:03,545 [salt.state       :1813][INFO    ][8901] Executing state file.directory for [/var/log/calico]
2019-05-18 16:40:03,546 [salt.state       :300 ][INFO    ][8901] Directory /var/log/calico is in the correct state
Directory /var/log/calico updated
2019-05-18 16:40:03,546 [salt.state       :1951][INFO    ][8901] Completed state [/var/log/calico] at time 16:40:03.546226 duration_in_ms=1.198
2019-05-18 16:40:03,549 [salt.state       :1780][INFO    ][8901] Running state [calico-node] at time 16:40:03.549490
2019-05-18 16:40:03,549 [salt.state       :1813][INFO    ][8901] Executing state service.running for [calico-node]
2019-05-18 16:40:03,550 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'status', 'calico-node.service', '-n', '0'] in directory '/root'
2019-05-18 16:40:03,566 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-active', 'calico-node.service'] in directory '/root'
2019-05-18 16:40:03,582 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-enabled', 'calico-node.service'] in directory '/root'
2019-05-18 16:40:03,602 [salt.state       :300 ][INFO    ][8901] The service calico-node is already running
2019-05-18 16:40:03,603 [salt.state       :1951][INFO    ][8901] Completed state [calico-node] at time 16:40:03.603079 duration_in_ms=53.587
2019-05-18 16:40:03,603 [salt.state       :1780][INFO    ][8901] Running state [/etc/kubernetes/proxy.kubeconfig] at time 16:40:03.603741
2019-05-18 16:40:03,604 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/kubernetes/proxy.kubeconfig]
2019-05-18 16:40:03,674 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:40:03,798 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:40:03,949 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:40:04,127 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:40:04,290 [salt.state       :300 ][INFO    ][8901] File /etc/kubernetes/proxy.kubeconfig is in the correct state
2019-05-18 16:40:04,290 [salt.state       :1951][INFO    ][8901] Completed state [/etc/kubernetes/proxy.kubeconfig] at time 16:40:04.290851 duration_in_ms=687.109
2019-05-18 16:40:04,291 [salt.state       :1780][INFO    ][8901] Running state [/etc/systemd/system/kube-proxy.service] at time 16:40:04.291196
2019-05-18 16:40:04,291 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/systemd/system/kube-proxy.service]
2019-05-18 16:40:04,314 [salt.state       :300 ][INFO    ][8901] File /etc/systemd/system/kube-proxy.service is in the correct state
2019-05-18 16:40:04,314 [salt.state       :1951][INFO    ][8901] Completed state [/etc/systemd/system/kube-proxy.service] at time 16:40:04.314623 duration_in_ms=23.427
2019-05-18 16:40:04,314 [salt.state       :1780][INFO    ][8901] Running state [/etc/default/kube-proxy] at time 16:40:04.314870
2019-05-18 16:40:04,315 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/etc/default/kube-proxy]
2019-05-18 16:40:04,316 [salt.state       :300 ][INFO    ][8901] File /etc/default/kube-proxy is in the correct state
2019-05-18 16:40:04,317 [salt.state       :1951][INFO    ][8901] Completed state [/etc/default/kube-proxy] at time 16:40:04.316824 duration_in_ms=1.954
2019-05-18 16:40:04,320 [salt.state       :1780][INFO    ][8901] Running state [kube-proxy] at time 16:40:04.320238
2019-05-18 16:40:04,320 [salt.state       :1813][INFO    ][8901] Executing state service.running for [kube-proxy]
2019-05-18 16:40:04,321 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'status', 'kube-proxy.service', '-n', '0'] in directory '/root'
2019-05-18 16:40:04,335 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-active', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:40:04,345 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command ['systemctl', 'is-enabled', 'kube-proxy.service'] in directory '/root'
2019-05-18 16:40:04,358 [salt.state       :300 ][INFO    ][8901] The service kube-proxy is already running
2019-05-18 16:40:04,359 [salt.state       :1951][INFO    ][8901] Completed state [kube-proxy] at time 16:40:04.359114 duration_in_ms=38.875
2019-05-18 16:40:04,361 [salt.state       :1780][INFO    ][8901] Running state [/srv/kubernetes] at time 16:40:04.361063
2019-05-18 16:40:04,361 [salt.state       :1813][INFO    ][8901] Executing state file.directory for [/srv/kubernetes]
2019-05-18 16:40:04,362 [salt.state       :300 ][INFO    ][8901] Directory /srv/kubernetes is in the correct state
Directory /srv/kubernetes updated
2019-05-18 16:40:04,362 [salt.state       :1951][INFO    ][8901] Completed state [/srv/kubernetes] at time 16:40:04.362598 duration_in_ms=1.535
2019-05-18 16:40:04,363 [salt.state       :1780][INFO    ][8901] Running state [/srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml] at time 16:40:04.363536
2019-05-18 16:40:04,363 [salt.state       :1813][INFO    ][8901] Executing state file.managed for [/srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml]
2019-05-18 16:40:04,386 [salt.fileclient  :1219][INFO    ][8901] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/rolebinding.yml'
2019-05-18 16:40:04,405 [salt.state       :300 ][INFO    ][8901] File changed:
New file
2019-05-18 16:40:04,406 [salt.state       :1951][INFO    ][8901] Completed state [/srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml] at time 16:40:04.406135 duration_in_ms=42.585
2019-05-18 16:40:04,407 [salt.state       :1780][INFO    ][8901] Running state [kubectl apply -f /srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml] at time 16:40:04.407421
2019-05-18 16:40:04,407 [salt.state       :1813][INFO    ][8901] Executing state cmd.run for [kubectl apply -f /srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml]
2019-05-18 16:40:04,408 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8901] Executing command 'kubectl apply -f /srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml' in directory '/root'
2019-05-18 16:40:04,699 [salt.state       :300 ][INFO    ][8901] {'pid': 11666, 'retcode': 0, 'stderr': '', 'stdout': 'clusterrolebinding.rbac.authorization.k8s.io/root-cluster-admin-binding created'}
2019-05-18 16:40:04,699 [salt.state       :1951][INFO    ][8901] Completed state [kubectl apply -f /srv/kubernetes/roles/cluster-admin/root-cluster-admin-binding-rolebinding.yml] at time 16:40:04.699673 duration_in_ms=292.251
2019-05-18 16:40:04,703 [salt.minion      :1711][INFO    ][8901] Returning information for job: 20190518163923178309
2019-05-18 16:40:05,349 [salt.minion      :1308][INFO    ][5017] User sudo_ubuntu Executing command state.sls with jid 20190518164005342440
2019-05-18 16:40:05,362 [salt.minion      :1432][INFO    ][11688] Starting a new job with PID 11688
2019-05-18 16:40:11,451 [salt.state       :915 ][INFO    ][11688] Loading fresh modules for state activity
2019-05-18 16:40:11,561 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11688] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:40:11,712 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11688] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:40:11,870 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11688] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:40:12,016 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11688] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:40:12,182 [salt.state       :1780][INFO    ][11688] Running state [/etc/kubernetes/kubeconfig.sh] at time 16:40:12.181977
2019-05-18 16:40:12,182 [salt.state       :1813][INFO    ][11688] Executing state file.managed for [/etc/kubernetes/kubeconfig.sh]
2019-05-18 16:40:12,214 [salt.fileclient  :1219][INFO    ][11688] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kubeconfig.sh'
2019-05-18 16:40:12,263 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11688] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:40:12,390 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11688] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:40:12,547 [salt.state       :300 ][INFO    ][11688] File changed:
New file
2019-05-18 16:40:12,548 [salt.state       :1951][INFO    ][11688] Completed state [/etc/kubernetes/kubeconfig.sh] at time 16:40:12.547943 duration_in_ms=365.967
2019-05-18 16:40:12,549 [salt.state       :1780][INFO    ][11688] Running state [/etc/kubernetes/kubeconfig.sh > /etc/kubernetes/admin-kube-config] at time 16:40:12.549504
2019-05-18 16:40:12,549 [salt.state       :1813][INFO    ][11688] Executing state cmd.run for [/etc/kubernetes/kubeconfig.sh > /etc/kubernetes/admin-kube-config]
2019-05-18 16:40:12,550 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11688] Executing command '/etc/kubernetes/kubeconfig.sh > /etc/kubernetes/admin-kube-config' in directory '/root'
2019-05-18 16:40:12,593 [salt.state       :300 ][INFO    ][11688] {'pid': 11813, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-18 16:40:12,593 [salt.state       :1951][INFO    ][11688] Completed state [/etc/kubernetes/kubeconfig.sh > /etc/kubernetes/admin-kube-config] at time 16:40:12.593710 duration_in_ms=44.205
2019-05-18 16:40:12,594 [salt.state       :1780][INFO    ][11688] Running state [/etc/kubernetes/addons/namespace.yaml] at time 16:40:12.594297
2019-05-18 16:40:12,594 [salt.state       :1813][INFO    ][11688] Executing state file.managed for [/etc/kubernetes/addons/namespace.yaml]
2019-05-18 16:40:12,619 [salt.fileclient  :1219][INFO    ][11688] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/kube-addon-manager/namespace.yaml'
2019-05-18 16:40:12,621 [salt.state       :300 ][INFO    ][11688] File changed:
New file
2019-05-18 16:40:12,621 [salt.state       :1951][INFO    ][11688] Completed state [/etc/kubernetes/addons/namespace.yaml] at time 16:40:12.621473 duration_in_ms=27.175
2019-05-18 16:40:12,621 [salt.state       :1780][INFO    ][11688] Running state [/etc/kubernetes/manifests/kube-addon-manager.yml] at time 16:40:12.621828
2019-05-18 16:40:12,622 [salt.state       :1813][INFO    ][11688] Executing state file.managed for [/etc/kubernetes/manifests/kube-addon-manager.yml]
2019-05-18 16:40:12,646 [salt.fileclient  :1219][INFO    ][11688] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/manifest/kube-addon-manager.yml'
2019-05-18 16:40:12,693 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11688] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:40:12,830 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11688] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:40:12,993 [salt.state       :300 ][INFO    ][11688] File changed:
New file
2019-05-18 16:40:12,994 [salt.state       :1951][INFO    ][11688] Completed state [/etc/kubernetes/manifests/kube-addon-manager.yml] at time 16:40:12.994078 duration_in_ms=372.249
2019-05-18 16:40:12,994 [salt.state       :1780][INFO    ][11688] Running state [/etc/default/kube-addon-manager] at time 16:40:12.994540
2019-05-18 16:40:12,994 [salt.state       :1813][INFO    ][11688] Executing state file.absent for [/etc/default/kube-addon-manager]
2019-05-18 16:40:12,995 [salt.state       :300 ][INFO    ][11688] File /etc/default/kube-addon-manager is not present
2019-05-18 16:40:12,995 [salt.state       :1951][INFO    ][11688] Completed state [/etc/default/kube-addon-manager] at time 16:40:12.995859 duration_in_ms=1.319
2019-05-18 16:40:12,996 [salt.state       :1780][INFO    ][11688] Running state [/usr/bin/kube-addons.sh] at time 16:40:12.996193
2019-05-18 16:40:12,996 [salt.state       :1813][INFO    ][11688] Executing state file.absent for [/usr/bin/kube-addons.sh]
2019-05-18 16:40:12,997 [salt.state       :300 ][INFO    ][11688] File /usr/bin/kube-addons.sh is not present
2019-05-18 16:40:12,997 [salt.state       :1951][INFO    ][11688] Completed state [/usr/bin/kube-addons.sh] at time 16:40:12.997339 duration_in_ms=1.147
2019-05-18 16:40:13,448 [salt.state       :1780][INFO    ][11688] Running state [kube-addon-manager] at time 16:40:13.448552
2019-05-18 16:40:13,448 [salt.state       :1813][INFO    ][11688] Executing state service.dead for [kube-addon-manager]
2019-05-18 16:40:13,449 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11688] Executing command ['systemctl', 'status', 'kube-addon-manager.service', '-n', '0'] in directory '/root'
2019-05-18 16:40:13,473 [salt.state       :300 ][INFO    ][11688] The named service kube-addon-manager is not available
2019-05-18 16:40:13,474 [salt.state       :1951][INFO    ][11688] Completed state [kube-addon-manager] at time 16:40:13.474387 duration_in_ms=25.835
2019-05-18 16:40:13,474 [salt.state       :1780][INFO    ][11688] Running state [/etc/systemd/system/kube-addon-manager.service] at time 16:40:13.474883
2019-05-18 16:40:13,475 [salt.state       :1813][INFO    ][11688] Executing state file.absent for [/etc/systemd/system/kube-addon-manager.service]
2019-05-18 16:40:13,475 [salt.state       :300 ][INFO    ][11688] File /etc/systemd/system/kube-addon-manager.service is not present
2019-05-18 16:40:13,476 [salt.state       :1951][INFO    ][11688] Completed state [/etc/systemd/system/kube-addon-manager.service] at time 16:40:13.476097 duration_in_ms=1.214
2019-05-18 16:40:13,476 [salt.state       :1780][INFO    ][11688] Running state [/srv/kubernetes/conformance.yml] at time 16:40:13.476392
2019-05-18 16:40:13,476 [salt.state       :1813][INFO    ][11688] Executing state file.managed for [/srv/kubernetes/conformance.yml]
2019-05-18 16:40:13,495 [salt.fileclient  :1219][INFO    ][11688] Fetching file from saltenv 'base', ** done ** 'kubernetes/files/conformance/conformance.yml'
2019-05-18 16:40:13,551 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11688] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/-.*//g' -e 's/v//g' -e 's/Kubernetes //g' | awk -F'.' '{print $1 "." $2}'' in directory '/root'
2019-05-18 16:40:13,682 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11688] Executing command '(hyperkube --version kubelet 2> /dev/null || echo '0.0') | sed -e 's/+.*//g' -e 's/v//g' -e 's/Kubernetes //g'' in directory '/root'
2019-05-18 16:40:13,826 [salt.state       :300 ][INFO    ][11688] File changed:
New file
2019-05-18 16:40:13,826 [salt.state       :1951][INFO    ][11688] Completed state [/srv/kubernetes/conformance.yml] at time 16:40:13.826877 duration_in_ms=350.485
2019-05-18 16:40:13,828 [salt.minion      :1711][INFO    ][11688] Returning information for job: 20190518164005342440
2019-05-18 16:40:14,456 [salt.minion      :1308][INFO    ][5017] User sudo_ubuntu Executing command cp.push with jid 20190518164014446560
2019-05-18 16:40:14,469 [salt.minion      :1432][INFO    ][11959] Starting a new job with PID 11959
2019-05-18 16:40:14,491 [salt.minion      :1711][INFO    ][11959] Returning information for job: 20190518164014446560
2019-05-18 16:40:15,257 [salt.minion      :1308][INFO    ][5017] User sudo_ubuntu Executing command cp.push_dir with jid 20190518164015245335
2019-05-18 16:40:15,279 [salt.minion      :1432][INFO    ][11976] Starting a new job with PID 11976
2019-05-18 16:40:15,671 [salt.minion      :1711][INFO    ][11976] Returning information for job: 20190518164015245335
2019-05-18 16:42:57,822 [salt.minion      :1308][INFO    ][5017] User sudo_ubuntu Executing command cp.push_dir with jid 20190518164257807017
2019-05-18 16:42:57,844 [salt.minion      :1432][INFO    ][14004] Starting a new job with PID 14004
