Closed prajnadnayak closed 2 years ago
Using system_images
is no longer supported, please remove it from the YAML and start using kubernetes_version
. This will set all the correct images for that version, you can list supported k8s versions using rke config -l -a
.
If you still encounter issues after using the correct configuration, please share full log of rke up
, and outputs of kubectl get nodes -o wide
and kubectl get pods -A
.
I removed the system images from the cluster file but I still see same problem. Network pods don't start. Here is the output from rke up.
fatal: [127.0.0.1]: FAILED! => {"changed": true, "cmd": ["rke", "up", "--config", "./rancher-cluster.yml", "--disable-port-check"], "delta": "0:09:04.008117", "end": "2021-11-30 11:59:47.318061", "msg": "non-zero return code", "rc": 1, "start": "2021-11-30 11:50:43.309944", "stderr": "", "stderr_lines": [], "stdout": "time=\"2021-11-30T11:50:43Z\" level=info msg=\"Running RKE version: v1.3.1\"\ntime=\"2021-11-30T11:50:43Z\" level=info msg=\"Initiating Kubernetes cluster\"\ntime=\"2021-11-30T11:50:43Z\" level=info msg=\"[certificates] GenerateServingCertificate is disabled, checking if there are unused kubelet certificates\"\ntime=\"2021-11-30T11:50:43Z\" level=info msg=\"[certificates] Generating admin certificates and kubeconfig\"\ntime=\"2021-11-30T11:50:43Z\" level=info msg=\"Successfully Deployed state file at [./rancher-cluster.rkestate]\"\ntime=\"2021-11-30T11:50:43Z\" level=info msg=\"Building Kubernetes cluster\"\ntime=\"2021-11-30T11:50:43Z\" level=info msg=\"[dialer] Setup tunnel for host [10.x.x.1]\"\ntime=\"2021-11-30T11:50:43Z\" level=info msg=\"[dialer] Setup tunnel for host [10.x.x.2]\"\ntime=\"2021-11-30T11:50:43Z\" level=info msg=\"[dialer] Setup tunnel for host [10.x.x.3]\"\ntime=\"2021-11-30T11:50:44Z\" level=info msg=\"[certificates] Deploying kubernetes certificates to Cluster nodes\"\ntime=\"2021-11-30T11:50:44Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:50:44Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:50:44Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:50:44Z\" level=info msg=\"Pulling image [rancher/rke-tools:v0.1.78] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:50:44Z\" level=info msg=\"Pulling image [rancher/rke-tools:v0.1.78] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:50:44Z\" level=info msg=\"Pulling image [rancher/rke-tools:v0.1.78] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:50:50Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:50:50Z\" level=info msg=\"Starting container [cert-deployer] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:50:51Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:50:51Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:50:51Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:50:51Z\" level=info msg=\"Starting container [cert-deployer] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:50:51Z\" level=info msg=\"Starting container [cert-deployer] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:50:52Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:50:52Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:50:56Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:50:56Z\" level=info msg=\"Removing container [cert-deployer] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:50:57Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:50:57Z\" level=info msg=\"Removing container [cert-deployer] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:50:57Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:50:57Z\" level=info msg=\"Removing container [cert-deployer] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:50:57Z\" level=info msg=\"[reconcile] Rebuilding and updating local kube config\"\ntime=\"2021-11-30T11:50:57Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"\ntime=\"2021-11-30T11:50:57Z\" level=warning msg=\"[reconcile] host [10.x.x.1] is a control plane node without reachable Kubernetes API endpoint in the cluster\"\ntime=\"2021-11-30T11:50:57Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"\ntime=\"2021-11-30T11:50:57Z\" level=warning msg=\"[reconcile] host [10.x.x.2] is a control plane node without reachable Kubernetes API endpoint in the cluster\"\ntime=\"2021-11-30T11:50:57Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"\ntime=\"2021-11-30T11:50:57Z\" level=warning msg=\"[reconcile] host [10.x.x.3] is a control plane node without reachable Kubernetes API endpoint in the cluster\"\ntime=\"2021-11-30T11:50:57Z\" level=warning msg=\"[reconcile] no control plane node with reachable Kubernetes API endpoint in the cluster found\"\ntime=\"2021-11-30T11:50:57Z\" level=info msg=\"[certificates] Successfully deployed kubernetes certificates to Cluster nodes\"\ntime=\"2021-11-30T11:50:57Z\" level=info msg=\"[file-deploy] Deploying file [/etc/kubernetes/audit-policy.yaml] to node [10.x.x.1]\"\ntime=\"2021-11-30T11:50:57Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:50:57Z\" level=info msg=\"Starting container [file-deployer] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:50:58Z\" level=info msg=\"Successfully started [file-deployer] container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:50:58Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.1]\"\ntime=\"2021-11-30T11:50:58Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.1]\"\ntime=\"2021-11-30T11:50:58Z\" level=info msg=\"Container [file-deployer] is still running on host [10.x.x.1]: stderr: [], stdout: []\"\ntime=\"2021-11-30T11:50:59Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.1]\"\ntime=\"2021-11-30T11:50:59Z\" level=info msg=\"Removing container [file-deployer] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:50:59Z\" level=info msg=\"[remove/file-deployer] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:50:59Z\" level=info msg=\"[file-deploy] Deploying file [/etc/kubernetes/audit-policy.yaml] to node [10.x.x.2]\"\ntime=\"2021-11-30T11:50:59Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:50:59Z\" level=info msg=\"Starting container [file-deployer] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:50:59Z\" level=info msg=\"Successfully started [file-deployer] container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:50:59Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.2]\"\ntime=\"2021-11-30T11:50:59Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.2]\"\ntime=\"2021-11-30T11:50:59Z\" level=info msg=\"Container [file-deployer] is still running on host [10.x.x.2]: stderr: [], stdout: []\"\ntime=\"2021-11-30T11:51:00Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.2]\"\ntime=\"2021-11-30T11:51:00Z\" level=info msg=\"Removing container [file-deployer] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:51:00Z\" level=info msg=\"[remove/file-deployer] Successfully removed container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:51:00Z\" level=info msg=\"[file-deploy] Deploying file [/etc/kubernetes/audit-policy.yaml] to node [10.x.x.3]\"\ntime=\"2021-11-30T11:51:00Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:51:01Z\" level=info msg=\"Starting container [file-deployer] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:51:01Z\" level=info msg=\"Successfully started [file-deployer] container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:51:01Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.3]\"\ntime=\"2021-11-30T11:51:01Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.3]\"\ntime=\"2021-11-30T11:51:01Z\" level=info msg=\"Container [file-deployer] is still running on host [10.x.x.3]: stderr: [], stdout: []\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.3]\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"Removing container [file-deployer] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"[remove/file-deployer] Successfully removed container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"[/etc/kubernetes/audit-policy.yaml] Successfully deployed audit policy file to Cluster control nodes\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"[reconcile] Reconciling cluster state\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"[reconcile] Check etcd hosts to be deleted\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"[reconcile] Check etcd hosts to be added\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"[reconcile] Rebuilding and updating local kube config\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"\ntime=\"2021-11-30T11:51:02Z\" level=warning msg=\"[reconcile] host [10.x.x.1] is a control plane node without reachable Kubernetes API endpoint in the cluster\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"\ntime=\"2021-11-30T11:51:02Z\" level=warning msg=\"[reconcile] host [10.x.x.2] is a control plane node without reachable Kubernetes API endpoint in the cluster\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"\ntime=\"2021-11-30T11:51:02Z\" level=warning msg=\"[reconcile] host [10.x.x.3] is a control plane node without reachable Kubernetes API endpoint in the cluster\"\ntime=\"2021-11-30T11:51:02Z\" level=warning msg=\"[reconcile] no control plane node with reachable Kubernetes API endpoint in the cluster found\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"[reconcile] Reconciled cluster state successfully\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"max_unavailable_worker got rounded down to 0, resetting to 1\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"Setting maxUnavailable for worker nodes to: 1\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"Setting maxUnavailable for controlplane nodes to: 1\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"Pre-pulling kubernetes images\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"Pulling image [rancher/hyperkube:v1.20.11-rancher1] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"Pulling image [rancher/hyperkube:v1.20.11-rancher1] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:51:02Z\" level=info msg=\"Pulling image [rancher/hyperkube:v1.20.11-rancher1] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:51:50Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:52:12Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:53:14Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:53:14Z\" level=info msg=\"Kubernetes images pulled successfully\"\ntime=\"2021-11-30T11:53:14Z\" level=info msg=\"[etcd] Building up etcd plane..\"\ntime=\"2021-11-30T11:53:14Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:53:37Z\" level=info msg=\"Starting container [etcd-fix-perm] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:53:38Z\" level=info msg=\"Successfully started [etcd-fix-perm] container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:53:38Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.1]\"\ntime=\"2021-11-30T11:53:38Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.1]\"\ntime=\"2021-11-30T11:53:38Z\" level=info msg=\"Container [etcd-fix-perm] is still running on host [10.x.x.1]: stderr: [], stdout: []\"\ntime=\"2021-11-30T11:53:39Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.1]\"\ntime=\"2021-11-30T11:53:39Z\" level=info msg=\"Removing container [etcd-fix-perm] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:53:39Z\" level=info msg=\"[remove/etcd-fix-perm] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:53:39Z\" level=info msg=\"Pulling image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:53:44Z\" level=info msg=\"Image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:54:14Z\" level=info msg=\"Starting container [etcd] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:54:15Z\" level=info msg=\"[etcd] Successfully started [etcd] container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:54:15Z\" level=info msg=\"[etcd] Running rolling snapshot container [etcd-snapshot-once] on host [10.x.x.1]\"\ntime=\"2021-11-30T11:54:15Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:54:15Z\" level=info msg=\"Starting container [etcd-rolling-snapshots] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:54:15Z\" level=info msg=\"[etcd] Successfully started [etcd-rolling-snapshots] container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:54:20Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:54:21Z\" level=info msg=\"Starting container [rke-bundle-cert] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:54:21Z\" level=info msg=\"[certificates] Successfully started [rke-bundle-cert] container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:54:21Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.1]\"\ntime=\"2021-11-30T11:54:21Z\" level=info msg=\"Container [rke-bundle-cert] is still running on host [10.x.x.1]: stderr: [], stdout: []\"\ntime=\"2021-11-30T11:54:22Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.1]\"\ntime=\"2021-11-30T11:54:22Z\" level=info msg=\"[certificates] successfully saved certificate bundle [/opt/rke/etcd-snapshots//pki.bundle.tar.gz] on host [10.x.x.1]\"\ntime=\"2021-11-30T11:54:22Z\" level=info msg=\"Removing container [rke-bundle-cert] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:54:22Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:54:22Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:54:23Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:54:23Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:54:23Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:54:23Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:54:23Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:54:23Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:54:23Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:54:24Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:54:24Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:24Z\" level=info msg=\"Starting container [etcd-fix-perm] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:54:25Z\" level=info msg=\"Successfully started [etcd-fix-perm] container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:25Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:25Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:25Z\" level=info msg=\"Container [etcd-fix-perm] is still running on host [10.x.x.2]: stderr: [], stdout: []\"\ntime=\"2021-11-30T11:54:26Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:26Z\" level=info msg=\"Removing container [etcd-fix-perm] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:54:26Z\" level=info msg=\"[remove/etcd-fix-perm] Successfully removed container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:26Z\" level=info msg=\"Pulling image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:54:29Z\" level=info msg=\"Image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:29Z\" level=info msg=\"Starting container [etcd] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:54:30Z\" level=info msg=\"[etcd] Successfully started [etcd] container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:30Z\" level=info msg=\"[etcd] Running rolling snapshot container [etcd-snapshot-once] on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:30Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:49Z\" level=info msg=\"Starting container [etcd-rolling-snapshots] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:54:49Z\" level=info msg=\"[etcd] Successfully started [etcd-rolling-snapshots] container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:54Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:54Z\" level=info msg=\"Starting container [rke-bundle-cert] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:54:55Z\" level=info msg=\"[certificates] Successfully started [rke-bundle-cert] container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:55Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:55Z\" level=info msg=\"Container [rke-bundle-cert] is still running on host [10.x.x.2]: stderr: [], stdout: []\"\ntime=\"2021-11-30T11:54:56Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:56Z\" level=info msg=\"[certificates] successfully saved certificate bundle [/opt/rke/etcd-snapshots//pki.bundle.tar.gz] on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:56Z\" level=info msg=\"Removing container [rke-bundle-cert] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:54:56Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:56Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:54:56Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:56Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:54:56Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:56Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:57Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:54:57Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:57Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:54:57Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:54:58Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:54:58Z\" level=info msg=\"Starting container [etcd-fix-perm] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:54:59Z\" level=info msg=\"Successfully started [etcd-fix-perm] container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:54:59Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.3]\"\ntime=\"2021-11-30T11:54:59Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.3]\"\ntime=\"2021-11-30T11:54:59Z\" level=info msg=\"Container [etcd-fix-perm] is still running on host [10.x.x.3]: stderr: [], stdout: []\"\ntime=\"2021-11-30T11:55:00Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:00Z\" level=info msg=\"Removing container [etcd-fix-perm] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:55:00Z\" level=info msg=\"[remove/etcd-fix-perm] Successfully removed container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:00Z\" level=info msg=\"Pulling image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:55:03Z\" level=info msg=\"Image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:03Z\" level=info msg=\"Starting container [etcd] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:55:03Z\" level=info msg=\"[etcd] Successfully started [etcd] container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:03Z\" level=info msg=\"[etcd] Running rolling snapshot container [etcd-snapshot-once] on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:03Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:22Z\" level=info msg=\"Starting container [etcd-rolling-snapshots] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:55:22Z\" level=info msg=\"[etcd] Successfully started [etcd-rolling-snapshots] container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:27Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:27Z\" level=info msg=\"Starting container [rke-bundle-cert] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:55:28Z\" level=info msg=\"[certificates] Successfully started [rke-bundle-cert] container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:28Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:28Z\" level=info msg=\"Container [rke-bundle-cert] is still running on host [10.x.x.3]: stderr: [], stdout: []\"\ntime=\"2021-11-30T11:55:29Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:29Z\" level=info msg=\"[certificates] successfully saved certificate bundle [/opt/rke/etcd-snapshots//pki.bundle.tar.gz] on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:29Z\" level=info msg=\"Removing container [rke-bundle-cert] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:55:29Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:29Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:55:29Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:29Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:55:30Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:30Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:30Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:55:30Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:30Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:55:31Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:55:31Z\" level=info msg=\"[etcd] Successfully started etcd plane.. Checking etcd cluster health\"\ntime=\"2021-11-30T11:55:31Z\" level=info msg=\"[etcd] etcd host [10.x.x.1] reported healthy=true\"\ntime=\"2021-11-30T11:55:31Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.1, try #1\"\ntime=\"2021-11-30T11:55:56Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.2, try #1\"\ntime=\"2021-11-30T11:56:21Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.3, try #1\"\ntime=\"2021-11-30T11:56:46Z\" level=info msg=\"Attempting upgrade of controlplane components on following hosts in NotReady status: 10.x.x.1,10.x.x.2,10.x.x.3\"\ntime=\"2021-11-30T11:56:46Z\" level=info msg=\"[controlplane] Building up Controller Plane..\"\ntime=\"2021-11-30T11:56:47Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:56:47Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:56:47Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:56:47Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:56:47Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:56:47Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:56:47Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:56:47Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:56:47Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:56:47Z\" level=info msg=\"Starting container [kube-apiserver] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:56:47Z\" level=info msg=\"Starting container [kube-apiserver] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:56:47Z\" level=info msg=\"Starting container [kube-apiserver] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:56:48Z\" level=info msg=\"[controlplane] Successfully started [kube-apiserver] container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:56:48Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-apiserver] on host [10.x.x.1]\"\ntime=\"2021-11-30T11:56:48Z\" level=info msg=\"[controlplane] Successfully started [kube-apiserver] container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:56:48Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-apiserver] on host [10.x.x.2]\"\ntime=\"2021-11-30T11:56:48Z\" level=info msg=\"[controlplane] Successfully started [kube-apiserver] container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:56:48Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-apiserver] on host [10.x.x.3]\"\ntime=\"2021-11-30T11:56:56Z\" level=info msg=\"[healthcheck] service [kube-apiserver] on host [10.x.x.2] is healthy\"\ntime=\"2021-11-30T11:56:56Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:56:57Z\" level=info msg=\"[healthcheck] service [kube-apiserver] on host [10.x.x.3] is healthy\"\ntime=\"2021-11-30T11:56:57Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:56:57Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:56:57Z\" level=info msg=\"[healthcheck] service [kube-apiserver] on host [10.x.x.1] is healthy\"\ntime=\"2021-11-30T11:56:57Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:56:57Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:56:57Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:56:57Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:56:57Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:56:57Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:56:57Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:56:57Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:56:57Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:56:57Z\" level=info msg=\"Starting container [kube-controller-manager] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:56:57Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:56:58Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:56:58Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:56:58Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:56:58Z\" level=info msg=\"Starting container [kube-controller-manager] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:56:58Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:56:58Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:56:58Z\" level=info msg=\"[controlplane] Successfully started [kube-controller-manager] container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:56:58Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-controller-manager] on host [10.x.x.2]\"\ntime=\"2021-11-30T11:56:58Z\" level=info msg=\"Starting container [kube-controller-manager] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:56:58Z\" level=info msg=\"[controlplane] Successfully started [kube-controller-manager] container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:56:58Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-controller-manager] on host [10.x.x.3]\"\ntime=\"2021-11-30T11:56:58Z\" level=info msg=\"[controlplane] Successfully started [kube-controller-manager] container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:56:58Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-controller-manager] on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:04Z\" level=info msg=\"[healthcheck] service [kube-controller-manager] on host [10.x.x.2] is healthy\"\ntime=\"2021-11-30T11:57:04Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:04Z\" level=info msg=\"[healthcheck] service [kube-controller-manager] on host [10.x.x.3] is healthy\"\ntime=\"2021-11-30T11:57:04Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:04Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:57:04Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:57:04Z\" level=info msg=\"[healthcheck] service [kube-controller-manager] on host [10.x.x.1] is healthy\"\ntime=\"2021-11-30T11:57:04Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"Starting container [kube-scheduler] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"Starting container [kube-scheduler] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"[controlplane] Successfully started [kube-scheduler] container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-scheduler] on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"Starting container [kube-scheduler] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"[controlplane] Successfully started [kube-scheduler] container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:05Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-scheduler] on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:06Z\" level=info msg=\"[controlplane] Successfully started [kube-scheduler] container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:06Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-scheduler] on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:07Z\" level=info msg=\"[healthcheck] service [kube-scheduler] on host [10.x.x.1] is healthy\"\ntime=\"2021-11-30T11:57:07Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:07Z\" level=info msg=\"[healthcheck] service [kube-scheduler] on host [10.x.x.3] is healthy\"\ntime=\"2021-11-30T11:57:07Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:07Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:57:07Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:57:07Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:07Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:57:07Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:07Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:57:07Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:08Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:11Z\" level=info msg=\"[healthcheck] service [kube-scheduler] on host [10.x.x.2] is healthy\"\ntime=\"2021-11-30T11:57:11Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:12Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:57:12Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:12Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:57:12Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:12Z\" level=info msg=\"[controlplane] Successfully started Controller Plane..\"\ntime=\"2021-11-30T11:57:12Z\" level=info msg=\"[worker] Building up Worker Plane..\"\ntime=\"2021-11-30T11:57:12Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:57:12Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:57:12Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:57:12Z\" level=info msg=\"[sidekick] Sidekick container already created on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:12Z\" level=info msg=\"[sidekick] Sidekick container already created on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:12Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:12Z\" level=info msg=\"[sidekick] Sidekick container already created on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:12Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:12Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:12Z\" level=info msg=\"Starting container [kubelet] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:57:13Z\" level=info msg=\"Starting container [kubelet] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:57:13Z\" level=info msg=\"Starting container [kubelet] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:57:13Z\" level=info msg=\"[worker] Successfully started [kubelet] container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:13Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kubelet] on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:13Z\" level=info msg=\"[worker] Successfully started [kubelet] container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:13Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kubelet] on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:13Z\" level=info msg=\"[worker] Successfully started [kubelet] container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:13Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kubelet] on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:24Z\" level=info msg=\"[healthcheck] service [kubelet] on host [10.x.x.2] is healthy\"\ntime=\"2021-11-30T11:57:24Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"[healthcheck] service [kubelet] on host [10.x.x.3] is healthy\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"[healthcheck] service [kubelet] on host [10.x.x.1] is healthy\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"Starting container [kube-proxy] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"[worker] Successfully started [kube-proxy] container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:25Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-proxy] on host [10.x.x.2]\"\ntime=\"2021-11-30T11:57:26Z\" level=info msg=\"Starting container [kube-proxy] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:57:26Z\" level=info msg=\"Starting container [kube-proxy] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:57:26Z\" level=info msg=\"[worker] Successfully started [kube-proxy] container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:26Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-proxy] on host [10.x.x.3]\"\ntime=\"2021-11-30T11:57:26Z\" level=info msg=\"[worker] Successfully started [kube-proxy] container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:57:26Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-proxy] on host [10.x.x.1]\"\ntime=\"2021-11-30T11:58:00Z\" level=info msg=\"[healthcheck] service [kube-proxy] on host [10.x.x.1] is healthy\"\ntime=\"2021-11-30T11:58:00Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T11:58:00Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:58:01Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:58:01Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T11:58:01Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T11:58:05Z\" level=info msg=\"[healthcheck] service [kube-proxy] on host [10.x.x.2] is healthy\"\ntime=\"2021-11-30T11:58:05Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T11:58:06Z\" level=info msg=\"[healthcheck] service [kube-proxy] on host [10.x.x.3] is healthy\"\ntime=\"2021-11-30T11:58:06Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T11:58:06Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:58:06Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:58:06Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:58:06Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T11:58:06Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:58:06Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T11:58:06Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"\ntime=\"2021-11-30T11:58:07Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"\ntime=\"2021-11-30T11:58:07Z\" level=info msg=\"[worker] Successfully started Worker Plane..\"\ntime=\"2021-11-30T11:58:07Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.1, try #1\"\ntime=\"2021-11-30T11:58:32Z\" level=error msg=\"Host 10.x.x.1 failed to report Ready status with error: [controlplane] Error getting node 10.x.x.1: \\"10.x.x.1\\" not found\"\ntime=\"2021-11-30T11:58:32Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.2, try #1\"\ntime=\"2021-11-30T11:58:57Z\" level=error msg=\"Host 10.x.x.2 failed to report Ready status with error: [controlplane] Error getting node 10.x.x.2: \\"10.x.x.2\\" not found\"\ntime=\"2021-11-30T11:58:57Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.3, try #1\"\ntime=\"2021-11-30T11:59:22Z\" level=error msg=\"Host 10.x.x.3 failed to report Ready status with error: [controlplane] Error getting node 10.x.x.3: \\"10.x.x.3\\" not found\"\ntime=\"2021-11-30T11:59:22Z\" level=info msg=\"[controlplane] Processing controlplane hosts for upgrade 1 at a time\"\ntime=\"2021-11-30T11:59:22Z\" level=info msg=\"Processing controlplane host 10.x.x.1\"\ntime=\"2021-11-30T11:59:22Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.1, try #1\"\ntime=\"2021-11-30T11:59:47Z\" level=error msg=\"Failed to upgrade hosts: 10.x.x.1 with error [[controlplane] Error getting node 10.x.x.1: \\"10.x.x.1\\" not found]\"\ntime=\"2021-11-30T11:59:47Z\" level=fatal msg=\"[controlPlane] Failed to upgrade Control Plane: [[[controlplane] Error getting node 10.x.x.1: \\"10.x.x.1\\" not found]]\"", "stdout_lines": ["time=\"2021-11-30T11:50:43Z\" level=info msg=\"Running RKE version: v1.3.1\"", "time=\"2021-11-30T11:50:43Z\" level=info msg=\"Initiating Kubernetes cluster\"", "time=\"2021-11-30T11:50:43Z\" level=info msg=\"[certificates] GenerateServingCertificate is disabled, checking if there are unused kubelet certificates\"", "time=\"2021-11-30T11:50:43Z\" level=info msg=\"[certificates] Generating admin certificates and kubeconfig\"", "time=\"2021-11-30T11:50:43Z\" level=info msg=\"Successfully Deployed state file at [./rancher-cluster.rkestate]\"", "time=\"2021-11-30T11:50:43Z\" level=info msg=\"Building Kubernetes cluster\"", "time=\"2021-11-30T11:50:43Z\" level=info msg=\"[dialer] Setup tunnel for host [10.x.x.1]\"", "time=\"2021-11-30T11:50:43Z\" level=info msg=\"[dialer] Setup tunnel for host [10.x.x.2]\"", "time=\"2021-11-30T11:50:43Z\" level=info msg=\"[dialer] Setup tunnel for host [10.x.x.3]\"", "time=\"2021-11-30T11:50:44Z\" level=info msg=\"[certificates] Deploying kubernetes certificates to Cluster nodes\"", "time=\"2021-11-30T11:50:44Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:50:44Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:50:44Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:50:44Z\" level=info msg=\"Pulling image [rancher/rke-tools:v0.1.78] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:50:44Z\" level=info msg=\"Pulling image [rancher/rke-tools:v0.1.78] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:50:44Z\" level=info msg=\"Pulling image [rancher/rke-tools:v0.1.78] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:50:50Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:50:50Z\" level=info msg=\"Starting container [cert-deployer] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:50:51Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:50:51Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:50:51Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:50:51Z\" level=info msg=\"Starting container [cert-deployer] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:50:51Z\" level=info msg=\"Starting container [cert-deployer] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:50:52Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:50:52Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:50:56Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:50:56Z\" level=info msg=\"Removing container [cert-deployer] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:50:57Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:50:57Z\" level=info msg=\"Removing container [cert-deployer] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:50:57Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:50:57Z\" level=info msg=\"Removing container [cert-deployer] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:50:57Z\" level=info msg=\"[reconcile] Rebuilding and updating local kube config\"", "time=\"2021-11-30T11:50:57Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"", "time=\"2021-11-30T11:50:57Z\" level=warning msg=\"[reconcile] host [10.x.x.1] is a control plane node without reachable Kubernetes API endpoint in the cluster\"", "time=\"2021-11-30T11:50:57Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"", "time=\"2021-11-30T11:50:57Z\" level=warning msg=\"[reconcile] host [10.x.x.2] is a control plane node without reachable Kubernetes API endpoint in the cluster\"", "time=\"2021-11-30T11:50:57Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"", "time=\"2021-11-30T11:50:57Z\" level=warning msg=\"[reconcile] host [10.x.x.3] is a control plane node without reachable Kubernetes API endpoint in the cluster\"", "time=\"2021-11-30T11:50:57Z\" level=warning msg=\"[reconcile] no control plane node with reachable Kubernetes API endpoint in the cluster found\"", "time=\"2021-11-30T11:50:57Z\" level=info msg=\"[certificates] Successfully deployed kubernetes certificates to Cluster nodes\"", "time=\"2021-11-30T11:50:57Z\" level=info msg=\"[file-deploy] Deploying file [/etc/kubernetes/audit-policy.yaml] to node [10.x.x.1]\"", "time=\"2021-11-30T11:50:57Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:50:57Z\" level=info msg=\"Starting container [file-deployer] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:50:58Z\" level=info msg=\"Successfully started [file-deployer] container on host [10.x.x.1]\"", "time=\"2021-11-30T11:50:58Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.1]\"", "time=\"2021-11-30T11:50:58Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.1]\"", "time=\"2021-11-30T11:50:58Z\" level=info msg=\"Container [file-deployer] is still running on host [10.x.x.1]: stderr: [], stdout: []\"", "time=\"2021-11-30T11:50:59Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.1]\"", "time=\"2021-11-30T11:50:59Z\" level=info msg=\"Removing container [file-deployer] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:50:59Z\" level=info msg=\"[remove/file-deployer] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T11:50:59Z\" level=info msg=\"[file-deploy] Deploying file [/etc/kubernetes/audit-policy.yaml] to node [10.x.x.2]\"", "time=\"2021-11-30T11:50:59Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:50:59Z\" level=info msg=\"Starting container [file-deployer] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:50:59Z\" level=info msg=\"Successfully started [file-deployer] container on host [10.x.x.2]\"", "time=\"2021-11-30T11:50:59Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.2]\"", "time=\"2021-11-30T11:50:59Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.2]\"", "time=\"2021-11-30T11:50:59Z\" level=info msg=\"Container [file-deployer] is still running on host [10.x.x.2]: stderr: [], stdout: []\"", "time=\"2021-11-30T11:51:00Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.2]\"", "time=\"2021-11-30T11:51:00Z\" level=info msg=\"Removing container [file-deployer] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:51:00Z\" level=info msg=\"[remove/file-deployer] Successfully removed container on host [10.x.x.2]\"", "time=\"2021-11-30T11:51:00Z\" level=info msg=\"[file-deploy] Deploying file [/etc/kubernetes/audit-policy.yaml] to node [10.x.x.3]\"", "time=\"2021-11-30T11:51:00Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:51:01Z\" level=info msg=\"Starting container [file-deployer] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:51:01Z\" level=info msg=\"Successfully started [file-deployer] container on host [10.x.x.3]\"", "time=\"2021-11-30T11:51:01Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.3]\"", "time=\"2021-11-30T11:51:01Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.3]\"", "time=\"2021-11-30T11:51:01Z\" level=info msg=\"Container [file-deployer] is still running on host [10.x.x.3]: stderr: [], stdout: []\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.3]\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"Removing container [file-deployer] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"[remove/file-deployer] Successfully removed container on host [10.x.x.3]\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"[/etc/kubernetes/audit-policy.yaml] Successfully deployed audit policy file to Cluster control nodes\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"[reconcile] Reconciling cluster state\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"[reconcile] Check etcd hosts to be deleted\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"[reconcile] Check etcd hosts to be added\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"[reconcile] Rebuilding and updating local kube config\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"", "time=\"2021-11-30T11:51:02Z\" level=warning msg=\"[reconcile] host [10.x.x.1] is a control plane node without reachable Kubernetes API endpoint in the cluster\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"", "time=\"2021-11-30T11:51:02Z\" level=warning msg=\"[reconcile] host [10.x.x.2] is a control plane node without reachable Kubernetes API endpoint in the cluster\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"", "time=\"2021-11-30T11:51:02Z\" level=warning msg=\"[reconcile] host [10.x.x.3] is a control plane node without reachable Kubernetes API endpoint in the cluster\"", "time=\"2021-11-30T11:51:02Z\" level=warning msg=\"[reconcile] no control plane node with reachable Kubernetes API endpoint in the cluster found\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"[reconcile] Reconciled cluster state successfully\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"max_unavailable_worker got rounded down to 0, resetting to 1\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"Setting maxUnavailable for worker nodes to: 1\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"Setting maxUnavailable for controlplane nodes to: 1\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"Pre-pulling kubernetes images\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"Pulling image [rancher/hyperkube:v1.20.11-rancher1] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"Pulling image [rancher/hyperkube:v1.20.11-rancher1] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:51:02Z\" level=info msg=\"Pulling image [rancher/hyperkube:v1.20.11-rancher1] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:51:50Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:52:12Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:53:14Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:53:14Z\" level=info msg=\"Kubernetes images pulled successfully\"", "time=\"2021-11-30T11:53:14Z\" level=info msg=\"[etcd] Building up etcd plane..\"", "time=\"2021-11-30T11:53:14Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:53:37Z\" level=info msg=\"Starting container [etcd-fix-perm] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:53:38Z\" level=info msg=\"Successfully started [etcd-fix-perm] container on host [10.x.x.1]\"", "time=\"2021-11-30T11:53:38Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.1]\"", "time=\"2021-11-30T11:53:38Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.1]\"", "time=\"2021-11-30T11:53:38Z\" level=info msg=\"Container [etcd-fix-perm] is still running on host [10.x.x.1]: stderr: [], stdout: []\"", "time=\"2021-11-30T11:53:39Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.1]\"", "time=\"2021-11-30T11:53:39Z\" level=info msg=\"Removing container [etcd-fix-perm] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:53:39Z\" level=info msg=\"[remove/etcd-fix-perm] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T11:53:39Z\" level=info msg=\"Pulling image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:53:44Z\" level=info msg=\"Image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:54:14Z\" level=info msg=\"Starting container [etcd] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:54:15Z\" level=info msg=\"[etcd] Successfully started [etcd] container on host [10.x.x.1]\"", "time=\"2021-11-30T11:54:15Z\" level=info msg=\"[etcd] Running rolling snapshot container [etcd-snapshot-once] on host [10.x.x.1]\"", "time=\"2021-11-30T11:54:15Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:54:15Z\" level=info msg=\"Starting container [etcd-rolling-snapshots] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:54:15Z\" level=info msg=\"[etcd] Successfully started [etcd-rolling-snapshots] container on host [10.x.x.1]\"", "time=\"2021-11-30T11:54:20Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:54:21Z\" level=info msg=\"Starting container [rke-bundle-cert] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:54:21Z\" level=info msg=\"[certificates] Successfully started [rke-bundle-cert] container on host [10.x.x.1]\"", "time=\"2021-11-30T11:54:21Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.1]\"", "time=\"2021-11-30T11:54:21Z\" level=info msg=\"Container [rke-bundle-cert] is still running on host [10.x.x.1]: stderr: [], stdout: []\"", "time=\"2021-11-30T11:54:22Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.1]\"", "time=\"2021-11-30T11:54:22Z\" level=info msg=\"[certificates] successfully saved certificate bundle [/opt/rke/etcd-snapshots//pki.bundle.tar.gz] on host [10.x.x.1]\"", "time=\"2021-11-30T11:54:22Z\" level=info msg=\"Removing container [rke-bundle-cert] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:54:22Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:54:22Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:54:23Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.1]\"", "time=\"2021-11-30T11:54:23Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:54:23Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T11:54:23Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:54:23Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:54:23Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.1]\"", "time=\"2021-11-30T11:54:23Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:54:24Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T11:54:24Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:24Z\" level=info msg=\"Starting container [etcd-fix-perm] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:54:25Z\" level=info msg=\"Successfully started [etcd-fix-perm] container on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:25Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:25Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:25Z\" level=info msg=\"Container [etcd-fix-perm] is still running on host [10.x.x.2]: stderr: [], stdout: []\"", "time=\"2021-11-30T11:54:26Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:26Z\" level=info msg=\"Removing container [etcd-fix-perm] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:54:26Z\" level=info msg=\"[remove/etcd-fix-perm] Successfully removed container on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:26Z\" level=info msg=\"Pulling image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:54:29Z\" level=info msg=\"Image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:29Z\" level=info msg=\"Starting container [etcd] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:54:30Z\" level=info msg=\"[etcd] Successfully started [etcd] container on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:30Z\" level=info msg=\"[etcd] Running rolling snapshot container [etcd-snapshot-once] on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:30Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:49Z\" level=info msg=\"Starting container [etcd-rolling-snapshots] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:54:49Z\" level=info msg=\"[etcd] Successfully started [etcd-rolling-snapshots] container on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:54Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:54Z\" level=info msg=\"Starting container [rke-bundle-cert] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:54:55Z\" level=info msg=\"[certificates] Successfully started [rke-bundle-cert] container on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:55Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:55Z\" level=info msg=\"Container [rke-bundle-cert] is still running on host [10.x.x.2]: stderr: [], stdout: []\"", "time=\"2021-11-30T11:54:56Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:56Z\" level=info msg=\"[certificates] successfully saved certificate bundle [/opt/rke/etcd-snapshots//pki.bundle.tar.gz] on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:56Z\" level=info msg=\"Removing container [rke-bundle-cert] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:54:56Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:56Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:54:56Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:56Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:54:56Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:56Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:57Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:54:57Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:57Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:54:57Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"", "time=\"2021-11-30T11:54:58Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:54:58Z\" level=info msg=\"Starting container [etcd-fix-perm] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:54:59Z\" level=info msg=\"Successfully started [etcd-fix-perm] container on host [10.x.x.3]\"", "time=\"2021-11-30T11:54:59Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.3]\"", "time=\"2021-11-30T11:54:59Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.3]\"", "time=\"2021-11-30T11:54:59Z\" level=info msg=\"Container [etcd-fix-perm] is still running on host [10.x.x.3]: stderr: [], stdout: []\"", "time=\"2021-11-30T11:55:00Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:00Z\" level=info msg=\"Removing container [etcd-fix-perm] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:55:00Z\" level=info msg=\"[remove/etcd-fix-perm] Successfully removed container on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:00Z\" level=info msg=\"Pulling image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:55:03Z\" level=info msg=\"Image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:03Z\" level=info msg=\"Starting container [etcd] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:55:03Z\" level=info msg=\"[etcd] Successfully started [etcd] container on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:03Z\" level=info msg=\"[etcd] Running rolling snapshot container [etcd-snapshot-once] on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:03Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:22Z\" level=info msg=\"Starting container [etcd-rolling-snapshots] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:55:22Z\" level=info msg=\"[etcd] Successfully started [etcd-rolling-snapshots] container on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:27Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:27Z\" level=info msg=\"Starting container [rke-bundle-cert] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:55:28Z\" level=info msg=\"[certificates] Successfully started [rke-bundle-cert] container on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:28Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:28Z\" level=info msg=\"Container [rke-bundle-cert] is still running on host [10.x.x.3]: stderr: [], stdout: []\"", "time=\"2021-11-30T11:55:29Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:29Z\" level=info msg=\"[certificates] successfully saved certificate bundle [/opt/rke/etcd-snapshots//pki.bundle.tar.gz] on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:29Z\" level=info msg=\"Removing container [rke-bundle-cert] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:55:29Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:29Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:55:29Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:29Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:55:30Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:30Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:30Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:55:30Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:30Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:55:31Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"", "time=\"2021-11-30T11:55:31Z\" level=info msg=\"[etcd] Successfully started etcd plane.. Checking etcd cluster health\"", "time=\"2021-11-30T11:55:31Z\" level=info msg=\"[etcd] etcd host [10.x.x.1] reported healthy=true\"", "time=\"2021-11-30T11:55:31Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.1, try #1\"", "time=\"2021-11-30T11:55:56Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.2, try #1\"", "time=\"2021-11-30T11:56:21Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.3, try #1\"", "time=\"2021-11-30T11:56:46Z\" level=info msg=\"Attempting upgrade of controlplane components on following hosts in NotReady status: 10.x.x.1,10.x.x.2,10.x.x.3\"", "time=\"2021-11-30T11:56:46Z\" level=info msg=\"[controlplane] Building up Controller Plane..\"", "time=\"2021-11-30T11:56:47Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:56:47Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:56:47Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:56:47Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:56:47Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:56:47Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:56:47Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:56:47Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:56:47Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:56:47Z\" level=info msg=\"Starting container [kube-apiserver] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:56:47Z\" level=info msg=\"Starting container [kube-apiserver] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:56:47Z\" level=info msg=\"Starting container [kube-apiserver] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:56:48Z\" level=info msg=\"[controlplane] Successfully started [kube-apiserver] container on host [10.x.x.1]\"", "time=\"2021-11-30T11:56:48Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-apiserver] on host [10.x.x.1]\"", "time=\"2021-11-30T11:56:48Z\" level=info msg=\"[controlplane] Successfully started [kube-apiserver] container on host [10.x.x.2]\"", "time=\"2021-11-30T11:56:48Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-apiserver] on host [10.x.x.2]\"", "time=\"2021-11-30T11:56:48Z\" level=info msg=\"[controlplane] Successfully started [kube-apiserver] container on host [10.x.x.3]\"", "time=\"2021-11-30T11:56:48Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-apiserver] on host [10.x.x.3]\"", "time=\"2021-11-30T11:56:56Z\" level=info msg=\"[healthcheck] service [kube-apiserver] on host [10.x.x.2] is healthy\"", "time=\"2021-11-30T11:56:56Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:56:57Z\" level=info msg=\"[healthcheck] service [kube-apiserver] on host [10.x.x.3] is healthy\"", "time=\"2021-11-30T11:56:57Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:56:57Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:56:57Z\" level=info msg=\"[healthcheck] service [kube-apiserver] on host [10.x.x.1] is healthy\"", "time=\"2021-11-30T11:56:57Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:56:57Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:56:57Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.2]\"", "time=\"2021-11-30T11:56:57Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:56:57Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:56:57Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.3]\"", "time=\"2021-11-30T11:56:57Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:56:57Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"", "time=\"2021-11-30T11:56:57Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:56:57Z\" level=info msg=\"Starting container [kube-controller-manager] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:56:57Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.1]\"", "time=\"2021-11-30T11:56:58Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:56:58Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"", "time=\"2021-11-30T11:56:58Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:56:58Z\" level=info msg=\"Starting container [kube-controller-manager] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:56:58Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T11:56:58Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:56:58Z\" level=info msg=\"[controlplane] Successfully started [kube-controller-manager] container on host [10.x.x.2]\"", "time=\"2021-11-30T11:56:58Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-controller-manager] on host [10.x.x.2]\"", "time=\"2021-11-30T11:56:58Z\" level=info msg=\"Starting container [kube-controller-manager] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:56:58Z\" level=info msg=\"[controlplane] Successfully started [kube-controller-manager] container on host [10.x.x.3]\"", "time=\"2021-11-30T11:56:58Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-controller-manager] on host [10.x.x.3]\"", "time=\"2021-11-30T11:56:58Z\" level=info msg=\"[controlplane] Successfully started [kube-controller-manager] container on host [10.x.x.1]\"", "time=\"2021-11-30T11:56:58Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-controller-manager] on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:04Z\" level=info msg=\"[healthcheck] service [kube-controller-manager] on host [10.x.x.2] is healthy\"", "time=\"2021-11-30T11:57:04Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:04Z\" level=info msg=\"[healthcheck] service [kube-controller-manager] on host [10.x.x.3] is healthy\"", "time=\"2021-11-30T11:57:04Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:04Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:57:04Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:57:04Z\" level=info msg=\"[healthcheck] service [kube-controller-manager] on host [10.x.x.1] is healthy\"", "time=\"2021-11-30T11:57:04Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"Starting container [kube-scheduler] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"Starting container [kube-scheduler] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"[controlplane] Successfully started [kube-scheduler] container on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-scheduler] on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"Starting container [kube-scheduler] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"[controlplane] Successfully started [kube-scheduler] container on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:05Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-scheduler] on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:06Z\" level=info msg=\"[controlplane] Successfully started [kube-scheduler] container on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:06Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-scheduler] on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:07Z\" level=info msg=\"[healthcheck] service [kube-scheduler] on host [10.x.x.1] is healthy\"", "time=\"2021-11-30T11:57:07Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:07Z\" level=info msg=\"[healthcheck] service [kube-scheduler] on host [10.x.x.3] is healthy\"", "time=\"2021-11-30T11:57:07Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:07Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:57:07Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:57:07Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:07Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:57:07Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:07Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:57:07Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:08Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:11Z\" level=info msg=\"[healthcheck] service [kube-scheduler] on host [10.x.x.2] is healthy\"", "time=\"2021-11-30T11:57:11Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:12Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:57:12Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:12Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:57:12Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:12Z\" level=info msg=\"[controlplane] Successfully started Controller Plane..\"", "time=\"2021-11-30T11:57:12Z\" level=info msg=\"[worker] Building up Worker Plane..\"", "time=\"2021-11-30T11:57:12Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:57:12Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:57:12Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:57:12Z\" level=info msg=\"[sidekick] Sidekick container already created on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:12Z\" level=info msg=\"[sidekick] Sidekick container already created on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:12Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:12Z\" level=info msg=\"[sidekick] Sidekick container already created on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:12Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:12Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:12Z\" level=info msg=\"Starting container [kubelet] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:57:13Z\" level=info msg=\"Starting container [kubelet] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:57:13Z\" level=info msg=\"Starting container [kubelet] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:57:13Z\" level=info msg=\"[worker] Successfully started [kubelet] container on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:13Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kubelet] on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:13Z\" level=info msg=\"[worker] Successfully started [kubelet] container on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:13Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kubelet] on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:13Z\" level=info msg=\"[worker] Successfully started [kubelet] container on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:13Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kubelet] on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:24Z\" level=info msg=\"[healthcheck] service [kubelet] on host [10.x.x.2] is healthy\"", "time=\"2021-11-30T11:57:24Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"[healthcheck] service [kubelet] on host [10.x.x.3] is healthy\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"[healthcheck] service [kubelet] on host [10.x.x.1] is healthy\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"Starting container [kube-proxy] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"[worker] Successfully started [kube-proxy] container on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:25Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-proxy] on host [10.x.x.2]\"", "time=\"2021-11-30T11:57:26Z\" level=info msg=\"Starting container [kube-proxy] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:57:26Z\" level=info msg=\"Starting container [kube-proxy] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:57:26Z\" level=info msg=\"[worker] Successfully started [kube-proxy] container on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:26Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-proxy] on host [10.x.x.3]\"", "time=\"2021-11-30T11:57:26Z\" level=info msg=\"[worker] Successfully started [kube-proxy] container on host [10.x.x.1]\"", "time=\"2021-11-30T11:57:26Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-proxy] on host [10.x.x.1]\"", "time=\"2021-11-30T11:58:00Z\" level=info msg=\"[healthcheck] service [kube-proxy] on host [10.x.x.1] is healthy\"", "time=\"2021-11-30T11:58:00Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T11:58:00Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:58:01Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.1]\"", "time=\"2021-11-30T11:58:01Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T11:58:01Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T11:58:05Z\" level=info msg=\"[healthcheck] service [kube-proxy] on host [10.x.x.2] is healthy\"", "time=\"2021-11-30T11:58:05Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T11:58:06Z\" level=info msg=\"[healthcheck] service [kube-proxy] on host [10.x.x.3] is healthy\"", "time=\"2021-11-30T11:58:06Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T11:58:06Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:58:06Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:58:06Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.2]\"", "time=\"2021-11-30T11:58:06Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T11:58:06Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.3]\"", "time=\"2021-11-30T11:58:06Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T11:58:06Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"", "time=\"2021-11-30T11:58:07Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"", "time=\"2021-11-30T11:58:07Z\" level=info msg=\"[worker] Successfully started Worker Plane..\"", "time=\"2021-11-30T11:58:07Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.1, try #1\"", "time=\"2021-11-30T11:58:32Z\" level=error msg=\"Host 10.x.x.1 failed to report Ready status with error: [controlplane] Error getting node 10.x.x.1: \\"10.x.x.1\\" not found\"", "time=\"2021-11-30T11:58:32Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.2, try #1\"", "time=\"2021-11-30T11:58:57Z\" level=error msg=\"Host 10.x.x.2 failed to report Ready status with error: [controlplane] Error getting node 10.x.x.2: \\"10.x.x.2\\" not found\"", "time=\"2021-11-30T11:58:57Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.3, try #1\"", "time=\"2021-11-30T11:59:22Z\" level=error msg=\"Host 10.x.x.3 failed to report Ready status with error: [controlplane] Error getting node 10.x.x.3: \\"10.x.x.3\\" not found\"", "time=\"2021-11-30T11:59:22Z\" level=info msg=\"[controlplane] Processing controlplane hosts for upgrade 1 at a time\"", "time=\"2021-11-30T11:59:22Z\" level=info msg=\"Processing controlplane host 10.x.x.1\"", "time=\"2021-11-30T11:59:22Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.1, try #1\"", "time=\"2021-11-30T11:59:47Z\" level=error msg=\"Failed to upgrade hosts: 10.x.x.1 with error [[controlplane] Error getting node 10.x.x.1: \\"10.x.x.1\\" not found]\"", "time=\"2021-11-30T11:59:47Z\" level=fatal msg=\"[controlPlane] Failed to upgrade Control Plane: [[[controlplane] Error getting node 10.x.x.1: \\"10.x.x.1\\" not found]]\""]
I'm missing the output of the other 2 commands, please also share docker logs kubelet 2>&1
from 10.x.x.3
kubectl get pods -A --kubeconfig kubeconfig.yaml No resources found
kubectl get nodes -o wide --kubeconfig kubeconfig.yaml
No resources found
rke up \"Running RKE version: v1.3.1\"\ntime=\"2021-11-30T12:55:49Z\" level=info msg=\"Initiating Kubernetes cluster\"\ntime=\"2021-11-30T12:55:50Z\" level=info msg=\"[certificates] GenerateServingCertificate is disabled, checking if there are unused kubelet certificates\"\ntime=\"2021-11-30T12:55:50Z\" level=info msg=\"[certificates] Generating admin certificates and kubeconfig\"\ntime=\"2021-11-30T12:55:50Z\" level=info msg=\"Successfully Deployed state file at [./rancher-cluster.rkestate]\"\ntime=\"2021-11-30T12:55:50Z\" level=info msg=\"Building Kubernetes cluster\"\ntime=\"2021-11-30T12:55:50Z\" level=info msg=\"[dialer] Setup tunnel for host [10.x.x.3]\"\ntime=\"2021-11-30T12:55:50Z\" level=info msg=\"[dialer] Setup tunnel for host [10.x.x.1]\"\ntime=\"2021-11-30T12:55:50Z\" level=info msg=\"[dialer] Setup tunnel for host [10.x.x.2]\"\ntime=\"2021-11-30T12:55:50Z\" level=info msg=\"[certificates] Deploying kubernetes certificates to Cluster nodes\"\ntime=\"2021-11-30T12:55:50Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:55:50Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:55:50Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:55:50Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:55:50Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:55:50Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:55:51Z\" level=info msg=\"Starting container [cert-deployer] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:55:51Z\" level=info msg=\"Starting container [cert-deployer] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:55:51Z\" level=info msg=\"Starting container [cert-deployer] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:55:51Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:55:51Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:55:51Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:55:56Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:55:56Z\" level=info msg=\"Removing container [cert-deployer] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:55:56Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:55:56Z\" level=info msg=\"Removing container [cert-deployer] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:55:56Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:55:56Z\" level=info msg=\"Removing container [cert-deployer] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:55:56Z\" level=info msg=\"[reconcile] Rebuilding and updating local kube config\"\ntime=\"2021-11-30T12:55:56Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"\ntime=\"2021-11-30T12:55:56Z\" level=warning msg=\"[reconcile] host [10.x.x.1] is a control plane node without reachable Kubernetes API endpoint in the cluster\"\ntime=\"2021-11-30T12:55:56Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"\ntime=\"2021-11-30T12:55:56Z\" level=warning msg=\"[reconcile] host [10.x.x.2] is a control plane node without reachable Kubernetes API endpoint in the cluster\"\ntime=\"2021-11-30T12:55:56Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"\ntime=\"2021-11-30T12:55:56Z\" level=warning msg=\"[reconcile] host [10.x.x.3] is a control plane node without reachable Kubernetes API endpoint in the cluster\"\ntime=\"2021-11-30T12:55:56Z\" level=warning msg=\"[reconcile] no control plane node with reachable Kubernetes API endpoint in the cluster found\"\ntime=\"2021-11-30T12:55:56Z\" level=info msg=\"[certificates] Successfully deployed kubernetes certificates to Cluster nodes\"\ntime=\"2021-11-30T12:55:56Z\" level=info msg=\"[file-deploy] Deploying file [/etc/kubernetes/audit-policy.yaml] to node [10.x.x.1]\"\ntime=\"2021-11-30T12:55:56Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:55:56Z\" level=info msg=\"Starting container [file-deployer] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:55:57Z\" level=info msg=\"Successfully started [file-deployer] container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:55:57Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.1]\"\ntime=\"2021-11-30T12:55:57Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.1]\"\ntime=\"2021-11-30T12:55:57Z\" level=info msg=\"Container [file-deployer] is still running on host [10.x.x.1]: stderr: [], stdout: []\"\ntime=\"2021-11-30T12:55:58Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.1]\"\ntime=\"2021-11-30T12:55:58Z\" level=info msg=\"Removing container [file-deployer] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:55:58Z\" level=info msg=\"[remove/file-deployer] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:55:58Z\" level=info msg=\"[file-deploy] Deploying file [/etc/kubernetes/audit-policy.yaml] to node [10.x.x.2]\"\ntime=\"2021-11-30T12:55:58Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:55:58Z\" level=info msg=\"Starting container [file-deployer] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:55:59Z\" level=info msg=\"Successfully started [file-deployer] container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:55:59Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.2]\"\ntime=\"2021-11-30T12:55:59Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.2]\"\ntime=\"2021-11-30T12:55:59Z\" level=info msg=\"Container [file-deployer] is still running on host [10.x.x.2]: stderr: [], stdout: []\"\ntime=\"2021-11-30T12:56:00Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:00Z\" level=info msg=\"Removing container [file-deployer] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:56:00Z\" level=info msg=\"[remove/file-deployer] Successfully removed container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:00Z\" level=info msg=\"[file-deploy] Deploying file [/etc/kubernetes/audit-policy.yaml] to node [10.x.x.3]\"\ntime=\"2021-11-30T12:56:00Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:56:00Z\" level=info msg=\"Starting container [file-deployer] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:56:01Z\" level=info msg=\"Successfully started [file-deployer] container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:56:01Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.3]\"\ntime=\"2021-11-30T12:56:01Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.3]\"\ntime=\"2021-11-30T12:56:01Z\" level=info msg=\"Container [file-deployer] is still running on host [10.x.x.3]: stderr: [], stdout: []\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.3]\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"Removing container [file-deployer] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"[remove/file-deployer] Successfully removed container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"[/etc/kubernetes/audit-policy.yaml] Successfully deployed audit policy file to Cluster control nodes\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"[reconcile] Reconciling cluster state\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"[reconcile] Check etcd hosts to be deleted\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"[reconcile] Check etcd hosts to be added\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"[reconcile] Rebuilding and updating local kube config\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"\ntime=\"2021-11-30T12:56:02Z\" level=warning msg=\"[reconcile] host [10.x.x.1] is a control plane node without reachable Kubernetes API endpoint in the cluster\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"\ntime=\"2021-11-30T12:56:02Z\" level=warning msg=\"[reconcile] host [10.x.x.2] is a control plane node without reachable Kubernetes API endpoint in the cluster\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"\ntime=\"2021-11-30T12:56:02Z\" level=warning msg=\"[reconcile] host [10.x.x.3] is a control plane node without reachable Kubernetes API endpoint in the cluster\"\ntime=\"2021-11-30T12:56:02Z\" level=warning msg=\"[reconcile] no control plane node with reachable Kubernetes API endpoint in the cluster found\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"[reconcile] Reconciled cluster state successfully\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"max_unavailable_worker got rounded down to 0, resetting to 1\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"Setting maxUnavailable for worker nodes to: 1\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"Setting maxUnavailable for controlplane nodes to: 1\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"Pre-pulling kubernetes images\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"Kubernetes images pulled successfully\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"[etcd] Building up etcd plane..\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:02Z\" level=info msg=\"Starting container [etcd-fix-perm] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:56:03Z\" level=info msg=\"Successfully started [etcd-fix-perm] container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:03Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:03Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:03Z\" level=info msg=\"Container [etcd-fix-perm] is still running on host [10.x.x.1]: stderr: [], stdout: []\"\ntime=\"2021-11-30T12:56:04Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:04Z\" level=info msg=\"Removing container [etcd-fix-perm] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:56:04Z\" level=info msg=\"[remove/etcd-fix-perm] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:04Z\" level=info msg=\"Image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:04Z\" level=info msg=\"Starting container [etcd] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:56:05Z\" level=info msg=\"[etcd] Successfully started [etcd] container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:05Z\" level=info msg=\"[etcd] Running rolling snapshot container [etcd-snapshot-once] on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:05Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:05Z\" level=info msg=\"Starting container [etcd-rolling-snapshots] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:56:05Z\" level=info msg=\"[etcd] Successfully started [etcd-rolling-snapshots] container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:10Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:11Z\" level=info msg=\"Starting container [rke-bundle-cert] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:56:11Z\" level=info msg=\"[certificates] Successfully started [rke-bundle-cert] container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:11Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:11Z\" level=info msg=\"Container [rke-bundle-cert] is still running on host [10.x.x.1]: stderr: [], stdout: []\"\ntime=\"2021-11-30T12:56:12Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:12Z\" level=info msg=\"[certificates] successfully saved certificate bundle [/opt/rke/etcd-snapshots//pki.bundle.tar.gz] on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:12Z\" level=info msg=\"Removing container [rke-bundle-cert] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:56:12Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:12Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:56:13Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:13Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:56:13Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:13Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:13Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:56:14Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:14Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:56:14Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:56:14Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:14Z\" level=info msg=\"Starting container [etcd-fix-perm] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:56:15Z\" level=info msg=\"Successfully started [etcd-fix-perm] container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:15Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:15Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:15Z\" level=info msg=\"Container [etcd-fix-perm] is still running on host [10.x.x.2]: stderr: [], stdout: []\"\ntime=\"2021-11-30T12:56:16Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:16Z\" level=info msg=\"Removing container [etcd-fix-perm] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:56:16Z\" level=info msg=\"[remove/etcd-fix-perm] Successfully removed container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:16Z\" level=info msg=\"Image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:16Z\" level=info msg=\"Starting container [etcd] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:56:17Z\" level=info msg=\"[etcd] Successfully started [etcd] container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:17Z\" level=info msg=\"[etcd] Running rolling snapshot container [etcd-snapshot-once] on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:17Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:46Z\" level=info msg=\"Starting container [etcd-rolling-snapshots] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:56:47Z\" level=info msg=\"[etcd] Successfully started [etcd-rolling-snapshots] container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:52Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:52Z\" level=info msg=\"Starting container [rke-bundle-cert] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:56:52Z\" level=info msg=\"[certificates] Successfully started [rke-bundle-cert] container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:52Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:52Z\" level=info msg=\"Container [rke-bundle-cert] is still running on host [10.x.x.2]: stderr: [], stdout: []\"\ntime=\"2021-11-30T12:56:53Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:53Z\" level=info msg=\"[certificates] successfully saved certificate bundle [/opt/rke/etcd-snapshots//pki.bundle.tar.gz] on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:53Z\" level=info msg=\"Removing container [rke-bundle-cert] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:56:53Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:54Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:56:54Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:54Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:56:54Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:54Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:55Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:56:55Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:55Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:56:55Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:56:56Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:56:56Z\" level=info msg=\"Starting container [etcd-fix-perm] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:56:57Z\" level=info msg=\"Successfully started [etcd-fix-perm] container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:56:57Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.3]\"\ntime=\"2021-11-30T12:56:57Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.3]\"\ntime=\"2021-11-30T12:56:57Z\" level=info msg=\"Container [etcd-fix-perm] is still running on host [10.x.x.3]: stderr: [], stdout: []\"\ntime=\"2021-11-30T12:56:58Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.3]\"\ntime=\"2021-11-30T12:56:58Z\" level=info msg=\"Removing container [etcd-fix-perm] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:56:58Z\" level=info msg=\"[remove/etcd-fix-perm] Successfully removed container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:56:58Z\" level=info msg=\"Image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:56:58Z\" level=info msg=\"Starting container [etcd] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:56:59Z\" level=info msg=\"[etcd] Successfully started [etcd] container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:56:59Z\" level=info msg=\"[etcd] Running rolling snapshot container [etcd-snapshot-once] on host [10.x.x.3]\"\ntime=\"2021-11-30T12:56:59Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:56:59Z\" level=info msg=\"Starting container [etcd-rolling-snapshots] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:56:59Z\" level=info msg=\"[etcd] Successfully started [etcd-rolling-snapshots] container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:57:04Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:57:04Z\" level=info msg=\"Starting container [rke-bundle-cert] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:57:05Z\" level=info msg=\"[certificates] Successfully started [rke-bundle-cert] container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:57:05Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.3]\"\ntime=\"2021-11-30T12:57:05Z\" level=info msg=\"Container [rke-bundle-cert] is still running on host [10.x.x.3]: stderr: [], stdout: []\"\ntime=\"2021-11-30T12:57:06Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.3]\"\ntime=\"2021-11-30T12:57:06Z\" level=info msg=\"[certificates] successfully saved certificate bundle [/opt/rke/etcd-snapshots//pki.bundle.tar.gz] on host [10.x.x.3]\"\ntime=\"2021-11-30T12:57:06Z\" level=info msg=\"Removing container [rke-bundle-cert] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:57:06Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:57:21Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:57:22Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:57:22Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:57:22Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:57:22Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:57:22Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:57:23Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:57:23Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:57:23Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:57:23Z\" level=info msg=\"[etcd] Successfully started etcd plane.. Checking etcd cluster health\"\ntime=\"2021-11-30T12:57:24Z\" level=info msg=\"[etcd] etcd host [10.x.x.1] reported healthy=true\"\ntime=\"2021-11-30T12:57:24Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.1, try #1\"\ntime=\"2021-11-30T12:57:49Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.2, try #1\"\ntime=\"2021-11-30T12:58:14Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.3, try #1\"\ntime=\"2021-11-30T12:58:39Z\" level=info msg=\"Attempting upgrade of controlplane components on following hosts in NotReady status: 10.x.x.1,10.x.x.2,10.x.x.3\"\ntime=\"2021-11-30T12:58:39Z\" level=info msg=\"[controlplane] Building up Controller Plane..\"\ntime=\"2021-11-30T12:58:39Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:58:39Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:58:39Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:58:39Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:58:39Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:58:39Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:40Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:58:40Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:58:40Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:40Z\" level=info msg=\"Starting container [kube-apiserver] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:58:40Z\" level=info msg=\"Starting container [kube-apiserver] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:58:40Z\" level=info msg=\"Starting container [kube-apiserver] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:58:40Z\" level=info msg=\"[controlplane] Successfully started [kube-apiserver] container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:58:40Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-apiserver] on host [10.x.x.2]\"\ntime=\"2021-11-30T12:58:40Z\" level=info msg=\"[controlplane] Successfully started [kube-apiserver] container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:40Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-apiserver] on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:40Z\" level=info msg=\"[controlplane] Successfully started [kube-apiserver] container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:58:40Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-apiserver] on host [10.x.x.1]\"\ntime=\"2021-11-30T12:58:49Z\" level=info msg=\"[healthcheck] service [kube-apiserver] on host [10.x.x.2] is healthy\"\ntime=\"2021-11-30T12:58:49Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:58:49Z\" level=info msg=\"[healthcheck] service [kube-apiserver] on host [10.x.x.1] is healthy\"\ntime=\"2021-11-30T12:58:49Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:58:49Z\" level=info msg=\"[healthcheck] service [kube-apiserver] on host [10.x.x.3] is healthy\"\ntime=\"2021-11-30T12:58:49Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:49Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:58:49Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:58:49Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:58:50Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:58:50Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:58:50Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:58:50Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:58:50Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:50Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:58:50Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:58:50Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:58:50Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:58:50Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:58:50Z\" level=info msg=\"Starting container [kube-controller-manager] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:58:50Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:50Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:50Z\" level=info msg=\"Starting container [kube-controller-manager] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:58:50Z\" level=info msg=\"Starting container [kube-controller-manager] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:58:51Z\" level=info msg=\"[controlplane] Successfully started [kube-controller-manager] container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:58:51Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-controller-manager] on host [10.x.x.2]\"\ntime=\"2021-11-30T12:58:51Z\" level=info msg=\"[controlplane] Successfully started [kube-controller-manager] container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:58:51Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-controller-manager] on host [10.x.x.1]\"\ntime=\"2021-11-30T12:58:51Z\" level=info msg=\"[controlplane] Successfully started [kube-controller-manager] container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:51Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-controller-manager] on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:52Z\" level=info msg=\"[healthcheck] service [kube-controller-manager] on host [10.x.x.3] is healthy\"\ntime=\"2021-11-30T12:58:52Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:52Z\" level=info msg=\"[healthcheck] service [kube-controller-manager] on host [10.x.x.1] is healthy\"\ntime=\"2021-11-30T12:58:52Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:58:52Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:58:52Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:58:52Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:52Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:58:53Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:58:53Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:58:53Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:53Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:53Z\" level=info msg=\"Starting container [kube-scheduler] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:58:53Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:58:53Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:58:53Z\" level=info msg=\"Starting container [kube-scheduler] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:58:53Z\" level=info msg=\"[controlplane] Successfully started [kube-scheduler] container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:53Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-scheduler] on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:53Z\" level=info msg=\"[controlplane] Successfully started [kube-scheduler] container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:58:53Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-scheduler] on host [10.x.x.1]\"\ntime=\"2021-11-30T12:58:54Z\" level=info msg=\"[healthcheck] service [kube-scheduler] on host [10.x.x.3] is healthy\"\ntime=\"2021-11-30T12:58:54Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:55Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:58:55Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:55Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:58:55Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:58:57Z\" level=info msg=\"[healthcheck] service [kube-controller-manager] on host [10.x.x.2] is healthy\"\ntime=\"2021-11-30T12:58:57Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:58:57Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:58:58Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:58:58Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:58:58Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:58:58Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:58:58Z\" level=info msg=\"Starting container [kube-scheduler] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:58:58Z\" level=info msg=\"[controlplane] Successfully started [kube-scheduler] container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:58:58Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-scheduler] on host [10.x.x.2]\"\ntime=\"2021-11-30T12:58:59Z\" level=info msg=\"[healthcheck] service [kube-scheduler] on host [10.x.x.2] is healthy\"\ntime=\"2021-11-30T12:58:59Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:58:59Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:59:00Z\" level=info msg=\"[healthcheck] service [kube-scheduler] on host [10.x.x.1] is healthy\"\ntime=\"2021-11-30T12:59:00Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:59:00Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:59:00Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:59:00Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:59:00Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:59:00Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:59:00Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"[controlplane] Successfully started Controller Plane..\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"[worker] Building up Worker Plane..\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"[sidekick] Sidekick container already created on host [10.x.x.2]\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"[sidekick] Sidekick container already created on host [10.x.x.1]\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"[sidekick] Sidekick container already created on host [10.x.x.3]\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"Starting container [kubelet] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"Starting container [kubelet] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"Starting container [kubelet] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"[worker] Successfully started [kubelet] container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kubelet] on host [10.x.x.2]\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"[worker] Successfully started [kubelet] container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kubelet] on host [10.x.x.1]\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"[worker] Successfully started [kubelet] container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:59:01Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kubelet] on host [10.x.x.3]\"\ntime=\"2021-11-30T12:59:13Z\" level=info msg=\"[healthcheck] service [kubelet] on host [10.x.x.2] is healthy\"\ntime=\"2021-11-30T12:59:13Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:59:13Z\" level=info msg=\"[healthcheck] service [kubelet] on host [10.x.x.1] is healthy\"\ntime=\"2021-11-30T12:59:13Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:59:13Z\" level=info msg=\"[healthcheck] service [kubelet] on host [10.x.x.3] is healthy\"\ntime=\"2021-11-30T12:59:13Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:59:13Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:59:13Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:59:13Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:59:13Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:59:14Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:59:14Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:59:14Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:59:14Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"\ntime=\"2021-11-30T12:59:14Z\" level=info msg=\"Starting container [kube-proxy] on host [10.x.x.2], try #1\"\ntime=\"2021-11-30T12:59:14Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:59:14Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"\ntime=\"2021-11-30T12:59:14Z\" level=info msg=\"[worker] Successfully started [kube-proxy] container on host [10.x.x.2]\"\ntime=\"2021-11-30T12:59:14Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-proxy] on host [10.x.x.2]\"\ntime=\"2021-11-30T12:59:14Z\" level=info msg=\"Starting container [kube-proxy] on host [10.x.x.3], try #1\"\ntime=\"2021-11-30T12:59:14Z\" level=info msg=\"[worker] Successfully started [kube-proxy] container on host [10.x.x.3]\"\ntime=\"2021-11-30T12:59:14Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-proxy] on host [10.x.x.3]\"\ntime=\"2021-11-30T12:59:33Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:59:34Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:59:34Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:59:34Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:59:34Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T12:59:34Z\" level=info msg=\"Starting container [kube-proxy] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T12:59:35Z\" level=info msg=\"[worker] Successfully started [kube-proxy] container on host [10.x.x.1]\"\ntime=\"2021-11-30T12:59:35Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-proxy] on host [10.x.x.1]\"\ntime=\"2021-11-30T13:00:14Z\" level=info msg=\"[healthcheck] service [kube-proxy] on host [10.x.x.1] is healthy\"\ntime=\"2021-11-30T13:00:14Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"\ntime=\"2021-11-30T13:00:15Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T13:00:15Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.1]\"\ntime=\"2021-11-30T13:00:15Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"\ntime=\"2021-11-30T13:00:16Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"\ntime=\"2021-11-30T13:00:16Z\" level=error msg=\"Failed to upgrade worker components on NotReady hosts, error: [Failed to verify healthcheck: Failed to check http://localhost:10256/healthz for service [kube-proxy] on host [10.x.x.2]: Get \\"http://localhost:10256/healthz\\": Unable to access the service on localhost:10256. The service might be still starting up. Error: ssh: rejected: connect failed (Connection refused), log: I1130 13:00:10.615491 13676 proxier.go:826] syncProxyRules took 28.141668ms]\"\ntime=\"2021-11-30T13:00:16Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.1, try #1\"\ntime=\"2021-11-30T13:00:41Z\" level=error msg=\"Host 10.x.x.1 failed to report Ready status with error: [controlplane] Error getting node 10.x.x.1: \\"10.x.x.1\\" not found\"\ntime=\"2021-11-30T13:00:41Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.2, try #1\"\ntime=\"2021-11-30T13:01:06Z\" level=error msg=\"Host 10.x.x.2 failed to report Ready status with error: [controlplane] Error getting node 10.x.x.2: \\"10.x.x.2\\" not found\"\ntime=\"2021-11-30T13:01:06Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.3, try #1\"\ntime=\"2021-11-30T13:01:31Z\" level=error msg=\"Host 10.x.x.3 failed to report Ready status with error: [controlplane] Error getting node 10.x.x.3: \\"10.x.x.3\\" not found\"\ntime=\"2021-11-30T13:01:31Z\" level=info msg=\"[controlplane] Processing controlplane hosts for upgrade 1 at a time\"\ntime=\"2021-11-30T13:01:31Z\" level=info msg=\"Processing controlplane host 10.x.x.1\"\ntime=\"2021-11-30T13:01:31Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.1, try #1\"\ntime=\"2021-11-30T13:01:56Z\" level=error msg=\"Failed to upgrade hosts: 10.x.x.1 with error [[controlplane] Error getting node 10.x.x.1: \\"10.x.x.1\\" not found]\"\ntime=\"2021-11-30T13:01:56Z\" level=fatal msg=\"[controlPlane] Failed to upgrade Control Plane: [[[controlplane] Error getting node 10.x.x.1: \\"10.x.x.1\\" not found]]\"", "stdout_lines": ["time=\"2021-11-30T12:55:49Z\" level=info msg=\"Running RKE version: v1.3.1\"", "time=\"2021-11-30T12:55:49Z\" level=info msg=\"Initiating Kubernetes cluster\"", "time=\"2021-11-30T12:55:50Z\" level=info msg=\"[certificates] GenerateServingCertificate is disabled, checking if there are unused kubelet certificates\"", "time=\"2021-11-30T12:55:50Z\" level=info msg=\"[certificates] Generating admin certificates and kubeconfig\"", "time=\"2021-11-30T12:55:50Z\" level=info msg=\"Successfully Deployed state file at [./rancher-cluster.rkestate]\"", "time=\"2021-11-30T12:55:50Z\" level=info msg=\"Building Kubernetes cluster\"", "time=\"2021-11-30T12:55:50Z\" level=info msg=\"[dialer] Setup tunnel for host [10.x.x.3]\"", "time=\"2021-11-30T12:55:50Z\" level=info msg=\"[dialer] Setup tunnel for host [10.x.x.1]\"", "time=\"2021-11-30T12:55:50Z\" level=info msg=\"[dialer] Setup tunnel for host [10.x.x.2]\"", "time=\"2021-11-30T12:55:50Z\" level=info msg=\"[certificates] Deploying kubernetes certificates to Cluster nodes\"", "time=\"2021-11-30T12:55:50Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:55:50Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:55:50Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:55:50Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:55:50Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:55:50Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:55:51Z\" level=info msg=\"Starting container [cert-deployer] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:55:51Z\" level=info msg=\"Starting container [cert-deployer] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:55:51Z\" level=info msg=\"Starting container [cert-deployer] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:55:51Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:55:51Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:55:51Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:55:56Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:55:56Z\" level=info msg=\"Removing container [cert-deployer] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:55:56Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:55:56Z\" level=info msg=\"Removing container [cert-deployer] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:55:56Z\" level=info msg=\"Checking if container [cert-deployer] is running on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:55:56Z\" level=info msg=\"Removing container [cert-deployer] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:55:56Z\" level=info msg=\"[reconcile] Rebuilding and updating local kube config\"", "time=\"2021-11-30T12:55:56Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"", "time=\"2021-11-30T12:55:56Z\" level=warning msg=\"[reconcile] host [10.x.x.1] is a control plane node without reachable Kubernetes API endpoint in the cluster\"", "time=\"2021-11-30T12:55:56Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"", "time=\"2021-11-30T12:55:56Z\" level=warning msg=\"[reconcile] host [10.x.x.2] is a control plane node without reachable Kubernetes API endpoint in the cluster\"", "time=\"2021-11-30T12:55:56Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"", "time=\"2021-11-30T12:55:56Z\" level=warning msg=\"[reconcile] host [10.x.x.3] is a control plane node without reachable Kubernetes API endpoint in the cluster\"", "time=\"2021-11-30T12:55:56Z\" level=warning msg=\"[reconcile] no control plane node with reachable Kubernetes API endpoint in the cluster found\"", "time=\"2021-11-30T12:55:56Z\" level=info msg=\"[certificates] Successfully deployed kubernetes certificates to Cluster nodes\"", "time=\"2021-11-30T12:55:56Z\" level=info msg=\"[file-deploy] Deploying file [/etc/kubernetes/audit-policy.yaml] to node [10.x.x.1]\"", "time=\"2021-11-30T12:55:56Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:55:56Z\" level=info msg=\"Starting container [file-deployer] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:55:57Z\" level=info msg=\"Successfully started [file-deployer] container on host [10.x.x.1]\"", "time=\"2021-11-30T12:55:57Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.1]\"", "time=\"2021-11-30T12:55:57Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.1]\"", "time=\"2021-11-30T12:55:57Z\" level=info msg=\"Container [file-deployer] is still running on host [10.x.x.1]: stderr: [], stdout: []\"", "time=\"2021-11-30T12:55:58Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.1]\"", "time=\"2021-11-30T12:55:58Z\" level=info msg=\"Removing container [file-deployer] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:55:58Z\" level=info msg=\"[remove/file-deployer] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T12:55:58Z\" level=info msg=\"[file-deploy] Deploying file [/etc/kubernetes/audit-policy.yaml] to node [10.x.x.2]\"", "time=\"2021-11-30T12:55:58Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:55:58Z\" level=info msg=\"Starting container [file-deployer] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:55:59Z\" level=info msg=\"Successfully started [file-deployer] container on host [10.x.x.2]\"", "time=\"2021-11-30T12:55:59Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.2]\"", "time=\"2021-11-30T12:55:59Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.2]\"", "time=\"2021-11-30T12:55:59Z\" level=info msg=\"Container [file-deployer] is still running on host [10.x.x.2]: stderr: [], stdout: []\"", "time=\"2021-11-30T12:56:00Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:00Z\" level=info msg=\"Removing container [file-deployer] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:56:00Z\" level=info msg=\"[remove/file-deployer] Successfully removed container on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:00Z\" level=info msg=\"[file-deploy] Deploying file [/etc/kubernetes/audit-policy.yaml] to node [10.x.x.3]\"", "time=\"2021-11-30T12:56:00Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:56:00Z\" level=info msg=\"Starting container [file-deployer] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:56:01Z\" level=info msg=\"Successfully started [file-deployer] container on host [10.x.x.3]\"", "time=\"2021-11-30T12:56:01Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.3]\"", "time=\"2021-11-30T12:56:01Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.3]\"", "time=\"2021-11-30T12:56:01Z\" level=info msg=\"Container [file-deployer] is still running on host [10.x.x.3]: stderr: [], stdout: []\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"Waiting for [file-deployer] container to exit on host [10.x.x.3]\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"Removing container [file-deployer] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"[remove/file-deployer] Successfully removed container on host [10.x.x.3]\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"[/etc/kubernetes/audit-policy.yaml] Successfully deployed audit policy file to Cluster control nodes\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"[reconcile] Reconciling cluster state\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"[reconcile] Check etcd hosts to be deleted\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"[reconcile] Check etcd hosts to be added\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"[reconcile] Rebuilding and updating local kube config\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"", "time=\"2021-11-30T12:56:02Z\" level=warning msg=\"[reconcile] host [10.x.x.1] is a control plane node without reachable Kubernetes API endpoint in the cluster\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"", "time=\"2021-11-30T12:56:02Z\" level=warning msg=\"[reconcile] host [10.x.x.2] is a control plane node without reachable Kubernetes API endpoint in the cluster\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"Successfully Deployed local admin kubeconfig at [./kube_config_rancher-cluster.yml]\"", "time=\"2021-11-30T12:56:02Z\" level=warning msg=\"[reconcile] host [10.x.x.3] is a control plane node without reachable Kubernetes API endpoint in the cluster\"", "time=\"2021-11-30T12:56:02Z\" level=warning msg=\"[reconcile] no control plane node with reachable Kubernetes API endpoint in the cluster found\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"[reconcile] Reconciled cluster state successfully\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"max_unavailable_worker got rounded down to 0, resetting to 1\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"Setting maxUnavailable for worker nodes to: 1\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"Setting maxUnavailable for controlplane nodes to: 1\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"Pre-pulling kubernetes images\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"Kubernetes images pulled successfully\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"[etcd] Building up etcd plane..\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:02Z\" level=info msg=\"Starting container [etcd-fix-perm] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:56:03Z\" level=info msg=\"Successfully started [etcd-fix-perm] container on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:03Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:03Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:03Z\" level=info msg=\"Container [etcd-fix-perm] is still running on host [10.x.x.1]: stderr: [], stdout: []\"", "time=\"2021-11-30T12:56:04Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:04Z\" level=info msg=\"Removing container [etcd-fix-perm] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:56:04Z\" level=info msg=\"[remove/etcd-fix-perm] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:04Z\" level=info msg=\"Image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:04Z\" level=info msg=\"Starting container [etcd] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:56:05Z\" level=info msg=\"[etcd] Successfully started [etcd] container on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:05Z\" level=info msg=\"[etcd] Running rolling snapshot container [etcd-snapshot-once] on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:05Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:05Z\" level=info msg=\"Starting container [etcd-rolling-snapshots] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:56:05Z\" level=info msg=\"[etcd] Successfully started [etcd-rolling-snapshots] container on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:10Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:11Z\" level=info msg=\"Starting container [rke-bundle-cert] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:56:11Z\" level=info msg=\"[certificates] Successfully started [rke-bundle-cert] container on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:11Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:11Z\" level=info msg=\"Container [rke-bundle-cert] is still running on host [10.x.x.1]: stderr: [], stdout: []\"", "time=\"2021-11-30T12:56:12Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:12Z\" level=info msg=\"[certificates] successfully saved certificate bundle [/opt/rke/etcd-snapshots//pki.bundle.tar.gz] on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:12Z\" level=info msg=\"Removing container [rke-bundle-cert] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:56:12Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:12Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:56:13Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:13Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:56:13Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:13Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:13Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:56:14Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:14Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:56:14Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T12:56:14Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:14Z\" level=info msg=\"Starting container [etcd-fix-perm] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:56:15Z\" level=info msg=\"Successfully started [etcd-fix-perm] container on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:15Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:15Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:15Z\" level=info msg=\"Container [etcd-fix-perm] is still running on host [10.x.x.2]: stderr: [], stdout: []\"", "time=\"2021-11-30T12:56:16Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:16Z\" level=info msg=\"Removing container [etcd-fix-perm] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:56:16Z\" level=info msg=\"[remove/etcd-fix-perm] Successfully removed container on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:16Z\" level=info msg=\"Image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:16Z\" level=info msg=\"Starting container [etcd] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:56:17Z\" level=info msg=\"[etcd] Successfully started [etcd] container on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:17Z\" level=info msg=\"[etcd] Running rolling snapshot container [etcd-snapshot-once] on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:17Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:46Z\" level=info msg=\"Starting container [etcd-rolling-snapshots] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:56:47Z\" level=info msg=\"[etcd] Successfully started [etcd-rolling-snapshots] container on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:52Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:52Z\" level=info msg=\"Starting container [rke-bundle-cert] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:56:52Z\" level=info msg=\"[certificates] Successfully started [rke-bundle-cert] container on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:52Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:52Z\" level=info msg=\"Container [rke-bundle-cert] is still running on host [10.x.x.2]: stderr: [], stdout: []\"", "time=\"2021-11-30T12:56:53Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:53Z\" level=info msg=\"[certificates] successfully saved certificate bundle [/opt/rke/etcd-snapshots//pki.bundle.tar.gz] on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:53Z\" level=info msg=\"Removing container [rke-bundle-cert] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:56:53Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:54Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:56:54Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:54Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:56:54Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:54Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:55Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:56:55Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:55Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:56:55Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"", "time=\"2021-11-30T12:56:56Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:56:56Z\" level=info msg=\"Starting container [etcd-fix-perm] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:56:57Z\" level=info msg=\"Successfully started [etcd-fix-perm] container on host [10.x.x.3]\"", "time=\"2021-11-30T12:56:57Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.3]\"", "time=\"2021-11-30T12:56:57Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.3]\"", "time=\"2021-11-30T12:56:57Z\" level=info msg=\"Container [etcd-fix-perm] is still running on host [10.x.x.3]: stderr: [], stdout: []\"", "time=\"2021-11-30T12:56:58Z\" level=info msg=\"Waiting for [etcd-fix-perm] container to exit on host [10.x.x.3]\"", "time=\"2021-11-30T12:56:58Z\" level=info msg=\"Removing container [etcd-fix-perm] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:56:58Z\" level=info msg=\"[remove/etcd-fix-perm] Successfully removed container on host [10.x.x.3]\"", "time=\"2021-11-30T12:56:58Z\" level=info msg=\"Image [rancher/mirrored-coreos-etcd:v3.4.15-rancher1] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:56:58Z\" level=info msg=\"Starting container [etcd] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:56:59Z\" level=info msg=\"[etcd] Successfully started [etcd] container on host [10.x.x.3]\"", "time=\"2021-11-30T12:56:59Z\" level=info msg=\"[etcd] Running rolling snapshot container [etcd-snapshot-once] on host [10.x.x.3]\"", "time=\"2021-11-30T12:56:59Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:56:59Z\" level=info msg=\"Starting container [etcd-rolling-snapshots] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:56:59Z\" level=info msg=\"[etcd] Successfully started [etcd-rolling-snapshots] container on host [10.x.x.3]\"", "time=\"2021-11-30T12:57:04Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:57:04Z\" level=info msg=\"Starting container [rke-bundle-cert] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:57:05Z\" level=info msg=\"[certificates] Successfully started [rke-bundle-cert] container on host [10.x.x.3]\"", "time=\"2021-11-30T12:57:05Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.3]\"", "time=\"2021-11-30T12:57:05Z\" level=info msg=\"Container [rke-bundle-cert] is still running on host [10.x.x.3]: stderr: [], stdout: []\"", "time=\"2021-11-30T12:57:06Z\" level=info msg=\"Waiting for [rke-bundle-cert] container to exit on host [10.x.x.3]\"", "time=\"2021-11-30T12:57:06Z\" level=info msg=\"[certificates] successfully saved certificate bundle [/opt/rke/etcd-snapshots//pki.bundle.tar.gz] on host [10.x.x.3]\"", "time=\"2021-11-30T12:57:06Z\" level=info msg=\"Removing container [rke-bundle-cert] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:57:06Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:57:21Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:57:22Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.3]\"", "time=\"2021-11-30T12:57:22Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:57:22Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"", "time=\"2021-11-30T12:57:22Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:57:22Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:57:23Z\" level=info msg=\"[etcd] Successfully started [rke-log-linker] container on host [10.x.x.3]\"", "time=\"2021-11-30T12:57:23Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:57:23Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"", "time=\"2021-11-30T12:57:23Z\" level=info msg=\"[etcd] Successfully started etcd plane.. Checking etcd cluster health\"", "time=\"2021-11-30T12:57:24Z\" level=info msg=\"[etcd] etcd host [10.x.x.1] reported healthy=true\"", "time=\"2021-11-30T12:57:24Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.1, try #1\"", "time=\"2021-11-30T12:57:49Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.2, try #1\"", "time=\"2021-11-30T12:58:14Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.3, try #1\"", "time=\"2021-11-30T12:58:39Z\" level=info msg=\"Attempting upgrade of controlplane components on following hosts in NotReady status: 10.x.x.1,10.x.x.2,10.x.x.3\"", "time=\"2021-11-30T12:58:39Z\" level=info msg=\"[controlplane] Building up Controller Plane..\"", "time=\"2021-11-30T12:58:39Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:58:39Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:58:39Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:58:39Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:58:39Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:58:39Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:40Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:58:40Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:58:40Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:40Z\" level=info msg=\"Starting container [kube-apiserver] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:58:40Z\" level=info msg=\"Starting container [kube-apiserver] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:58:40Z\" level=info msg=\"Starting container [kube-apiserver] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:58:40Z\" level=info msg=\"[controlplane] Successfully started [kube-apiserver] container on host [10.x.x.2]\"", "time=\"2021-11-30T12:58:40Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-apiserver] on host [10.x.x.2]\"", "time=\"2021-11-30T12:58:40Z\" level=info msg=\"[controlplane] Successfully started [kube-apiserver] container on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:40Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-apiserver] on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:40Z\" level=info msg=\"[controlplane] Successfully started [kube-apiserver] container on host [10.x.x.1]\"", "time=\"2021-11-30T12:58:40Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-apiserver] on host [10.x.x.1]\"", "time=\"2021-11-30T12:58:49Z\" level=info msg=\"[healthcheck] service [kube-apiserver] on host [10.x.x.2] is healthy\"", "time=\"2021-11-30T12:58:49Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:58:49Z\" level=info msg=\"[healthcheck] service [kube-apiserver] on host [10.x.x.1] is healthy\"", "time=\"2021-11-30T12:58:49Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:58:49Z\" level=info msg=\"[healthcheck] service [kube-apiserver] on host [10.x.x.3] is healthy\"", "time=\"2021-11-30T12:58:49Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:49Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:58:49Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:58:49Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:58:50Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.2]\"", "time=\"2021-11-30T12:58:50Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:58:50Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.1]\"", "time=\"2021-11-30T12:58:50Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:58:50Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:50Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:58:50Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"", "time=\"2021-11-30T12:58:50Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:58:50Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T12:58:50Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:58:50Z\" level=info msg=\"Starting container [kube-controller-manager] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:58:50Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:50Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:50Z\" level=info msg=\"Starting container [kube-controller-manager] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:58:50Z\" level=info msg=\"Starting container [kube-controller-manager] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:58:51Z\" level=info msg=\"[controlplane] Successfully started [kube-controller-manager] container on host [10.x.x.2]\"", "time=\"2021-11-30T12:58:51Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-controller-manager] on host [10.x.x.2]\"", "time=\"2021-11-30T12:58:51Z\" level=info msg=\"[controlplane] Successfully started [kube-controller-manager] container on host [10.x.x.1]\"", "time=\"2021-11-30T12:58:51Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-controller-manager] on host [10.x.x.1]\"", "time=\"2021-11-30T12:58:51Z\" level=info msg=\"[controlplane] Successfully started [kube-controller-manager] container on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:51Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-controller-manager] on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:52Z\" level=info msg=\"[healthcheck] service [kube-controller-manager] on host [10.x.x.3] is healthy\"", "time=\"2021-11-30T12:58:52Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:52Z\" level=info msg=\"[healthcheck] service [kube-controller-manager] on host [10.x.x.1] is healthy\"", "time=\"2021-11-30T12:58:52Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:58:52Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:58:52Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:58:52Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:52Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:58:53Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.1]\"", "time=\"2021-11-30T12:58:53Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:58:53Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:53Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:53Z\" level=info msg=\"Starting container [kube-scheduler] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:58:53Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T12:58:53Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:58:53Z\" level=info msg=\"Starting container [kube-scheduler] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:58:53Z\" level=info msg=\"[controlplane] Successfully started [kube-scheduler] container on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:53Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-scheduler] on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:53Z\" level=info msg=\"[controlplane] Successfully started [kube-scheduler] container on host [10.x.x.1]\"", "time=\"2021-11-30T12:58:53Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-scheduler] on host [10.x.x.1]\"", "time=\"2021-11-30T12:58:54Z\" level=info msg=\"[healthcheck] service [kube-scheduler] on host [10.x.x.3] is healthy\"", "time=\"2021-11-30T12:58:54Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:55Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:58:55Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:55Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:58:55Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"", "time=\"2021-11-30T12:58:57Z\" level=info msg=\"[healthcheck] service [kube-controller-manager] on host [10.x.x.2] is healthy\"", "time=\"2021-11-30T12:58:57Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:58:57Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:58:58Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.2]\"", "time=\"2021-11-30T12:58:58Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:58:58Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"", "time=\"2021-11-30T12:58:58Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:58:58Z\" level=info msg=\"Starting container [kube-scheduler] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:58:58Z\" level=info msg=\"[controlplane] Successfully started [kube-scheduler] container on host [10.x.x.2]\"", "time=\"2021-11-30T12:58:58Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-scheduler] on host [10.x.x.2]\"", "time=\"2021-11-30T12:58:59Z\" level=info msg=\"[healthcheck] service [kube-scheduler] on host [10.x.x.2] is healthy\"", "time=\"2021-11-30T12:58:59Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:58:59Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:59:00Z\" level=info msg=\"[healthcheck] service [kube-scheduler] on host [10.x.x.1] is healthy\"", "time=\"2021-11-30T12:59:00Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:59:00Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.2]\"", "time=\"2021-11-30T12:59:00Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:59:00Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:59:00Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"", "time=\"2021-11-30T12:59:00Z\" level=info msg=\"[controlplane] Successfully started [rke-log-linker] container on host [10.x.x.1]\"", "time=\"2021-11-30T12:59:00Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"[controlplane] Successfully started Controller Plane..\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"[worker] Building up Worker Plane..\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"Checking if container [service-sidekick] is running on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"[sidekick] Sidekick container already created on host [10.x.x.2]\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"[sidekick] Sidekick container already created on host [10.x.x.1]\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"[sidekick] Sidekick container already created on host [10.x.x.3]\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"Starting container [kubelet] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"Starting container [kubelet] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"Starting container [kubelet] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"[worker] Successfully started [kubelet] container on host [10.x.x.2]\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kubelet] on host [10.x.x.2]\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"[worker] Successfully started [kubelet] container on host [10.x.x.1]\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kubelet] on host [10.x.x.1]\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"[worker] Successfully started [kubelet] container on host [10.x.x.3]\"", "time=\"2021-11-30T12:59:01Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kubelet] on host [10.x.x.3]\"", "time=\"2021-11-30T12:59:13Z\" level=info msg=\"[healthcheck] service [kubelet] on host [10.x.x.2] is healthy\"", "time=\"2021-11-30T12:59:13Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:59:13Z\" level=info msg=\"[healthcheck] service [kubelet] on host [10.x.x.1] is healthy\"", "time=\"2021-11-30T12:59:13Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:59:13Z\" level=info msg=\"[healthcheck] service [kubelet] on host [10.x.x.3] is healthy\"", "time=\"2021-11-30T12:59:13Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:59:13Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:59:13Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:59:13Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.2]\"", "time=\"2021-11-30T12:59:13Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:59:14Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.3]\"", "time=\"2021-11-30T12:59:14Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:59:14Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.2]\"", "time=\"2021-11-30T12:59:14Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.2]\"", "time=\"2021-11-30T12:59:14Z\" level=info msg=\"Starting container [kube-proxy] on host [10.x.x.2], try #1\"", "time=\"2021-11-30T12:59:14Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.3]\"", "time=\"2021-11-30T12:59:14Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.3]\"", "time=\"2021-11-30T12:59:14Z\" level=info msg=\"[worker] Successfully started [kube-proxy] container on host [10.x.x.2]\"", "time=\"2021-11-30T12:59:14Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-proxy] on host [10.x.x.2]\"", "time=\"2021-11-30T12:59:14Z\" level=info msg=\"Starting container [kube-proxy] on host [10.x.x.3], try #1\"", "time=\"2021-11-30T12:59:14Z\" level=info msg=\"[worker] Successfully started [kube-proxy] container on host [10.x.x.3]\"", "time=\"2021-11-30T12:59:14Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-proxy] on host [10.x.x.3]\"", "time=\"2021-11-30T12:59:33Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:59:34Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.1]\"", "time=\"2021-11-30T12:59:34Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:59:34Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T12:59:34Z\" level=info msg=\"Image [rancher/hyperkube:v1.20.11-rancher1] exists on host [10.x.x.1]\"", "time=\"2021-11-30T12:59:34Z\" level=info msg=\"Starting container [kube-proxy] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T12:59:35Z\" level=info msg=\"[worker] Successfully started [kube-proxy] container on host [10.x.x.1]\"", "time=\"2021-11-30T12:59:35Z\" level=info msg=\"[healthcheck] Start Healthcheck on service [kube-proxy] on host [10.x.x.1]\"", "time=\"2021-11-30T13:00:14Z\" level=info msg=\"[healthcheck] service [kube-proxy] on host [10.x.x.1] is healthy\"", "time=\"2021-11-30T13:00:14Z\" level=info msg=\"Image [rancher/rke-tools:v0.1.78] exists on host [10.x.x.1]\"", "time=\"2021-11-30T13:00:15Z\" level=info msg=\"Starting container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T13:00:15Z\" level=info msg=\"[worker] Successfully started [rke-log-linker] container on host [10.x.x.1]\"", "time=\"2021-11-30T13:00:15Z\" level=info msg=\"Removing container [rke-log-linker] on host [10.x.x.1], try #1\"", "time=\"2021-11-30T13:00:16Z\" level=info msg=\"[remove/rke-log-linker] Successfully removed container on host [10.x.x.1]\"", "time=\"2021-11-30T13:00:16Z\" level=error msg=\"Failed to upgrade worker components on NotReady hosts, error: [Failed to verify healthcheck: Failed to check http://localhost:10256/healthz for service [kube-proxy] on host [10.x.x.2]: Get \\"http://localhost:10256/healthz\\": Unable to access the service on localhost:10256. The service might be still starting up. Error: ssh: rejected: connect failed (Connection refused), log: I1130 13:00:10.615491 13676 proxier.go:826] syncProxyRules took 28.141668ms]\"", "time=\"2021-11-30T13:00:16Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.1, try #1\"", "time=\"2021-11-30T13:00:41Z\" level=error msg=\"Host 10.x.x.1 failed to report Ready status with error: [controlplane] Error getting node 10.x.x.1: \\"10.x.x.1\\" not found\"", "time=\"2021-11-30T13:00:41Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.2, try #1\"", "time=\"2021-11-30T13:01:06Z\" level=error msg=\"Host 10.x.x.2 failed to report Ready status with error: [controlplane] Error getting node 10.x.x.2: \\"10.x.x.2\\" not found\"", "time=\"2021-11-30T13:01:06Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.3, try #1\"", "time=\"2021-11-30T13:01:31Z\" level=error msg=\"Host 10.x.x.3 failed to report Ready status with error: [controlplane] Error getting node 10.x.x.3: \\"10.x.x.3\\" not found\"", "time=\"2021-11-30T13:01:31Z\" level=info msg=\"[controlplane] Processing controlplane hosts for upgrade 1 at a time\"", "time=\"2021-11-30T13:01:31Z\" level=info msg=\"Processing controlplane host 10.x.x.1\"", "time=\"2021-11-30T13:01:31Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.1, try #1\"", "time=\"2021-11-30T13:01:56Z\" level=error msg=\"Failed to upgrade hosts: 10.x.x.1 with error [[controlplane] Error getting node 10.x.x.1: \\"10.x.x.1\\" not found]\"", "time=\"2021-11-30T13:01:56Z\" level=fatal msg=\"[controlPlane] Failed to upgrade Control Plane: [[[controlplane] Error getting node 10.x.x.1: \\"10.x.x.1\\" not found]]\""]}
docker logs kubelet 2>&1
Ok, this at least explains why it is happening but we also need docker logs kube-apiserver 2>&1
from the node to see if it logs why it is happening.
Is this a new cluster or was it an existing one? If it is already a new cluster does it reproduce if you start from scratch as well?
This is a new cluster and it has been failing since the start. Here are the logs
docker logs kube-apiserver 2>&1
RKE version: v1.3.1
Docker version: (
docker version
,docker info
preferred) Client: Docker Engine - Community Version: 20.10.11 API version: 1.40 Go version: go1.16.9 Git commit: dea9396 Built: Thu Nov 18 00:37:08 2021 OS/Arch: linux/amd64 Context: default Experimental: trueServer: Docker Engine - Community Engine: Version: 19.03.6 API version: 1.40 (minimum version 1.12) Go version: go1.12.16 Git commit: 369ce74a3c Built: Thu Feb 13 01:26:21 2020 OS/Arch: linux/amd64 Experimental: false containerd: Version: 1.4.12 GitCommit: 7b11cfaabd73bb80907dd23182b9347b4245eb5d runc: Version: 1.0.2 GitCommit: v1.0.2-0-g52b36a2 docker-init: Version: 0.18.0 GitCommit: fec3683
Operating system and kernel: (
cat /etc/os-release
,uname -r
preferred) cat /etc/os-release NAME="Ubuntu" VERSION="18.04.4 LTS (Bionic Beaver)" ID=ubuntu ID_LIKE=debian PRETTY_NAME="Ubuntu 18.04.4 LTS" VERSION_ID="18.04" HOME_URL="https://www.ubuntu.com/" SUPPORT_URL="https://help.ubuntu.com/" BUG_REPORT_URL="https://bugs.launchpad.net/ubuntu/" PRIVACY_POLICY_URL="https://www.ubuntu.com/legal/terms-and-policies/privacy-policy" VERSION_CODENAME=bionic UBUNTU_CODENAME=bionicuname -r 4.15.0-88-generic
Type/provider of hosts: (VirtualBox/Bare-metal/AWS/GCE/DO) VmWare VM
cluster.yml file: nodes:
services: etcd: snapshot: true creation: 6h retention: 24h
addon_job_timeout: 100 system_images: etcd: "rancher/mirrored-coreos-etcd:v3.4.15-rancher1" kubernetes: "rancher/hyperkube:v1.18.20-rancher1" alpine: "rancher/rke-tools:v0.1.75" nginxproxy: "rancher/rke-tools:v0.1.75" certdownloader: "rancher/rke-tools:v0.1.75" kubernetesservicessidecar: "rancher/rke-tools:v0.1.75" kubedns: "rancher/mirrored-k8s-dns-kube-dns:1.15.2" dnsmasq: "rancher/mirrored-k8s-dns-dnsmasq-nanny:1.15.2" kubednssidecar: "rancher/mirrored-k8s-dns-sidecar:1.15.2" kubednsautoscaler: "rancher/mirrored-cluster-proportional-autoscaler:1.7.1" flannel: "rancher/mirrored-coreos-flannel:v0.12.0" flannelcni: "rancher/flannel-cni:v0.3.0-rancher6" caliconode: "rancher/mirrored-calico-node:v3.13.4" calicocni: "rancher/mirrored-calico-cni:v3.13.4" calicocontrollers: "rancher/mirrored-calico-kube-controllers:v3.13.4" calicoctl: "rancher/mirrored-calico-ctl:v3.13.4" calicoflexvol: "rancher/mirrored-calico-pod2daemon-flexvol:v3.13.4" canalnode: "rancher/mirrored-calico-node:v3.13.4" canalcni: "rancher/mirrored-calico-cni:v3.13.4" canalflannel: "rancher/mirrored-coreos-flannel:v0.12.0" canalflexvol: "rancher/mirrored-calico-pod2daemon-flexvol:v3.13.4" weavenode: "weaveworks/weave-kube:2.6.4" weavecni: "weaveworks/weave-npc:2.6.4" acicnideploycontainer: "noiro/cnideploy:5.1.1.0.1ae238a" acihostcontainer: "noiro/aci-containers-host:5.1.1.0.1ae238a" aciopflexcontainer: "noiro/opflex:5.1.1.0.1ae238a" acimcastcontainer: "noiro/opflex:5.1.1.0.1ae238a" aciopenvswitchcontainer: "noiro/openvswitch:5.1.1.0.1ae238a" acicontrollercontainer: "noiro/aci-containers-controller:5.1.1.0.1ae238a" acigbpservercontainer: "noiro/gbp-server:5.1.1.0.1ae238a" aciopflexservercontainer: "noiro/opflex-server:5.1.1.0.1ae238a" podinfracontainer: "rancher/mirrored-pause:3.1" ingress: "rancher/nginx-ingress-controller:nginx-0.35.0-rancher2" ingressbackend: "rancher/mirrored-nginx-ingress-controller-defaultbackend:1.5-rancher1" metricsserver: "rancher/mirrored-metrics-server:v0.3.6" coredns: "rancher/mirrored-coredns-coredns:1.6.9" corednsautoscaler: "rancher/mirrored-cluster-proportional-autoscaler:1.7.1" windowspodinfracontainer: "rancher/kubelet-pause:v0.1.6" nodelocal: "rancher/mirrored-k8s-dns-node-cache:1.15.7"
network: plugin: canal
Steps to Reproduce: rke up --config config.yaml
Results: Network containers (k8s_autoscaler_coredns-autoscaler, k8s_kube-flannel_canal, k8s_calico-node_canal-, k8s_POD_coredns-autoscaler, k8s_flexvol-driver_canal, k8s_install-cni_canal, k8s_POD_canal) don't start which results in creation failure.
snippet of RKE up output log: Now checking status of node , try rancher/rke#1\"", "time=\"2021-11-29T15:12:10Z\" level=error msg=\"Host 10.53.5.62 failed to report Ready status with error: [controlplane] Error getting node : \\"10.x.x.x.\\" not found\"", "time=\"2021-11-29T15:12:10Z\" level=info msg=\"[controlplane] Processing controlplane hosts for upgrade 1 at a time\"", "time=\"2021-11-29T15:12:10Z\" level=info msg=\"Processing controlplane host 10.x.x.x\"", "time=\"2021-11-29T15:12:10Z\" level=info msg=\"[controlplane] Now checking status of node 10.x.x.x, try rancher/rke#1\"", "time=\"2021-11-29T15:12:35Z\" level=error msg=\"Failed to upgrade hosts: 10.53.5.60 with error [[controlplane] Error getting node 10.x.x.x: \\"10.x.x.x\\" not found]\"", "time=\"2021-11-29T15:12:35Z\" level=fatal msg=\"[controlPlane] Failed to upgrade Control Plane: [[[controlplane] Error getting node 10.53.5.60: \\"10.x.x.x\\" not found]]\"