Rancher UI can't be reached

Well since it says up 6 seconds, it’s probably crashing in a loop… look at the logs.

No not 6 seconds, 6 minutes and I deleted the first rancher container after I installed Portainer

Look at your screenshot. Created 6 minutes ago, up 6 seconds.

I have the exact same problem… Just install latest Ubuntu 20.04.2, latest docker 20.10.7 and rancher/rancher: latest
The rancher server UI refused the connection…
A month ago, when I first installed it, it was working just fine. Then I need to installed it on the 2nd machine, and it no longer be able to access the admin UI

1 Like

I will not come into a forum and ask a question if I did not spend hours before I try to fix my issues…

rancher/server:stable works fine in port 8080, but I want a specific UI that my favorite youtuber use and he definitly don’t use rancher/server, by the way I don’t know where are the cluster into that server UI.

Hi,

I had the same problem yesterday after upgrading to the last kernel “Ubuntu 20.04 LTS : Linux 5.4.0-80-generic”.
After rolling back to the n-1 version of the kernel (5.4.0-77-generic) i was able to restart my rancher (rancher 2.5.9 single node on docker 20.10.7).

If needed :

You can roll back the kernel version easily via the “grub” menu : on boot, choose the grub menu et the menu “Advanced”, and choose “5.4.0-77” kernel version.

After, in linux remove the 5.4.0-80 kernel files via :
sudo apt remove linux-image-5.4.0-80-generic linux-headers-5.4.0-80-generic linux-image-unsigned-5.4.0-80-generic

and finally :
sudo apt autoremove

TiTidom.

That is interesting, I guess I learned something, but I installed the 20.04 so in the advanced menu of the grub there is only 5.4.0-80-generic and 5.4.0-80-generic (recovery mode) even if I tried to remove linux-image-5.4.0-80-generic. I guess I should install a previous version of Ubuntu.

Not sure if I understand your advice. I custom the port to fit my arrangement, certainly not 8080 but something else however it still point to port 80 and 443 of the server. I did search on this forum but return none result so far.
I doubt that it has something to do with the updates from Ubuntu itself lately (in a month or so), or the docker, or even the rancher… As I said, a month ago, I was able to get everything works in one go… not this time.
I will try the roll-back the Ubuntu updates per TiTidom’s post, and would let you know

rancher server command
I don’t tell you to do this, I just tell you that this command line works for me while I still can’t accecss the UI of rancher/rancher just like you.

I repeat but hey why not, rancher/server is not rancher/rancher, with rancher/server you need to set a host on another machine, this is a nightmare for a noob like me with kubernetes, I don’t recommend it, I can’t even access the rancher UI

8080 and rancher/server (vs rancher/rancher) are for 1.x and ancient/no longer maintained.

My point about the 6 seconds is that means it’s crashing and being restarted, which means the log probably says something relevant, but you still haven’t looked (or at least shown us).

The log

{"log":"I0726 08:03:38.621010      33 server_others.go:186] Using iptables Proxier.\n","stream":"stderr","time":"2021-07-26T08:03:38.621042631Z"}
{"log":"I0726 08:03:38.621124      33 server.go:650] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:03:38.621152201Z"}
{"log":"I0726 08:03:38.622410      33 conntrack.go:103] Set sysctl 'net/netfilter/nf_conntrack_max' to 196608\n","stream":"stderr","time":"2021-07-26T08  :03:38.622447957Z"}
{"log":"F0726 08:03:38.622424      33 server.go:495] open /proc/sys/net/netfilter/nf_conntrack_max: permission denied\n","stream":"stderr","time":"2021-  07-26T08:03:38.622453877Z"}
{"log":"2021/07/26 08:03:38 [FATAL] k3s exited with: exit status 1\n","stream":"stdout","time":"2021-07-26T08:03:38.62569003Z"}
{"log":"2021/07/26 08:03:39 [INFO] Rancher version v2.5.9 (3c5418944) is starting\n","stream":"stdout","time":"2021-07-26T08:03:39.35145985Z"}
{"log":"2021/07/26 08:03:39 [INFO] Listening on /tmp/log.sock\n","stream":"stdout","time":"2021-07-26T08:03:39.35148656Z"}
{"log":"2021/07/26 08:03:39 [INFO] Rancher arguments {ACMEDomains:[] AddLocal:true Embedded:false BindHost: HTTPListenPort:80 HTTPSListenPort:443 K8sMod  e:auto Debug:false Trace:false NoCACerts:false AuditLogPath:/var/log/auditlog/rancher-api-audit.log AuditLogMaxage:10 AuditLogMaxsize:100 AuditLogMaxbac  kup:10 AuditLevel:0 Agent:false Features: ClusterRegistry:}\n","stream":"stdout","time":"2021-07-26T08:03:39.35149096Z"}
{"log":"2021/07/26 08:03:39 [INFO] Running etcd --data-dir=management-state/etcd --heartbeat-interval=500 --election-timeout=5000\n","stream":"stdout","  time":"2021-07-26T08:03:39.351744339Z"}
{"log":"2021-07-26 08:03:39.359624 W | pkg/flags: unrecognized environment variable ETCD_URL_arm64=https://github.com/etcd-io/etcd/releases/download/v3.  4.3/etcd-v3.4.3-linux-arm64.tar.gz\n","stream":"stderr","time":"2021-07-26T08:03:39.35971127Z"}
{"log":"2021-07-26 08:03:39.359651 W | pkg/flags: unrecognized environment variable ETCD_URL_amd64=https://github.com/etcd-io/etcd/releases/download/v3.  4.3/etcd-v3.4.3-linux-amd64.tar.gz\n","stream":"stderr","time":"2021-07-26T08:03:39.35972207Z"}
{"log":"2021-07-26 08:03:39.359655 W | pkg/flags: unrecognized environment variable ETCD_UNSUPPORTED_ARCH=amd64\n","stream":"stderr","time":"2021-07-26T  08:03:39.35972609Z"}
{"log":"2021-07-26 08:03:39.359665 W | pkg/flags: unrecognized environment variable ETCD_URL=ETCD_URL_amd64\n","stream":"stderr","time":"2021-07-26T08:0  3:39.35974706Z"}
{"log":"[WARNING] Deprecated '--logger=capnslog' flag is set; use '--logger=zap' flag instead\n","stream":"stderr","time":"2021-07-26T08:03:39.3597495Z"  }
{"log":"2021-07-26 08:03:39.359694 I | etcdmain: etcd Version: 3.4.3\n","stream":"stderr","time":"2021-07-26T08:03:39.35975131Z"}
{"log":"2021-07-26 08:03:39.359704 I | etcdmain: Git SHA: 3cf2f69b5\n","stream":"stderr","time":"2021-07-26T08:03:39.35975533Z"}
{"log":"2021-07-26 08:03:39.359708 I | etcdmain: Go Version: go1.12.12\n","stream":"stderr","time":"2021-07-26T08:03:39.35975731Z"}
{"log":"2021-07-26 08:03:39.359717 I | etcdmain: Go OS/Arch: linux/amd64\n","stream":"stderr","time":"2021-07-26T08:03:39.35975919Z"}
{"log":"2021-07-26 08:03:39.359722 I | etcdmain: setting maximum number of CPUs to 6, total number of available CPUs is 6\n","stream":"stderr","time":"2  021-07-26T08:03:39.35976101Z"}
{"log":"2021-07-26 08:03:39.359782 N | etcdmain: the server is already initialized as member before, starting as etcd member...\n","stream":"stderr","ti  me":"2021-07-26T08:03:39.35984985Z"}
{"log":"[WARNING] Deprecated '--logger=capnslog' flag is set; use '--logger=zap' flag instead\n","stream":"stderr","time":"2021-07-26T08:03:39.35987119Z  "}
{"log":"2021-07-26 08:03:39.360137 I | embed: name = default\n","stream":"stderr","time":"2021-07-26T08:03:39.36020122Z"}
{"log":"2021-07-26 08:03:39.360149 I | embed: data dir = management-state/etcd\n","stream":"stderr","time":"2021-07-26T08:03:39.36021762Z"}
{"log":"2021-07-26 08:03:39.360153 I | embed: member dir = management-state/etcd/member\n","stream":"stderr","time":"2021-07-26T08:03:39.36022417Z"}
{"log":"2021-07-26 08:03:39.360157 I | embed: heartbeat = 500ms\n","stream":"stderr","time":"2021-07-26T08:03:39.36022982Z"}
{"log":"2021-07-26 08:03:39.360165 I | embed: election = 5000ms\n","stream":"stderr","time":"2021-07-26T08:03:39.3602354Z"}
{"log":"2021-07-26 08:03:39.360174 I | embed: snapshot count = 100000\n","stream":"stderr","time":"2021-07-26T08:03:39.36024082Z"}
{"log":"2021-07-26 08:03:39.360188 I | embed: advertise client URLs = http://localhost:2379\n","stream":"stderr","time":"2021-07-26T08:03:39.36024809Z"}
{"log":"2021-07-26 08:03:39.360198 I | embed: initial advertise peer URLs = http://localhost:2380\n","stream":"stderr","time":"2021-07-26T08:03:39.36025  366Z"}
{"log":"2021-07-26 08:03:39.360210 I | embed: initial cluster = \n","stream":"stderr","time":"2021-07-26T08:03:39.36025892Z"}
{"log":"2021-07-26 08:03:39.365259 I | etcdserver: restarting member 8e9e05c52164694d in cluster cdf818194e3a8c32 at commit index 1227\n","stream":"stde  rr","time":"2021-07-26T08:03:39.365328058Z"}
{"log":"raft2021/07/26 08:03:39 INFO: 8e9e05c52164694d switched to configuration voters=()\n","stream":"stderr","time":"2021-07-26T08:03:39.365554497Z"}
{"log":"raft2021/07/26 08:03:39 INFO: 8e9e05c52164694d became follower at term 37\n","stream":"stderr","time":"2021-07-26T08:03:39.365561567Z"}
{"log":"raft2021/07/26 08:03:39 INFO: newRaft 8e9e05c52164694d [peers: [], term: 37, commit: 1227, applied: 0, lastindex: 1227, lastterm: 37]\n","stream  ":"stderr","time":"2021-07-26T08:03:39.365564797Z"}
{"log":"2021-07-26 08:03:39.369615 W | auth: simple token is not cryptographically signed\n","stream":"stderr","time":"2021-07-26T08:03:39.369703947Z"}
{"log":"2021-07-26 08:03:39.371408 I | etcdserver: starting server... [version: 3.4.3, cluster version: to_be_decided]\n","stream":"stderr","time":"2021  -07-26T08:03:39.371477522Z"}
{"log":"raft2021/07/26 08:03:39 INFO: 8e9e05c52164694d switched to configuration voters=(10276657743932975437)\n","stream":"stderr","time":"2021-07-26T0  8:03:39.371779223Z"}
{"log":"2021-07-26 08:03:39.371805 I | etcdserver/membership: added member 8e9e05c52164694d [http://localhost:2380] to cluster cdf818194e3a8c32\n","stre  am":"stderr","time":"2021-07-26T08:03:39.371856662Z"}
{"log":"2021-07-26 08:03:39.371877 N | etcdserver/membership: set the initial cluster version to 3.4\n","stream":"stderr","time":"2021-07-26T08:03:39.37  1936172Z"}
{"log":"2021-07-26 08:03:39.371904 I | etcdserver/api: enabled capabilities for version 3.4\n","stream":"stderr","time":"2021-07-26T08:03:39.371953492Z"  }
{"log":"2021-07-26 08:03:39.374065 I | embed: listening for peers on 127.0.0.1:2380\n","stream":"stderr","time":"2021-07-26T08:03:39.374166546Z"}
{"log":"raft2021/07/26 08:03:46 INFO: 8e9e05c52164694d is starting a new election at term 37\n","stream":"stderr","time":"2021-07-26T08:03:46.366074523Z  "}
{"log":"raft2021/07/26 08:03:46 INFO: 8e9e05c52164694d became candidate at term 38\n","stream":"stderr","time":"2021-07-26T08:03:46.366102683Z"}
{"log":"raft2021/07/26 08:03:46 INFO: 8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 38\n","stream":"stderr","time":"2021-07-26T08:  03:46.366108103Z"}
{"log":"raft2021/07/26 08:03:46 INFO: 8e9e05c52164694d became leader at term 38\n","stream":"stderr","time":"2021-07-26T08:03:46.366112203Z"}
{"log":"raft2021/07/26 08:03:46 INFO: raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 38\n","stream":"stderr","time":"2021-07-26T08:  03:46.366115972Z"}
{"log":"2021-07-26 08:03:46.368863 I | embed: ready to serve client requests\n","stream":"stderr","time":"2021-07-26T08:03:46.368975046Z"}
{"log":"2021-07-26 08:03:46.368900 I | etcdserver: published {Name:default ClientURLs:[http://localhost:2379]} to cluster cdf818194e3a8c32\n","stream":"  stderr","time":"2021-07-26T08:03:46.368988066Z"}
{"log":"2021-07-26 08:03:46.369614 N | embed: serving insecure client requests on 127.0.0.1:2379, this is strongly discouraged!\n","stream":"stderr","ti  me":"2021-07-26T08:03:46.369701714Z"}
{"log":"2021/07/26 08:03:46 [INFO] Waiting for server to become available: Get \"https://127.0.0.1:6443/version?timeout=15m0s\": dial tcp 127.0.0.1:6443  : connect: connection refused\n","stream":"stdout","time":"2021-07-26T08:03:46.383175671Z"}
{"log":"time=\"2021-07-26T08:03:46.544224297Z\" level=info msg=\"Starting k3s v1.19.8+k3s1 (95fc76b2)\"\n","stream":"stderr","time":"2021-07-26T08:03:46  .544363086Z"}
{"log":"time=\"2021-07-26T08:03:46.544378316Z\" level=info msg=\"Cluster bootstrap already complete\"\n","stream":"stderr","time":"2021-07-26T08:03:46.5  44458566Z"}
{"log":"time=\"2021-07-26T08:03:46.552062518Z\" level=info msg=\"Running kube-apiserver --advertise-port=6443 --allow-privileged=true --anonymous-auth=f  alse --api-audiences=unknown --authorization-mode=Node,RBAC --bind-address=127.0.0.1 --cert-dir=/var/lib/rancher/k3s/server/tls/temporary-certs --client  -ca-file=/var/lib/rancher/k3s/server/tls/client-ca.crt --enable-admission-plugins=NodeRestriction --etcd-servers=http://localhost:2379 --insecure-port=0   --kubelet-certificate-authority=/var/lib/rancher/k3s/server/tls/server-ca.crt --kubelet-client-certificate=/var/lib/rancher/k3s/server/tls/client-kube-  apiserver.crt --kubelet-client-key=/var/lib/rancher/k3s/server/tls/client-kube-apiserver.key --profiling=false --proxy-client-cert-file=/var/lib/rancher  /k3s/server/tls/client-auth-proxy.crt --proxy-client-key-file=/var/lib/rancher/k3s/server/tls/client-auth-proxy.key --requestheader-allowed-names=system  :auth-proxy --requestheader-client-ca-file=/var/lib/rancher/k3s/server/tls/request-header-ca.crt --requestheader-extra-headers-prefix=X-Remote-Extra- --  requestheader-group-headers=X-Remote-Group --requestheader-username-headers=X-Remote-User --secure-port=6444 --service-account-issuer=k3s --service-acco  unt-key-file=/var/lib/rancher/k3s/server/tls/service.key --service-account-signing-key-file=/var/lib/rancher/k3s/server/tls/service.key --service-cluste  r-ip-range=10.43.0.0/16 --storage-backend=etcd3 --tls-cert-file=/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.crt --tls-private-key-file=/var/l  ib/rancher/k3s/server/tls/serving-kube-apiserver.key\"\n","stream":"stderr","time":"2021-07-26T08:03:46.552174628Z"}
{"log":"I0726 08:03:46.552698      35 server.go:652] external host was not specified, using 172.17.0.2\n","stream":"stderr","time":"2021-07-26T08:03:46.  552770886Z"}
{"log":"I0726 08:03:46.552853      35 server.go:177] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:03:46.552911836Z"}
{"log":"I0726 08:03:46.555552      35 plugins.go:158] Loaded 12 mutating admission controller(s) successfully in the following order: NamespaceLifecycle  ,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,Run  timeClass,DefaultIngressClass,MutatingAdmissionWebhook.\n","stream":"stderr","time":"2021-07-26T08:03:46.55562372Z"}
{"log":"I0726 08:03:46.555562      35 plugins.go:161] Loaded 10 validating admission controller(s) successfully in the following order: LimitRanger,Serv  iceAccount,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,CertificateSubjectRestriction,ValidatingAdmissionWeb  hook,ResourceQuota.\n","stream":"stderr","time":"2021-07-26T08:03:46.55563548Z"}
{"log":"I0726 08:03:46.556118      35 plugins.go:158] Loaded 12 mutating admission controller(s) successfully in the following order: NamespaceLifecycle  ,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,Run  timeClass,DefaultIngressClass,MutatingAdmissionWebhook.\n","stream":"stderr","time":"2021-07-26T08:03:46.556185948Z"}
{"log":"I0726 08:03:46.556126      35 plugins.go:161] Loaded 10 validating admission controller(s) successfully in the following order: LimitRanger,Serv  iceAccount,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,CertificateSubjectRestriction,ValidatingAdmissionWeb  hook,ResourceQuota.\n","stream":"stderr","time":"2021-07-26T08:03:46.556192628Z"}
{"log":"I0726 08:03:46.577726      35 master.go:271] Using reconciler: lease\n","stream":"stderr","time":"2021-07-26T08:03:46.577854697Z"}
{"log":"W0726 08:03:46.864644      35 genericapiserver.go:412] Skipping API batch/v2alpha1 because it has no resources.\n","stream":"stderr","time":"202  1-07-26T08:03:46.864763282Z"}
{"log":"W0726 08:03:46.873745      35 genericapiserver.go:412] Skipping API discovery.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr",  "time":"2021-07-26T08:03:46.87385191Z"}
{"log":"W0726 08:03:46.883268      35 genericapiserver.go:412] Skipping API node.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr","time  ":"2021-07-26T08:03:46.883348538Z"}
{"log":"W0726 08:03:46.893515      35 genericapiserver.go:412] Skipping API rbac.authorization.k8s.io/v1alpha1 because it has no resources.\n","stream":  "stderr","time":"2021-07-26T08:03:46.893590563Z"}
{"log":"W0726 08:03:46.895617      35 genericapiserver.go:412] Skipping API scheduling.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr"  ,"time":"2021-07-26T08:03:46.895680608Z"}
{"log":"W0726 08:03:46.904610      35 genericapiserver.go:412] Skipping API storage.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr","t  ime":"2021-07-26T08:03:46.904680857Z"}
{"log":"W0726 08:03:46.916235      35 genericapiserver.go:412] Skipping API apps/v1beta2 because it has no resources.\n","stream":"stderr","time":"2021-  07-26T08:03:46.916317799Z"}
{"log":"W0726 08:03:46.916250      35 genericapiserver.go:412] Skipping API apps/v1beta1 because it has no resources.\n","stream":"stderr","time":"2021-  07-26T08:03:46.916331159Z"}
{"log":"I0726 08:03:46.923062      35 plugins.go:158] Loaded 12 mutating admission controller(s) successfully in the following order: NamespaceLifecycle  ,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,Run  timeClass,DefaultIngressClass,MutatingAdmissionWebhook.\n","stream":"stderr","time":"2021-07-26T08:03:46.923160403Z"}
{"log":"I0726 08:03:46.923078      35 plugins.go:161] Loaded 10 validating admission controller(s) successfully in the following order: LimitRanger,Serv  iceAccount,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,CertificateSubjectRestriction,ValidatingAdmissionWeb  hook,ResourceQuota.\n","stream":"stderr","time":"2021-07-26T08:03:46.923181523Z"}
{"log":"time=\"2021-07-26T08:03:46.930599045Z\" level=info msg=\"Running kube-scheduler --address=127.0.0.1 --bind-address=127.0.0.1 --kubeconfig=/var/l  ib/rancher/k3s/server/cred/scheduler.kubeconfig --port=10251 --profiling=false --secure-port=0\"\n","stream":"stderr","time":"2021-07-26T08:03:46.930687  335Z"}
{"log":"time=\"2021-07-26T08:03:46.930664075Z\" level=info msg=\"Waiting for API server to become available\"\n","stream":"stderr","time":"2021-07-26T08  :03:46.930718725Z"}
{"log":"I0726 08:03:46.930899      35 registry.go:173] Registering SelectorSpread plugin\n","stream":"stderr","time":"2021-07-26T08:03:46.930938314Z"}
{"log":"I0726 08:03:46.930916      35 registry.go:173] Registering SelectorSpread plugin\n","stream":"stderr","time":"2021-07-26T08:03:46.930942424Z"}
{"log":"time=\"2021-07-26T08:03:46.931239844Z\" level=info msg=\"Running kube-controller-manager --address=127.0.0.1 --allocate-node-cidrs=true --bind-a  ddress=127.0.0.1 --cluster-cidr=10.42.0.0/16 --cluster-signing-cert-file=/var/lib/rancher/k3s/server/tls/client-ca.crt --cluster-signing-key-file=/var/l  ib/rancher/k3s/server/tls/client-ca.key --kubeconfig=/var/lib/rancher/k3s/server/cred/controller.kubeconfig --port=10252 --profiling=false --root-ca-fil  e=/var/lib/rancher/k3s/server/tls/server-ca.crt --secure-port=0 --service-account-private-key-file=/var/lib/rancher/k3s/server/tls/service.key --use-ser  vice-account-credentials=true\"\n","stream":"stderr","time":"2021-07-26T08:03:46.931294503Z"}
{"log":"time=\"2021-07-26T08:03:46.932614900Z\" level=info msg=\"Node token is available at /var/lib/rancher/k3s/server/token\"\n","stream":"stderr","ti  me":"2021-07-26T08:03:46.93266062Z"}
{"log":"time=\"2021-07-26T08:03:46.932641070Z\" level=info msg=\"To join node to cluster: k3s agent -s https://172.17.0.2:6443 -t ${NODE_TOKEN}\"\n","st  ream":"stderr","time":"2021-07-26T08:03:46.9326657Z"}
{"log":"time=\"2021-07-26T08:03:46.933648917Z\" level=info msg=\"Wrote kubeconfig /etc/rancher/k3s/k3s.yaml\"\n","stream":"stderr","time":"2021-07-26T08  :03:46.933697897Z"}
{"log":"time=\"2021-07-26T08:03:46.933674977Z\" level=info msg=\"Run: k3s kubectl\"\n","stream":"stderr","time":"2021-07-26T08:03:46.933703957Z"}
{"log":"time=\"2021-07-26T08:03:46.933766887Z\" level=info msg=\"Module overlay was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:03:46.93  3808717Z"}
{"log":"time=\"2021-07-26T08:03:46.933789547Z\" level=info msg=\"Module nf_conntrack was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:03:  46.933821047Z"}
{"log":"time=\"2021-07-26T08:03:46.933811997Z\" level=info msg=\"Module br_netfilter was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:03:  46.933850238Z"}
{"log":"time=\"2021-07-26T08:03:46.933822627Z\" level=info msg=\"Module iptable_nat was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:03:4  6.933855358Z"}
{"log":"time=\"2021-07-26T08:03:46.944375042Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:03:46 http: TLS handshake error from 127.0.0.1:55560:   remote error: tls: bad certificate\"\n","stream":"stderr","time":"2021-07-26T08:03:46.944454671Z"}
{"log":"time=\"2021-07-26T08:03:46.947835864Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:03:46 http: TLS handshake error from 127.0.0.1:55566:   remote error: tls: bad certificate\"\n","stream":"stderr","time":"2021-07-26T08:03:46.947891603Z"}
{"log":"time=\"2021-07-26T08:03:46.962239429Z\" level=info msg=\"certificate CN=local-node signed by CN=k3s-server-ca@1627286228: notBefore=2021-07-26 0  7:57:08 +0000 UTC notAfter=2022-07-26 08:03:46 +0000 UTC\"\n","stream":"stderr","time":"2021-07-26T08:03:46.962411399Z"}
{"log":"time=\"2021-07-26T08:03:46.965031233Z\" level=info msg=\"certificate CN=system:node:local-node,O=system:nodes signed by CN=k3s-client-ca@1627286  228: notBefore=2021-07-26 07:57:08 +0000 UTC notAfter=2022-07-26 08:03:46 +0000 UTC\"\n","stream":"stderr","time":"2021-07-26T08:03:46.965138613Z"}
{"log":"time=\"2021-07-26T08:03:46.970041841Z\" level=info msg=\"Logging containerd to /var/lib/rancher/k3s/agent/containerd/containerd.log\"\n","stream  ":"stderr","time":"2021-07-26T08:03:46.970140561Z"}
{"log":"time=\"2021-07-26T08:03:46.970201020Z\" level=info msg=\"Running containerd -c /var/lib/rancher/k3s/agent/etc/containerd/config.toml -a /run/k3s  /containerd/containerd.sock --state /run/k3s/containerd --root /var/lib/rancher/k3s/agent/containerd\"\n","stream":"stderr","time":"2021-07-26T08:03:46.  9703023Z"}
{"log":"time=\"2021-07-26T08:03:47.971875595Z\" level=info msg=\"Containerd is now running\"\n","stream":"stderr","time":"2021-07-26T08:03:47.972006625Z  "}
{"log":"time=\"2021-07-26T08:03:47.978470289Z\" level=info msg=\"Connecting to proxy\" url=\"wss://127.0.0.1:6443/v1-k3s/connect\"\n","stream":"stderr",  "time":"2021-07-26T08:03:47.978586129Z"}
{"log":"time=\"2021-07-26T08:03:47.980650244Z\" level=info msg=\"Handling backend connection request [local-node]\"\n","stream":"stderr","time":"2021-07  -26T08:03:47.980744084Z"}
{"log":"time=\"2021-07-26T08:03:47.981115193Z\" level=warning msg=\"Disabling CPU quotas due to missing cpu.cfs_period_us\"\n","stream":"stderr","time":  "2021-07-26T08:03:47.981226643Z"}
{"log":"time=\"2021-07-26T08:03:47.981170323Z\" level=info msg=\"Running kubelet --address=0.0.0.0 --anonymous-auth=false --authentication-token-webhook  =true --authorization-mode=Webhook --cgroup-driver=cgroupfs --client-ca-file=/var/lib/rancher/k3s/agent/client-ca.crt --cloud-provider=external --cluste  r-dns=10.43.0.10 --cluster-domain=cluster.local --cni-bin-dir=/var/lib/rancher/k3s/data/ab1b521f47b3cbc273e0c667521084cff1ea3540d55b425ac7ed30c27daf92f8  /bin --cni-conf-dir=/var/lib/rancher/k3s/agent/etc/cni/net.d --container-runtime-endpoint=/run/k3s/containerd/containerd.sock --container-runtime=remote   --containerd=/run/k3s/containerd/containerd.sock --cpu-cfs-quota=false --eviction-hard=imagefs.available\u003c5%,nodefs.available\u003c5% --eviction-mi  nimum-reclaim=imagefs.available=10%,nodefs.available=10% --fail-swap-on=false --healthz-bind-address=127.0.0.1 --hostname-override=local-node --kubeconf  ig=/var/lib/rancher/k3s/agent/kubelet.kubeconfig --kubelet-cgroups=/k3s --node-labels= --pod-manifest-path=/var/lib/rancher/k3s/agent/pod-manifests --re  ad-only-port=0 --resolv-conf=/etc/resolv.conf --runtime-cgroups=/k3s --serialize-image-pulls=false --tls-cert-file=/var/lib/rancher/k3s/agent/serving-ku  belet.crt --tls-private-key-file=/var/lib/rancher/k3s/agent/serving-kubelet.key\"\n","stream":"stderr","time":"2021-07-26T08:03:47.981279483Z"}
{"log":"time=\"2021-07-26T08:03:47.981834351Z\" level=info msg=\"Running kube-proxy --cluster-cidr=10.42.0.0/16 --healthz-bind-address=127.0.0.1 --hostn  ame-override=local-node --kubeconfig=/var/lib/rancher/k3s/agent/kubeproxy.kubeconfig --proxy-mode=iptables\"\n","stream":"stderr","time":"2021-07-26T08:  03:47.981898971Z"}
{"log":"Flag --cloud-provider has been deprecated, will be removed in 1.23, in favor of removing cloud provider code from Kubelet.\n","stream":"stderr",  "time":"2021-07-26T08:03:47.982056291Z"}
{"log":"Flag --containerd has been deprecated, This is a cadvisor flag that was mistakenly registered with the Kubelet. Due to legacy concerns, it will   follow the standard CLI deprecation timeline before being removed.\n","stream":"stderr","time":"2021-07-26T08:03:47.982069981Z"}
{"log":"W0726 08:03:47.982107      35 server.go:226] WARNING: all flags other than --config, --write-config-to, and --cleanup are deprecated. Please beg  in using a config file ASAP.\n","stream":"stderr","time":"2021-07-26T08:03:47.98216484Z"}
{"log":"I0726 08:03:47.982455      35 server.go:407] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:03:47.98252885Z"}
{"log":"W0726 08:03:47.984123      35 proxier.go:639] Failed to read file /lib/modules/4.15.0-151-generic/modules.builtin with error open /lib/modules/4  .15.0-151-generic/modules.builtin: no such file or directory. You can ignore this message when kube-proxy is running inside container without mounting /  lib/modules\n","stream":"stderr","time":"2021-07-26T08:03:47.984181286Z"}
{"log":"W0726 08:03:47.984565      35 proxier.go:649] Failed to load kernel module ip_vs with modprobe. You can ignore this message when kube-proxy is r  unning inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:03:47.984638635Z"}
{"log":"W0726 08:03:47.984905      35 proxier.go:649] Failed to load kernel module ip_vs_rr with modprobe. You can ignore this message when kube-proxy i  s running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:03:47.984957434Z"}
{"log":"W0726 08:03:47.985225      35 proxier.go:649] Failed to load kernel module ip_vs_wrr with modprobe. You can ignore this message when kube-proxy   is running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:03:47.985273963Z"}
{"log":"W0726 08:03:47.985502      35 proxier.go:649] Failed to load kernel module ip_vs_sh with modprobe. You can ignore this message when kube-proxy i  s running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:03:47.985554292Z"}
{"log":"W0726 08:03:47.985777      35 proxier.go:649] Failed to load kernel module nf_conntrack_ipv4 with modprobe. You can ignore this message when kub  e-proxy is running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:03:47.985850061Z"}
{"log":"time=\"2021-07-26T08:03:47.988612455Z\" level=info msg=\"Waiting for node local-node CIDR not assigned yet\"\n","stream":"stderr","time":"2021-0  7-26T08:03:47.988690035Z"}
{"log":"E0726 08:03:47.990932      35 node.go:125] Failed to retrieve node info: nodes \"local-node\" is forbidden: User \"system:kube-proxy\" cannot ge  t resource \"nodes\" in API group \"\" at the cluster scope\n","stream":"stderr","time":"2021-07-26T08:03:47.99101939Z"}
{"log":"I0726 08:03:48.003527      35 dynamic_cafile_content.go:167] Starting client-ca-bundle::/var/lib/rancher/k3s/agent/client-ca.crt\n","stream":"st  derr","time":"2021-07-26T08:03:48.003595119Z"}
{"log":"I0726 08:03:48.192845      35 dynamic_cafile_content.go:167] Starting client-ca-bundle::/var/lib/rancher/k3s/server/tls/client-ca.crt\n","stream  ":"stderr","time":"2021-07-26T08:03:48.192979236Z"}
{"log":"I0726 08:03:48.192845      35 dynamic_cafile_content.go:167] Starting request-header::/var/lib/rancher/k3s/server/tls/request-header-ca.crt\n","  stream":"stderr","time":"2021-07-26T08:03:48.193010516Z"}
{"log":"I0726 08:03:48.192972      35 dynamic_serving_content.go:130] Starting serving-cert::/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.crt:  :/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.key\n","stream":"stderr","time":"2021-07-26T08:03:48.193063046Z"}
{"log":"I0726 08:03:48.193188      35 secure_serving.go:197] Serving securely on 127.0.0.1:6444\n","stream":"stderr","time":"2021-07-26T08:03:48.1932521  15Z"}
{"log":"I0726 08:03:48.193232      35 apiservice_controller.go:97] Starting APIServiceRegistrationController\n","stream":"stderr","time":"2021-07-26T08:  03:48.193275355Z"}
{"log":"I0726 08:03:48.193240      35 cache.go:32] Waiting for caches to sync for APIServiceRegistrationController controller\n","stream":"stderr","time  ":"2021-07-26T08:03:48.193279995Z"}
{"log":"I0726 08:03:48.193263      35 tlsconfig.go:240] Starting DynamicServingCertificateController\n","stream":"stderr","time":"2021-07-26T08:03:48.19  3304525Z"}
{"log":"I0726 08:03:48.193307      35 customresource_discovery_controller.go:209] Starting DiscoveryController\n","stream":"stderr","time":"2021-07-26T0  8:03:48.193365305Z"}
{"log":"I0726 08:03:48.193645      35 dynamic_serving_content.go:130] Starting aggregator-proxy-cert::/var/lib/rancher/k3s/server/tls/client-auth-proxy.  crt::/var/lib/rancher/k3s/server/tls/client-auth-proxy.key\n","stream":"stderr","time":"2021-07-26T08:03:48.193707904Z"}
{"log":"I0726 08:03:48.193677      35 available_controller.go:475] Starting AvailableConditionController\n","stream":"stderr","time":"2021-07-26T08:03:4  8.193749374Z"}
{"log":"I0726 08:03:48.193682      35 cache.go:32] Waiting for caches to sync for AvailableConditionController controller\n","stream":"stderr","time":"2  021-07-26T08:03:48.193753944Z"}
{"log":"I0726 08:03:48.193703      35 autoregister_controller.go:141] Starting autoregister controller\n","stream":"stderr","time":"2021-07-26T08:03:48.  193829703Z"}
{"log":"I0726 08:03:48.193712      35 cache.go:32] Waiting for caches to sync for autoregister controller\n","stream":"stderr","time":"2021-07-26T08:03:  48.193834853Z"}
{"log":"I0726 08:03:48.193724      35 controller.go:86] Starting OpenAPI controller\n","stream":"stderr","time":"2021-07-26T08:03:48.193838473Z"}
{"log":"I0726 08:03:48.193740      35 naming_controller.go:291] Starting NamingConditionController\n","stream":"stderr","time":"2021-07-26T08:03:48.1938  41933Z"}
{"log":"I0726 08:03:48.193751      35 establishing_controller.go:76] Starting EstablishingController\n","stream":"stderr","time":"2021-07-26T08:03:48.19  3845443Z"}
{"log":"I0726 08:03:48.193768      35 nonstructuralschema_controller.go:186] Starting NonStructuralSchemaConditionController\n","stream":"stderr","time"  :"2021-07-26T08:03:48.193848993Z"}
{"log":"I0726 08:03:48.193784      35 apiapproval_controller.go:186] Starting KubernetesAPIApprovalPolicyConformantConditionController\n","stream":"stde  rr","time":"2021-07-26T08:03:48.193852533Z"}
{"log":"I0726 08:03:48.193796      35 crd_finalizer.go:266] Starting CRDFinalizer\n","stream":"stderr","time":"2021-07-26T08:03:48.193856113Z"}
{"log":"I0726 08:03:48.194087      35 crdregistration_controller.go:111] Starting crd-autoregister controller\n","stream":"stderr","time":"2021-07-26T08  :03:48.194146823Z"}
{"log":"I0726 08:03:48.194100      35 shared_informer.go:240] Waiting for caches to sync for crd-autoregister\n","stream":"stderr","time":"2021-07-26T08  :03:48.194169722Z"}
{"log":"I0726 08:03:48.194146      35 controller.go:83] Starting OpenAPI AggregationController\n","stream":"stderr","time":"2021-07-26T08:03:48.19418520  2Z"}
{"log":"I0726 08:03:48.194538      35 cluster_authentication_trust_controller.go:440] Starting cluster_authentication_trust_controller controller\n","st  ream":"stderr","time":"2021-07-26T08:03:48.194606161Z"}
{"log":"I0726 08:03:48.194552      35 shared_informer.go:240] Waiting for caches to sync for cluster_authentication_trust_controller\n","stream":"stderr  ","time":"2021-07-26T08:03:48.194613671Z"}
{"log":"I0726 08:03:48.194965      35 dynamic_cafile_content.go:167] Starting client-ca-bundle::/var/lib/rancher/k3s/server/tls/client-ca.crt\n","stream  ":"stderr","time":"2021-07-26T08:03:48.19502893Z"}
{"log":"I0726 08:03:48.194990      35 dynamic_cafile_content.go:167] Starting request-header::/var/lib/rancher/k3s/server/tls/request-header-ca.crt\n","  stream":"stderr","time":"2021-07-26T08:03:48.19503588Z"}
{"log":"time=\"2021-07-26T08:03:48.209546476Z\" level=info msg=\"Running cloud-controller-manager --allocate-node-cidrs=true --allow-untagged-cloud=true   --bind-address=127.0.0.1 --cloud-provider=k3s --cluster-cidr=10.42.0.0/16 --kubeconfig=/var/lib/rancher/k3s/server/cred/cloud-controller.kubeconfig --n  ode-status-update-frequency=1m --profiling=false --secure-port=0\"\n","stream":"stderr","time":"2021-07-26T08:03:48.209655555Z"}
{"log":"Flag --allow-untagged-cloud has been deprecated, This flag is deprecated and will be removed in a future release. A cluster-id will be required   on cloud instances.\n","stream":"stderr","time":"2021-07-26T08:03:48.209681415Z"}
{"log":"I0726 08:03:48.213060      35 controllermanager.go:127] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:03:48.213140087Z"}
{"log":"W0726 08:03:48.213082      35 controllermanager.go:139] detected a cluster without a ClusterID.  A ClusterID will be required in the future.  Pl  ease tag your cluster to avoid any future issues\n","stream":"stderr","time":"2021-07-26T08:03:48.213186227Z"}
{"log":"I0726 08:03:48.213102      35 leaderelection.go:243] attempting to acquire leader lease  kube-system/cloud-controller-manager...\n","stream":"st  derr","time":"2021-07-26T08:03:48.213190657Z"}
{"log":"E0726 08:03:48.222225      35 controller.go:156] Unable to remove old endpoints from kubernetes service: no master IPs were listed in storage, r  efusing to erase all endpoints for the kubernetes service\n","stream":"stderr","time":"2021-07-26T08:03:48.222325146Z"}
{"log":"I0726 08:03:48.293392      35 cache.go:39] Caches are synced for APIServiceRegistrationController controller\n","stream":"stderr","time":"2021-0  7-26T08:03:48.293539223Z"}
{"log":"I0726 08:03:48.293754      35 cache.go:39] Caches are synced for autoregister controller\n","stream":"stderr","time":"2021-07-26T08:03:48.293848  052Z"}
{"log":"I0726 08:03:48.293784      35 cache.go:39] Caches are synced for AvailableConditionController controller\n","stream":"stderr","time":"2021-07-26  T08:03:48.293860132Z"}
{"log":"I0726 08:03:48.294386      35 shared_informer.go:247] Caches are synced for crd-autoregister \n","stream":"stderr","time":"2021-07-26T08:03:48.2  94454792Z"}
{"log":"I0726 08:03:48.294587      35 shared_informer.go:247] Caches are synced for cluster_authentication_trust_controller \n","stream":"stderr","time"  :"2021-07-26T08:03:48.294649661Z"}
{"log":"W0726 08:03:48.396959       9 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:03:48.397078877Z"}
{"log":"W0726 08:03:48.417152       9 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:03:48.417271458Z"}
{"log":"W0726 08:03:48.446313       9 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:03:48.446426389Z"}
{"log":"W0726 08:03:48.489482       9 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:03:48.489594115Z"}
{"log":"W0726 08:03:48.500835       9 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:03:48.500908398Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting API controllers\n","stream":"stdout","time":"2021-07-26T08:03:48.75014141Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting management.cattle.io/v3, Kind=User controller\n","stream":"stdout","time":"2021-07-26T08:03:48.871879208Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting management.cattle.io/v3, Kind=UserAttribute controller\n","stream":"stdout","time":"2021-07-26T08:03:48.8719  10497Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting management.cattle.io/v3, Kind=GroupMember controller\n","stream":"stdout","time":"2021-07-26T08:03:48.871929  927Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting management.cattle.io/v3, Kind=Token controller\n","stream":"stdout","time":"2021-07-26T08:03:48.871933457Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting /v1, Kind=Secret controller\n","stream":"stdout","time":"2021-07-26T08:03:48.871994858Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting management.cattle.io/v3, Kind=Group controller\n","stream":"stdout","time":"2021-07-26T08:03:48.872168688Z"}
{"log":"W0726 08:03:48.888929       9 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:03:48.889076387Z"}
{"log":"W0726 08:03:48.898327       9 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:03:48.898421714Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting catalog.cattle.io/v1, Kind=ClusterRepo controller\n","stream":"stdout","time":"2021-07-26T08:03:48.988892167  Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting apiregistration.k8s.io/v1, Kind=APIService controller\n","stream":"stdout","time":"2021-07-26T08:03:48.98892  9937Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting management.cattle.io/v3, Kind=Feature controller\n","stream":"stdout","time":"2021-07-26T08:03:48.988932307Z  "}
{"log":"2021/07/26 08:03:48 [INFO] Starting rbac.authorization.k8s.io/v1, Kind=RoleBinding controller\n","stream":"stdout","time":"2021-07-26T08:03:48.9  89133677Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting management.cattle.io/v3, Kind=Preference controller\n","stream":"stdout","time":"2021-07-26T08:03:48.9891612  87Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting /v1, Kind=Secret controller\n","stream":"stdout","time":"2021-07-26T08:03:48.989207427Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting rbac.authorization.k8s.io/v1, Kind=ClusterRole controller\n","stream":"stdout","time":"2021-07-26T08:03:48.9  89214217Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting apiextensions.k8s.io/v1beta1, Kind=CustomResourceDefinition controller\n","stream":"stdout","time":"2021-07-  26T08:03:48.989356566Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting rbac.authorization.k8s.io/v1, Kind=ClusterRoleBinding controller\n","stream":"stdout","time":"2021-07-26T08:  03:48.989452766Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting management.cattle.io/v3, Kind=Cluster controller\n","stream":"stdout","time":"2021-07-26T08:03:48.989567116Z  "}
{"log":"2021/07/26 08:03:48 [INFO] Starting rbac.authorization.k8s.io/v1, Kind=Role controller\n","stream":"stdout","time":"2021-07-26T08:03:48.98965181  5Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting /v1, Kind=ConfigMap controller\n","stream":"stdout","time":"2021-07-26T08:03:48.990981283Z"}
{"log":"2021/07/26 08:03:48 [INFO] Starting management.cattle.io/v3, Kind=Setting controller\n","stream":"stdout","time":"2021-07-26T08:03:48.991034813Z  "}
{"log":"I0726 08:03:48.991314       9 leaderelection.go:243] attempting to acquire leader lease kube-system/cattle-controllers...\n","stream":"stderr","  time":"2021-07-26T08:03:48.991373172Z"}
{"log":"time=\"2021-07-26T08:03:48.995475412Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:03:48 http: TLS handshake error from 127.0.0.1:55808:   EOF\"\n","stream":"stderr","time":"2021-07-26T08:03:48.995570592Z"}
{"log":"time=\"2021-07-26T08:03:48.995555062Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:03:48 http: TLS handshake error from 127.0.0.1:55804:   EOF\"\n","stream":"stderr","time":"2021-07-26T08:03:48.995625112Z"}
{"log":"time=\"2021-07-26T08:03:48.995586162Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:03:48 http: TLS handshake error from 127.0.0.1:55806:   EOF\"\n","stream":"stderr","time":"2021-07-26T08:03:48.995633062Z"}
{"log":"I0726 08:03:48.995745       9 leaderelection.go:253] successfully acquired lease kube-system/cattle-controllers\n","stream":"stderr","time":"202  1-07-26T08:03:48.995803541Z"}
{"log":"W0726 08:03:49.001868       9 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:03:49.001976047Z"}
{"log":"W0726 08:03:49.013800       9 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:03:49.013897308Z"}
{"log":"W0726 08:03:49.031351       9 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:03:49.031460625Z"}
{"log":"2021/07/26 08:03:49 [INFO] Running in single server mode, will not peer connections\n","stream":"stdout","time":"2021-07-26T08:03:49.036061675Z"  }
{"log":"2021/07/26 08:03:49 [INFO] Steve auth startup complete\n","stream":"stdout","time":"2021-07-26T08:03:49.041282092Z"}
{"log":"W0726 08:03:49.046331       9 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:03:49.046414649Z"}
{"log":"W0726 08:03:49.049751       9 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:03:49.049827721Z"}
{"log":"W0726 08:03:49.061495       9 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:03:49.061581123Z"}
{"log":"W0726 08:03:49.062471       9 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:03:49.062554201Z"}
{"log":"I0726 08:03:49.144277      35 node.go:136] Successfully retrieved node IP: 172.17.0.2\n","stream":"stderr","time":"2021-07-26T08:03:49.144394054  Z"}
{"log":"I0726 08:03:49.144313      35 server_others.go:143] kube-proxy node IP is an IPv4 address (172.17.0.2), assume IPv4 operation\n","stream":"stder  r","time":"2021-07-26T08:03:49.144417384Z"}
{"log":"I0726 08:03:49.144919      35 server_others.go:186] Using iptables Proxier.\n","stream":"stderr","time":"2021-07-26T08:03:49.144995832Z"}
{"log":"I0726 08:03:49.145116      35 server.go:650] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:03:49.145170432Z"}
{"log":"I0726 08:03:49.145457      35 conntrack.go:103] Set sysctl 'net/netfilter/nf_conntrack_max' to 196608\n","stream":"stderr","time":"2021-07-26T08  :03:49.145516271Z"}
{"log":"F0726 08:03:49.145481      35 server.go:495] open /proc/sys/net/netfilter/nf_conntrack_max: permission denied\n","stream":"stderr","time":"2021-  07-26T08:03:49.145525951Z"}
{"log":"W0726 08:03:49.161311       9 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.ClusterRepo ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close   - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:03:49.161390394Z"}
{"log":"2021/07/26 08:03:49 [FATAL] k3s exited with: exit status 1\n","stream":"stdout","time":"2021-07-26T08:03:49.162350711Z"}
{"log":"2021/07/26 08:03:49 [INFO] Rancher version v2.5.9 (3c5418944) is starting\n","stream":"stdout","time":"2021-07-26T08:03:49.974069991Z"}
{"log":"2021/07/26 08:03:49 [INFO] Rancher arguments {ACMEDomains:[] AddLocal:true Embedded:false BindHost: HTTPListenPort:80 HTTPSListenPort:443 K8sMod  e:auto Debug:false Trace:false NoCACerts:false AuditLogPath:/var/log/auditlog/rancher-api-audit.log AuditLogMaxage:10 AuditLogMaxsize:100 AuditLogMaxbac  kup:10 AuditLevel:0 Agent:false Features: ClusterRegistry:}\n","stream":"stdout","time":"2021-07-26T08:03:49.974108611Z"}
{"log":"2021/07/26 08:03:49 [INFO] Listening on /tmp/log.sock\n","stream":"stdout","time":"2021-07-26T08:03:49.974117431Z"}
{"log":"2021/07/26 08:03:49 [INFO] Running etcd --data-dir=management-state/etcd --heartbeat-interval=500 --election-timeout=5000\n","stream":"stdout","  time":"2021-07-26T08:03:49.97434677Z"}
{"log":"2021-07-26 08:03:49.982914 W | pkg/flags: unrecognized environment variable ETCD_URL_arm64=https://github.com/etcd-io/etcd/releases/download/v3.  4.3/etcd-v3.4.3-linux-arm64.tar.gz\n","stream":"stderr","time":"2021-07-26T08:03:49.983032069Z"}
{"log":"2021-07-26 08:03:49.982946 W | pkg/flags: unrecognized environment variable ETCD_URL_amd64=https://github.com/etcd-io/etcd/releases/download/v3.  4.3/etcd-v3.4.3-linux-amd64.tar.gz\n","stream":"stderr","time":"2021-07-26T08:03:49.983049189Z"}
{"log":"2021-07-26 08:03:49.982951 W | pkg/flags: unrecognized environment variable ETCD_UNSUPPORTED_ARCH=amd64\n","stream":"stderr","time":"2021-07-26T  08:03:49.983054519Z"}
{"log":"2021-07-26 08:03:49.982955 W | pkg/flags: unrecognized environment variable ETCD_URL=ETCD_URL_amd64\n","stream":"stderr","time":"2021-07-26T08:0  3:49.983069319Z"}
{"log":"[WARNING] Deprecated '--logger=capnslog' flag is set; use '--logger=zap' flag instead\n","stream":"stderr","time":"2021-07-26T08:03:49.983073089  Z"}
{"log":"2021-07-26 08:03:49.982979 I | etcdmain: etcd Version: 3.4.3\n","stream":"stderr","time":"2021-07-26T08:03:49.983076629Z"}
{"log":"2021-07-26 08:03:49.982982 I | etcdmain: Git SHA: 3cf2f69b5\n","stream":"stderr","time":"2021-07-26T08:03:49.983080069Z"}
{"log":"2021-07-26 08:03:49.982987 I | etcdmain: Go Version: go1.12.12\n","stream":"stderr","time":"2021-07-26T08:03:49.983084889Z"}
{"log":"2021-07-26 08:03:49.982990 I | etcdmain: Go OS/Arch: linux/amd64\n","stream":"stderr","time":"2021-07-26T08:03:49.983090089Z"}
{"log":"2021-07-26 08:03:49.982994 I | etcdmain: setting maximum number of CPUs to 6, total number of available CPUs is 6\n","stream":"stderr","time":"2  021-07-26T08:03:49.983096809Z"}
{"log":"2021-07-26 08:03:49.983041 N | etcdmain: the server is already initialized as member before, starting as etcd member...\n","stream":"stderr","ti  me":"2021-07-26T08:03:49.983142729Z"}
{"log":"[WARNING] Deprecated '--logger=capnslog' flag is set; use '--logger=zap' flag instead\n","stream":"stderr","time":"2021-07-26T08:03:49.983154259  Z"}
{"log":"2021-07-26 08:03:49.983402 I | embed: name = default\n","stream":"stderr","time":"2021-07-26T08:03:49.983484758Z"}
{"log":"2021-07-26 08:03:49.983414 I | embed: data dir = management-state/etcd\n","stream":"stderr","time":"2021-07-26T08:03:49.983509348Z"}
{"log":"2021-07-26 08:03:49.983418 I | embed: member dir = management-state/etcd/member\n","stream":"stderr","time":"2021-07-26T08:03:49.983511848Z"}
{"log":"2021-07-26 08:03:49.983422 I | embed: heartbeat = 500ms\n","stream":"stderr","time":"2021-07-26T08:03:49.983514368Z"}
{"log":"2021-07-26 08:03:49.983425 I | embed: election = 5000ms\n","stream":"stderr","time":"2021-07-26T08:03:49.983516328Z"}
{"log":"2021-07-26 08:03:49.983429 I | embed: snapshot count = 100000\n","stream":"stderr","time":"2021-07-26T08:03:49.983518008Z"}
{"log":"2021-07-26 08:03:49.983443 I | embed: advertise client URLs = http://localhost:2379\n","stream":"stderr","time":"2021-07-26T08:03:49.983519898Z"  }
{"log":"2021-07-26 08:03:49.983454 I | embed: initial advertise peer URLs = http://localhost:2380\n","stream":"stderr","time":"2021-07-26T08:03:49.98352  1638Z"}
{"log":"2021-07-26 08:03:49.983459 I | embed: initial cluster = \n","stream":"stderr","time":"2021-07-26T08:03:49.983523538Z"}
{"log":"2021-07-26 08:03:49.990037 I | etcdserver: restarting member 8e9e05c52164694d in cluster cdf818194e3a8c32 at commit index 1250\n","stream":"stde  rr","time":"2021-07-26T08:03:49.990117913Z"}
{"log":"raft2021/07/26 08:03:49 INFO: 8e9e05c52164694d switched to configuration voters=()\n","stream":"stderr","time":"2021-07-26T08:03:49.990351342Z"}
{"log":"raft2021/07/26 08:03:49 INFO: 8e9e05c52164694d became follower at term 38\n","stream":"stderr","time":"2021-07-26T08:03:49.990357272Z"}
{"log":"raft2021/07/26 08:03:49 INFO: newRaft 8e9e05c52164694d [peers: [], term: 38, commit: 1250, applied: 0, lastindex: 1250, lastterm: 38]\n","stream  ":"stderr","time":"2021-07-26T08:03:49.990359312Z"}
{"log":"2021-07-26 08:03:49.994782 W | auth: simple token is not cryptographically signed\n","stream":"stderr","time":"2021-07-26T08:03:49.994869641Z"}
{"log":"2021-07-26 08:03:49.996496 I | etcdserver: starting server... [version: 3.4.3, cluster version: to_be_decided]\n","stream":"stderr","time":"2021  -07-26T08:03:49.996595116Z"}
{"log":"raft2021/07/26 08:03:49 INFO: 8e9e05c52164694d switched to configuration voters=(10276657743932975437)\n","stream":"stderr","time":"2021-07-26T0  8:03:49.997129816Z"}
{"log":"2021-07-26 08:03:49.997164 I | etcdserver/membership: added member 8e9e05c52164694d [http://localhost:2380] to cluster cdf818194e3a8c32\n","stre  am":"stderr","time":"2021-07-26T08:03:49.997218916Z"}
{"log":"2021-07-26 08:03:49.997277 N | etcdserver/membership: set the initial cluster version to 3.4\n","stream":"stderr","time":"2021-07-26T08:03:49.99  7355345Z"}
{"log":"2021-07-26 08:03:49.997315 I | etcdserver/api: enabled capabilities for version 3.4\n","stream":"stderr","time":"2021-07-26T08:03:49.997374315Z"  }
{"log":"2021-07-26 08:03:49.997926 I | embed: listening for peers on 127.0.0.1:2380\n","stream":"stderr","time":"2021-07-26T08:03:49.998003603Z"}
{"log":"raft2021/07/26 08:03:58 INFO: 8e9e05c52164694d is starting a new election at term 38\n","stream":"stderr","time":"2021-07-26T08:03:58.490995878Z  "}
{"log":"raft2021/07/26 08:03:58 INFO: 8e9e05c52164694d became candidate at term 39\n","stream":"stderr","time":"2021-07-26T08:03:58.491033337Z"}
{"log":"raft2021/07/26 08:03:58 INFO: 8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 39\n","stream":"stderr","time":"2021-07-26T08:  03:58.491038147Z"}
{"log":"raft2021/07/26 08:03:58 INFO: 8e9e05c52164694d became leader at term 39\n","stream":"stderr","time":"2021-07-26T08:03:58.491041927Z"}
{"log":"raft2021/07/26 08:03:58 INFO: raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 39\n","stream":"stderr","time":"2021-07-26T08:  03:58.491045487Z"}
{"log":"2021-07-26 08:03:58.490967 I | etcdserver: published {Name:default ClientURLs:[http://localhost:2379]} to cluster cdf818194e3a8c32\n","stream":"  stderr","time":"2021-07-26T08:03:58.491081647Z"}
{"log":"2021-07-26 08:03:58.490998 I | embed: ready to serve client requests\n","stream":"stderr","time":"2021-07-26T08:03:58.491105627Z"}
{"log":"2021-07-26 08:03:58.491696 N | embed: serving insecure client requests on 127.0.0.1:2379, this is strongly discouraged!\n","stream":"stderr","ti  me":"2021-07-26T08:03:58.491814505Z"}
{"log":"2021/07/26 08:03:58 [INFO] Waiting for server to become available: Get \"https://127.0.0.1:6443/version?timeout=15m0s\": dial tcp 127.0.0.1:6443  : connect: connection refused\n","stream":"stdout","time":"2021-07-26T08:03:58.505192072Z"}
{"log":"time=\"2021-07-26T08:03:58.664994837Z\" level=info msg=\"Starting k3s v1.19.8+k3s1 (95fc76b2)\"\n","stream":"stderr","time":"2021-07-26T08:03:58  .665123226Z"}
{"log":"time=\"2021-07-26T08:03:58.665185186Z\" level=info msg=\"Cluster bootstrap already complete\"\n","stream":"stderr","time":"2021-07-26T08:03:58.6  65290906Z"}
{"log":"time=\"2021-07-26T08:03:58.673091736Z\" level=info msg=\"Running kube-apiserver --advertise-port=6443 --allow-privileged=true --anonymous-auth=f  alse --api-audiences=unknown --authorization-mode=Node,RBAC --bind-address=127.0.0.1 --cert-dir=/var/lib/rancher/k3s/server/tls/temporary-certs --client  -ca-file=/var/lib/rancher/k3s/server/tls/client-ca.crt --enable-admission-plugins=NodeRestriction --etcd-servers=http://localhost:2379 --insecure-port=0   --kubelet-certificate-authority=/var/lib/rancher/k3s/server/tls/server-ca.crt --kubelet-client-certificate=/var/lib/rancher/k3s/server/tls/client-kube-  apiserver.crt --kubelet-client-key=/var/lib/rancher/k3s/server/tls/client-kube-apiserver.key --profiling=false --proxy-client-cert-file=/var/lib/rancher  /k3s/server/tls/client-auth-proxy.crt --proxy-client-key-file=/var/lib/rancher/k3s/server/tls/client-auth-proxy.key --requestheader-allowed-names=system  :auth-proxy --requestheader-client-ca-file=/var/lib/rancher/k3s/server/tls/request-header-ca.crt --requestheader-extra-headers-prefix=X-Remote-Extra- --  requestheader-group-headers=X-Remote-Group --requestheader-username-headers=X-Remote-User --secure-port=6444 --service-account-issuer=k3s --service-acco  unt-key-file=/var/lib/rancher/k3s/server/tls/service.key --service-account-signing-key-file=/var/lib/rancher/k3s/server/tls/service.key --service-cluste  r-ip-range=10.43.0.0/16 --storage-backend=etcd3 --tls-cert-file=/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.crt --tls-private-key-file=/var/l  ib/rancher/k3s/server/tls/serving-kube-apiserver.key\"\n","stream":"stderr","time":"2021-07-26T08:03:58.673227246Z"}
{"log":"I0726 08:03:58.673766      33 server.go:652] external host was not specified, using 172.17.0.2\n","stream":"stderr","time":"2021-07-26T08:03:58.  673833194Z"}
{"log":"I0726 08:03:58.673917      33 server.go:177] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:03:58.673974554Z"}
{"log":"I0726 08:03:58.676885      33 plugins.go:158] Loaded 12 mutating admission controller(s) successfully in the following order: NamespaceLifecycle  ,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,Run  timeClass,DefaultIngressClass,MutatingAdmissionWebhook.\n","stream":"stderr","time":"2021-07-26T08:03:58.676960707Z"}
{"log":"I0726 08:03:58.676895      33 plugins.go:161] Loaded 10 validating admission controller(s) successfully in the following order: LimitRanger,Serv  iceAccount,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,CertificateSubjectRestriction,ValidatingAdmissionWeb  hook,ResourceQuota.\n","stream":"stderr","time":"2021-07-26T08:03:58.676978017Z"}
{"log":"I0726 08:03:58.677426      33 plugins.go:158] Loaded 12 mutating admission controller(s) successfully in the following order: NamespaceLifecycle  ,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,Run  timeClass,DefaultIngressClass,MutatingAdmissionWebhook.\n","stream":"stderr","time":"2021-07-26T08:03:58.677489186Z"}
{"log":"I0726 08:03:58.677436      33 plugins.go:161] Loaded 10 validating admission controller(s) successfully in the following order: LimitRanger,Serv  iceAccount,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,CertificateSubjectRestriction,ValidatingAdmissionWeb  hook,ResourceQuota.\n","stream":"stderr","time":"2021-07-26T08:03:58.677495736Z"}
{"log":"I0726 08:03:58.699102      33 master.go:271] Using reconciler: lease\n","stream":"stderr","time":"2021-07-26T08:03:58.699217073Z"}
{"log":"W0726 08:03:58.957958      33 genericapiserver.go:412] Skipping API batch/v2alpha1 because it has no resources.\n","stream":"stderr","time":"202  1-07-26T08:03:58.958099698Z"}
{"log":"W0726 08:03:58.974802      33 genericapiserver.go:412] Skipping API discovery.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr",  "time":"2021-07-26T08:03:58.974885826Z"}
{"log":"W0726 08:03:58.992321      33 genericapiserver.go:412] Skipping API node.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr","time  ":"2021-07-26T08:03:58.992404433Z"}
{"log":"W0726 08:03:59.006876      33 genericapiserver.go:412] Skipping API rbac.authorization.k8s.io/v1alpha1 because it has no resources.\n","stream":  "stderr","time":"2021-07-26T08:03:59.006978876Z"}
{"log":"W0726 08:03:59.008983      33 genericapiserver.go:412] Skipping API scheduling.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr"  ,"time":"2021-07-26T08:03:59.009046612Z"}
{"log":"W0726 08:03:59.017670      33 genericapiserver.go:412] Skipping API storage.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr","t  ime":"2021-07-26T08:03:59.01775078Z"}
{"log":"W0726 08:03:59.029046      33 genericapiserver.go:412] Skipping API apps/v1beta2 because it has no resources.\n","stream":"stderr","time":"2021-  07-26T08:03:59.029135672Z"}
{"log":"W0726 08:03:59.029060      33 genericapiserver.go:412] Skipping API apps/v1beta1 because it has no resources.\n","stream":"stderr","time":"2021-  07-26T08:03:59.029143732Z"}
{"log":"I0726 08:03:59.035793      33 plugins.go:158] Loaded 12 mutating admission controller(s) successfully in the following order: NamespaceLifecycle  ,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,Run  timeClass,DefaultIngressClass,MutatingAdmissionWebhook.\n","stream":"stderr","time":"2021-07-26T08:03:59.035865406Z"}
{"log":"I0726 08:03:59.035806      33 plugins.go:161] Loaded 10 validating admission controller(s) successfully in the following order: LimitRanger,Serv  iceAccount,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,CertificateSubjectRestriction,ValidatingAdmissionWeb  hook,ResourceQuota.\n","stream":"stderr","time":"2021-07-26T08:03:59.035874676Z"}
{"log":"time=\"2021-07-26T08:03:59.042212261Z\" level=info msg=\"Running kube-scheduler --address=127.0.0.1 --bind-address=127.0.0.1 --kubeconfig=/var/l  ib/rancher/k3s/server/cred/scheduler.kubeconfig --port=10251 --profiling=false --secure-port=0\"\n","stream":"stderr","time":"2021-07-26T08:03:59.042357  36Z"}
{"log":"I0726 08:03:59.042514      33 registry.go:173] Registering SelectorSpread plugin\n","stream":"stderr","time":"2021-07-26T08:03:59.04261476Z"}
{"log":"I0726 08:03:59.042536      33 registry.go:173] Registering SelectorSpread plugin\n","stream":"stderr","time":"2021-07-26T08:03:59.04264198Z"}
{"log":"time=\"2021-07-26T08:03:59.042830339Z\" level=info msg=\"Running kube-controller-manager --address=127.0.0.1 --allocate-node-cidrs=true --bind-a  ddress=127.0.0.1 --cluster-cidr=10.42.0.0/16 --cluster-signing-cert-file=/var/lib/rancher/k3s/server/tls/client-ca.crt --cluster-signing-key-file=/var/l  ib/rancher/k3s/server/tls/client-ca.key --kubeconfig=/var/lib/rancher/k3s/server/cred/controller.kubeconfig --port=10252 --profiling=false --root-ca-fil  e=/var/lib/rancher/k3s/server/tls/server-ca.crt --secure-port=0 --service-account-private-key-file=/var/lib/rancher/k3s/server/tls/service.key --use-ser  vice-account-credentials=true\"\n","stream":"stderr","time":"2021-07-26T08:03:59.042918339Z"}
{"log":"time=\"2021-07-26T08:03:59.043951626Z\" level=info msg=\"Waiting for API server to become available\"\n","stream":"stderr","time":"2021-07-26T08  :03:59.044044476Z"}
{"log":"time=\"2021-07-26T08:03:59.044232795Z\" level=info msg=\"Node token is available at /var/lib/rancher/k3s/server/token\"\n","stream":"stderr","ti  me":"2021-07-26T08:03:59.044324605Z"}
{"log":"time=\"2021-07-26T08:03:59.044262225Z\" level=info msg=\"To join node to cluster: k3s agent -s https://172.17.0.2:6443 -t ${NODE_TOKEN}\"\n","st  ream":"stderr","time":"2021-07-26T08:03:59.044340465Z"}
{"log":"time=\"2021-07-26T08:03:59.045365713Z\" level=info msg=\"Wrote kubeconfig /etc/rancher/k3s/k3s.yaml\"\n","stream":"stderr","time":"2021-07-26T08  :03:59.045448213Z"}
{"log":"time=\"2021-07-26T08:03:59.045394433Z\" level=info msg=\"Run: k3s kubectl\"\n","stream":"stderr","time":"2021-07-26T08:03:59.045457353Z"}
{"log":"time=\"2021-07-26T08:03:59.045522123Z\" level=info msg=\"Module overlay was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:03:59.04  5595173Z"}
{"log":"time=\"2021-07-26T08:03:59.045546533Z\" level=info msg=\"Module nf_conntrack was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:03:  59.045602483Z"}
{"log":"time=\"2021-07-26T08:03:59.045561733Z\" level=info msg=\"Module br_netfilter was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:03:  59.045606472Z"}
{"log":"time=\"2021-07-26T08:03:59.045575543Z\" level=info msg=\"Module iptable_nat was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:03:5  9.045611632Z"}
{"log":"time=\"2021-07-26T08:03:59.061000375Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:03:59 http: TLS handshake error from 127.0.0.1:55968:   remote error: tls: bad certificate\"\n","stream":"stderr","time":"2021-07-26T08:03:59.061195234Z"}
{"log":"time=\"2021-07-26T08:03:59.065623933Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:03:59 http: TLS handshake error from 127.0.0.1:55976:   remote error: tls: bad certificate\"\n","stream":"stderr","time":"2021-07-26T08:03:59.065740023Z"}
{"log":"time=\"2021-07-26T08:03:59.076422886Z\" level=info msg=\"certificate CN=local-node signed by CN=k3s-server-ca@1627286228: notBefore=2021-07-26 0  7:57:08 +0000 UTC notAfter=2022-07-26 08:03:59 +0000 UTC\"\n","stream":"stderr","time":"2021-07-26T08:03:59.076561496Z"}
{"log":"time=\"2021-07-26T08:03:59.078455351Z\" level=info msg=\"certificate CN=system:node:local-node,O=system:nodes signed by CN=k3s-client-ca@1627286  228: notBefore=2021-07-26 07:57:08 +0000 UTC notAfter=2022-07-26 08:03:59 +0000 UTC\"\n","stream":"stderr","time":"2021-07-26T08:03:59.078546171Z"}
{"log":"time=\"2021-07-26T08:03:59.081726923Z\" level=info msg=\"Logging containerd to /var/lib/rancher/k3s/agent/containerd/containerd.log\"\n","stream  ":"stderr","time":"2021-07-26T08:03:59.081806113Z"}
{"log":"time=\"2021-07-26T08:03:59.081849473Z\" level=info msg=\"Running containerd -c /var/lib/rancher/k3s/agent/etc/containerd/config.toml -a /run/k3s  /containerd/containerd.sock --state /run/k3s/containerd --root /var/lib/rancher/k3s/agent/containerd\"\n","stream":"stderr","time":"2021-07-26T08:03:59.  081919943Z"}
{"log":"time=\"2021-07-26T08:04:00.083777900Z\" level=info msg=\"Containerd is now running\"\n","stream":"stderr","time":"2021-07-26T08:04:00.083911409Z  "}
{"log":"time=\"2021-07-26T08:04:00.090161644Z\" level=info msg=\"Connecting to proxy\" url=\"wss://127.0.0.1:6443/v1-k3s/connect\"\n","stream":"stderr",  "time":"2021-07-26T08:04:00.090290674Z"}
{"log":"time=\"2021-07-26T08:04:00.092226518Z\" level=info msg=\"Handling backend connection request [local-node]\"\n","stream":"stderr","time":"2021-07  -26T08:04:00.092318178Z"}
{"log":"time=\"2021-07-26T08:04:00.092661877Z\" level=warning msg=\"Disabling CPU quotas due to missing cpu.cfs_period_us\"\n","stream":"stderr","time":  "2021-07-26T08:04:00.092729197Z"}
{"log":"time=\"2021-07-26T08:04:00.092729327Z\" level=info msg=\"Running kubelet --address=0.0.0.0 --anonymous-auth=false --authentication-token-webhook  =true --authorization-mode=Webhook --cgroup-driver=cgroupfs --client-ca-file=/var/lib/rancher/k3s/agent/client-ca.crt --cloud-provider=external --cluste  r-dns=10.43.0.10 --cluster-domain=cluster.local --cni-bin-dir=/var/lib/rancher/k3s/data/ab1b521f47b3cbc273e0c667521084cff1ea3540d55b425ac7ed30c27daf92f8  /bin --cni-conf-dir=/var/lib/rancher/k3s/agent/etc/cni/net.d --container-runtime-endpoint=/run/k3s/containerd/containerd.sock --container-runtime=remote   --containerd=/run/k3s/containerd/containerd.sock --cpu-cfs-quota=false --eviction-hard=imagefs.available\u003c5%,nodefs.available\u003c5% --eviction-mi  nimum-reclaim=imagefs.available=10%,nodefs.available=10% --fail-swap-on=false --healthz-bind-address=127.0.0.1 --hostname-override=local-node --kubeconf  ig=/var/lib/rancher/k3s/agent/kubelet.kubeconfig --kubelet-cgroups=/k3s --node-labels= --pod-manifest-path=/var/lib/rancher/k3s/agent/pod-manifests --re  ad-only-port=0 --resolv-conf=/etc/resolv.conf --runtime-cgroups=/k3s --serialize-image-pulls=false --tls-cert-file=/var/lib/rancher/k3s/agent/serving-ku  belet.crt --tls-private-key-file=/var/lib/rancher/k3s/agent/serving-kubelet.key\"\n","stream":"stderr","time":"2021-07-26T08:04:00.092833217Z"}
{"log":"time=\"2021-07-26T08:04:00.093372655Z\" level=info msg=\"Running kube-proxy --cluster-cidr=10.42.0.0/16 --healthz-bind-address=127.0.0.1 --hostn  ame-override=local-node --kubeconfig=/var/lib/rancher/k3s/agent/kubeproxy.kubeconfig --proxy-mode=iptables\"\n","stream":"stderr","time":"2021-07-26T08:  04:00.093446505Z"}
{"log":"Flag --cloud-provider has been deprecated, will be removed in 1.23, in favor of removing cloud provider code from Kubelet.\n","stream":"stderr",  "time":"2021-07-26T08:04:00.093566255Z"}
{"log":"Flag --containerd has been deprecated, This is a cadvisor flag that was mistakenly registered with the Kubelet. Due to legacy concerns, it will   follow the standard CLI deprecation timeline before being removed.\n","stream":"stderr","time":"2021-07-26T08:04:00.093632285Z"}
{"log":"W0726 08:04:00.093682      33 server.go:226] WARNING: all flags other than --config, --write-config-to, and --cleanup are deprecated. Please beg  in using a config file ASAP.\n","stream":"stderr","time":"2021-07-26T08:04:00.093733315Z"}
{"log":"W0726 08:04:00.094101      33 proxier.go:639] Failed to read file /lib/modules/4.15.0-151-generic/modules.builtin with error open /lib/modules/4  .15.0-151-generic/modules.builtin: no such file or directory. You can ignore this message when kube-proxy is running inside container without mounting /  lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:00.094150964Z"}
{"log":"I0726 08:04:00.094280      33 server.go:407] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:04:00.094339664Z"}
{"log":"W0726 08:04:00.094532      33 proxier.go:649] Failed to load kernel module ip_vs with modprobe. You can ignore this message when kube-proxy is r  unning inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:00.094564833Z"}
{"log":"W0726 08:04:00.094824      33 proxier.go:649] Failed to load kernel module ip_vs_rr with modprobe. You can ignore this message when kube-proxy i  s running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:00.094853853Z"}
{"log":"W0726 08:04:00.096595      33 proxier.go:649] Failed to load kernel module ip_vs_wrr with modprobe. You can ignore this message when kube-proxy   is running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:00.096683328Z"}
{"log":"W0726 08:04:00.096871      33 proxier.go:649] Failed to load kernel module ip_vs_sh with modprobe. You can ignore this message when kube-proxy i  s running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:00.096913247Z"}
{"log":"W0726 08:04:00.097147      33 proxier.go:649] Failed to load kernel module nf_conntrack_ipv4 with modprobe. You can ignore this message when kub  e-proxy is running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:00.097187486Z"}
{"log":"time=\"2021-07-26T08:04:00.101333546Z\" level=info msg=\"Waiting for node local-node CIDR not assigned yet\"\n","stream":"stderr","time":"2021-0  7-26T08:04:00.101473386Z"}
{"log":"E0726 08:04:00.102005      33 node.go:125] Failed to retrieve node info: nodes \"local-node\" is forbidden: User \"system:kube-proxy\" cannot ge  t resource \"nodes\" in API group \"\" at the cluster scope\n","stream":"stderr","time":"2021-07-26T08:04:00.102082395Z"}
{"log":"I0726 08:04:00.117761      33 dynamic_cafile_content.go:167] Starting client-ca-bundle::/var/lib/rancher/k3s/agent/client-ca.crt\n","stream":"st  derr","time":"2021-07-26T08:04:00.117876296Z"}
{"log":"I0726 08:04:00.289786      33 dynamic_cafile_content.go:167] Starting request-header::/var/lib/rancher/k3s/server/tls/request-header-ca.crt\n","  stream":"stderr","time":"2021-07-26T08:04:00.289932461Z"}
{"log":"I0726 08:04:00.289806      33 dynamic_cafile_content.go:167] Starting client-ca-bundle::/var/lib/rancher/k3s/server/tls/client-ca.crt\n","stream  ":"stderr","time":"2021-07-26T08:04:00.289959541Z"}
{"log":"I0726 08:04:00.290029      33 dynamic_serving_content.go:130] Starting serving-cert::/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.crt:  :/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.key\n","stream":"stderr","time":"2021-07-26T08:04:00.290103941Z"}
{"log":"I0726 08:04:00.290228      33 secure_serving.go:197] Serving securely on 127.0.0.1:6444\n","stream":"stderr","time":"2021-07-26T08:04:00.2902888  11Z"}
{"log":"I0726 08:04:00.290259      33 tlsconfig.go:240] Starting DynamicServingCertificateController\n","stream":"stderr","time":"2021-07-26T08:04:00.29  0296901Z"}
{"log":"I0726 08:04:00.290322      33 autoregister_controller.go:141] Starting autoregister controller\n","stream":"stderr","time":"2021-07-26T08:04:00.  29035764Z"}
{"log":"I0726 08:04:00.290337      33 cache.go:32] Waiting for caches to sync for autoregister controller\n","stream":"stderr","time":"2021-07-26T08:04:  00.29036359Z"}
{"log":"I0726 08:04:00.290392      33 customresource_discovery_controller.go:209] Starting DiscoveryController\n","stream":"stderr","time":"2021-07-26T0  8:04:00.29041165Z"}
{"log":"I0726 08:04:00.290455      33 controller.go:83] Starting OpenAPI AggregationController\n","stream":"stderr","time":"2021-07-26T08:04:00.29047785  Z"}
{"log":"I0726 08:04:00.290512      33 controller.go:86] Starting OpenAPI controller\n","stream":"stderr","time":"2021-07-26T08:04:00.29052789Z"}
{"log":"I0726 08:04:00.290550      33 naming_controller.go:291] Starting NamingConditionController\n","stream":"stderr","time":"2021-07-26T08:04:00.2905  7681Z"}
{"log":"I0726 08:04:00.290602      33 establishing_controller.go:76] Starting EstablishingController\n","stream":"stderr","time":"2021-07-26T08:04:00.29  061816Z"}
{"log":"I0726 08:04:00.290658      33 nonstructuralschema_controller.go:186] Starting NonStructuralSchemaConditionController\n","stream":"stderr","time"  :"2021-07-26T08:04:00.29067828Z"}
{"log":"I0726 08:04:00.290703      33 apiapproval_controller.go:186] Starting KubernetesAPIApprovalPolicyConformantConditionController\n","stream":"stde  rr","time":"2021-07-26T08:04:00.290727379Z"}
{"log":"I0726 08:04:00.290749      33 crd_finalizer.go:266] Starting CRDFinalizer\n","stream":"stderr","time":"2021-07-26T08:04:00.290766699Z"}
{"log":"I0726 08:04:00.290796      33 dynamic_serving_content.go:130] Starting aggregator-proxy-cert::/var/lib/rancher/k3s/server/tls/client-auth-proxy.  crt::/var/lib/rancher/k3s/server/tls/client-auth-proxy.key\n","stream":"stderr","time":"2021-07-26T08:04:00.290814669Z"}
{"log":"I0726 08:04:00.290940      33 cluster_authentication_trust_controller.go:440] Starting cluster_authentication_trust_controller controller\n","st  ream":"stderr","time":"2021-07-26T08:04:00.290973659Z"}
{"log":"I0726 08:04:00.290953      33 shared_informer.go:240] Waiting for caches to sync for cluster_authentication_trust_controller\n","stream":"stderr  ","time":"2021-07-26T08:04:00.290979819Z"}
{"log":"I0726 08:04:00.290985      33 apiservice_controller.go:97] Starting APIServiceRegistrationController\n","stream":"stderr","time":"2021-07-26T08:  04:00.291004759Z"}
{"log":"I0726 08:04:00.290990      33 cache.go:32] Waiting for caches to sync for APIServiceRegistrationController controller\n","stream":"stderr","time  ":"2021-07-26T08:04:00.291009659Z"}
{"log":"I0726 08:04:00.291010      33 available_controller.go:475] Starting AvailableConditionController\n","stream":"stderr","time":"2021-07-26T08:04:0  0.291028239Z"}
{"log":"I0726 08:04:00.291015      33 cache.go:32] Waiting for caches to sync for AvailableConditionController controller\n","stream":"stderr","time":"2  021-07-26T08:04:00.291032169Z"}
{"log":"I0726 08:04:00.291036      33 crdregistration_controller.go:111] Starting crd-autoregister controller\n","stream":"stderr","time":"2021-07-26T08  :04:00.291052329Z"}
{"log":"I0726 08:04:00.291040      33 shared_informer.go:240] Waiting for caches to sync for crd-autoregister\n","stream":"stderr","time":"2021-07-26T08  :04:00.291056459Z"}
{"log":"I0726 08:04:00.291070      33 dynamic_cafile_content.go:167] Starting client-ca-bundle::/var/lib/rancher/k3s/server/tls/client-ca.crt\n","stream  ":"stderr","time":"2021-07-26T08:04:00.291089618Z"}
{"log":"I0726 08:04:00.291096      33 dynamic_cafile_content.go:167] Starting request-header::/var/lib/rancher/k3s/server/tls/request-header-ca.crt\n","  stream":"stderr","time":"2021-07-26T08:04:00.291121188Z"}
{"log":"time=\"2021-07-26T08:04:00.301676822Z\" level=info msg=\"Running cloud-controller-manager --allocate-node-cidrs=true --allow-untagged-cloud=true   --bind-address=127.0.0.1 --cloud-provider=k3s --cluster-cidr=10.42.0.0/16 --kubeconfig=/var/lib/rancher/k3s/server/cred/cloud-controller.kubeconfig --n  ode-status-update-frequency=1m --profiling=false --secure-port=0\"\n","stream":"stderr","time":"2021-07-26T08:04:00.301794292Z"}
{"log":"Flag --allow-untagged-cloud has been deprecated, This flag is deprecated and will be removed in a future release. A cluster-id will be required   on cloud instances.\n","stream":"stderr","time":"2021-07-26T08:04:00.301811972Z"}
{"log":"I0726 08:04:00.306755      33 controllermanager.go:127] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:04:00.30682975Z"}
{"log":"W0726 08:04:00.306784      33 controllermanager.go:139] detected a cluster without a ClusterID.  A ClusterID will be required in the future.  Pl  ease tag your cluster to avoid any future issues\n","stream":"stderr","time":"2021-07-26T08:04:00.3068398Z"}
{"log":"I0726 08:04:00.306809      33 leaderelection.go:243] attempting to acquire leader lease  kube-system/cloud-controller-manager...\n","stream":"st  derr","time":"2021-07-26T08:04:00.30684196Z"}
{"log":"E0726 08:04:00.327143      33 controller.go:156] Unable to remove old endpoints from kubernetes service: no master IPs were listed in storage, r  efusing to erase all endpoints for the kubernetes service\n","stream":"stderr","time":"2021-07-26T08:04:00.32726369Z"}
{"log":"I0726 08:04:00.390529      33 cache.go:39] Caches are synced for autoregister controller\n","stream":"stderr","time":"2021-07-26T08:04:00.390691  814Z"}
{"log":"I0726 08:04:00.391293      33 shared_informer.go:247] Caches are synced for crd-autoregister \n","stream":"stderr","time":"2021-07-26T08:04:00.3  91391892Z"}
{"log":"I0726 08:04:00.391477      33 cache.go:39] Caches are synced for APIServiceRegistrationController controller\n","stream":"stderr","time":"2021-0  7-26T08:04:00.391559661Z"}
{"log":"I0726 08:04:00.391552      33 shared_informer.go:247] Caches are synced for cluster_authentication_trust_controller \n","stream":"stderr","time"  :"2021-07-26T08:04:00.391591731Z"}
{"log":"I0726 08:04:00.391718      33 cache.go:39] Caches are synced for AvailableConditionController controller\n","stream":"stderr","time":"2021-07-26  T08:04:00.391780321Z"}
{"log":"W0726 08:04:00.527035       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:00.527153688Z"}
{"log":"W0726 08:04:00.545550       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:00.545672731Z"}
{"log":"W0726 08:04:00.557023       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:00.557133963Z"}
{"log":"W0726 08:04:00.597937       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:00.598041902Z"}
{"log":"W0726 08:04:00.609401       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:00.609477974Z"}
{"log":"2021/07/26 08:04:00 [INFO] Starting API controllers\n","stream":"stdout","time":"2021-07-26T08:04:00.889052616Z"}
{"log":"2021/07/26 08:04:01 [INFO] Starting management.cattle.io/v3, Kind=GroupMember controller\n","stream":"stdout","time":"2021-07-26T08:04:01.111823  926Z"}
{"log":"2021/07/26 08:04:01 [INFO] Starting management.cattle.io/v3, Kind=UserAttribute controller\n","stream":"stdout","time":"2021-07-26T08:04:01.1119  06306Z"}
{"log":"2021/07/26 08:04:01 [INFO] Starting management.cattle.io/v3, Kind=Group controller\n","stream":"stdout","time":"2021-07-26T08:04:01.111986715Z"}
{"log":"2021/07/26 08:04:01 [INFO] Starting /v1, Kind=Secret controller\n","stream":"stdout","time":"2021-07-26T08:04:01.111994165Z"}
{"log":"2021/07/26 08:04:01 [INFO] Starting management.cattle.io/v3, Kind=Token controller\n","stream":"stdout","time":"2021-07-26T08:04:01.112092075Z"}
{"log":"2021/07/26 08:04:01 [INFO] Starting management.cattle.io/v3, Kind=User controller\n","stream":"stdout","time":"2021-07-26T08:04:01.113026873Z"}
{"log":"W0726 08:04:01.129542       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:01.129656402Z"}
{"log":"W0726 08:04:01.135495       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:01.135587098Z"}
{"log":"2021/07/26 08:04:01 [INFO] Starting management.cattle.io/v3, Kind=Feature controller\n","stream":"stdout","time":"2021-07-26T08:04:01.235494261Z  "}
{"log":"2021/07/26 08:04:01 [INFO] Starting apiregistration.k8s.io/v1, Kind=APIService controller\n","stream":"stdout","time":"2021-07-26T08:04:01.23554  476Z"}
{"log":"2021/07/26 08:04:01 [INFO] Starting rbac.authorization.k8s.io/v1, Kind=RoleBinding controller\n","stream":"stdout","time":"2021-07-26T08:04:01.2  3568595Z"}
{"log":"2021/07/26 08:04:01 [INFO] Starting apiextensions.k8s.io/v1beta1, Kind=CustomResourceDefinition controller\n","stream":"stdout","time":"2021-07-  26T08:04:01.23571399Z"}
{"log":"2021/07/26 08:04:01 [INFO] Starting rbac.authorization.k8s.io/v1, Kind=Role controller\n","stream":"stdout","time":"2021-07-26T08:04:01.2357934Z  "}
{"log":"2021/07/26 08:04:01 [INFO] Starting management.cattle.io/v3, Kind=Cluster controller\n","stream":"stdout","time":"2021-07-26T08:04:01.235949659Z  "}
{"log":"2021/07/26 08:04:01 [INFO] Starting /v1, Kind=Secret controller\n","stream":"stdout","time":"2021-07-26T08:04:01.235996689Z"}
{"log":"2021/07/26 08:04:01 [INFO] Starting rbac.authorization.k8s.io/v1, Kind=ClusterRoleBinding controller\n","stream":"stdout","time":"2021-07-26T08:  04:01.236138309Z"}
{"log":"2021/07/26 08:04:01 [INFO] Starting /v1, Kind=ConfigMap controller\n","stream":"stdout","time":"2021-07-26T08:04:01.236879707Z"}
{"log":"2021/07/26 08:04:01 [INFO] Starting management.cattle.io/v3, Kind=Setting controller\n","stream":"stdout","time":"2021-07-26T08:04:01.236981997Z  "}
{"log":"2021/07/26 08:04:01 [INFO] Starting rbac.authorization.k8s.io/v1, Kind=ClusterRole controller\n","stream":"stdout","time":"2021-07-26T08:04:01.2  39937369Z"}
{"log":"2021/07/26 08:04:01 [INFO] Starting catalog.cattle.io/v1, Kind=ClusterRepo controller\n","stream":"stdout","time":"2021-07-26T08:04:01.241958535  Z"}
{"log":"2021/07/26 08:04:01 [INFO] Starting management.cattle.io/v3, Kind=Preference controller\n","stream":"stdout","time":"2021-07-26T08:04:01.2431565  72Z"}
{"log":"time=\"2021-07-26T08:04:01.243376131Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:04:01 http: TLS handshake error from 127.0.0.1:56224:   EOF\"\n","stream":"stderr","time":"2021-07-26T08:04:01.243478741Z"}
{"log":"time=\"2021-07-26T08:04:01.243497921Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:04:01 http: TLS handshake error from 127.0.0.1:56220:   EOF\"\n","stream":"stderr","time":"2021-07-26T08:04:01.243543961Z"}
{"log":"time=\"2021-07-26T08:04:01.243561231Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:04:01 http: TLS handshake error from 127.0.0.1:56222:   EOF\"\n","stream":"stderr","time":"2021-07-26T08:04:01.24360295Z"}
{"log":"I0726 08:04:01.246096       8 leaderelection.go:243] attempting to acquire leader lease kube-system/cattle-controllers...\n","stream":"stderr","  time":"2021-07-26T08:04:01.246183044Z"}
{"log":"W0726 08:04:01.256103       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:01.256214879Z"}
{"log":"I0726 08:04:01.259245       8 leaderelection.go:253] successfully acquired lease kube-system/cattle-controllers\n","stream":"stderr","time":"202  1-07-26T08:04:01.259351441Z"}
{"log":"W0726 08:04:01.269923       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:01.270024815Z"}
{"log":"I0726 08:04:01.275879      33 node.go:136] Successfully retrieved node IP: 172.17.0.2\n","stream":"stderr","time":"2021-07-26T08:04:01.276002441  Z"}
{"log":"I0726 08:04:01.275915      33 server_others.go:143] kube-proxy node IP is an IPv4 address (172.17.0.2), assume IPv4 operation\n","stream":"stder  r","time":"2021-07-26T08:04:01.276032991Z"}
{"log":"I0726 08:04:01.276543      33 server_others.go:186] Using iptables Proxier.\n","stream":"stderr","time":"2021-07-26T08:04:01.276617549Z"}
{"log":"I0726 08:04:01.276747      33 server.go:650] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:04:01.276819949Z"}
{"log":"I0726 08:04:01.277069      33 conntrack.go:103] Set sysctl 'net/netfilter/nf_conntrack_max' to 196608\n","stream":"stderr","time":"2021-07-26T08  :04:01.277135148Z"}
{"log":"F0726 08:04:01.277093      33 server.go:495] open /proc/sys/net/netfilter/nf_conntrack_max: permission denied\n","stream":"stderr","time":"2021-  07-26T08:04:01.277148608Z"}
{"log":"W0726 08:04:01.292758       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Feature ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - wa  tch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.292835609Z"}
{"log":"W0726 08:04:01.292785       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.Role ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - watch   lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.292957429Z"}
{"log":"W0726 08:04:01.292902       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.ClusterRoleBinding ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch   close - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.292979188Z"}
{"log":"W0726 08:04:01.292946       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.ClusterRole ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close   - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.293034118Z"}
{"log":"W0726 08:04:01.292966       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Setting ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - wa  tch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.293042098Z"}
{"log":"W0726 08:04:01.292826       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.RoleBinding ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close   - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.293046678Z"}
{"log":"W0726 08:04:01.293070       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.User ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - watch   lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.293135118Z"}
{"log":"W0726 08:04:01.293081       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Token ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - watc  h lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.293145858Z"}
{"log":"W0726 08:04:01.293247       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1beta1.CustomResourceDefinition ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexp  ected watch close - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.293325298Z"}
{"log":"W0726 08:04:01.293260       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Group ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - watc  h lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.293354357Z"}
{"log":"W0726 08:04:01.293291       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.GroupMember ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close   - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.293359157Z"}
{"log":"2021/07/26 08:04:01 [ERROR] failed to call leader func: failed to add authconfig data: Post \"https://127.0.0.1:6443/apis/management.cattle.io/v  3/authconfigs\": EOF\n","stream":"stdout","time":"2021-07-26T08:04:01.293378307Z"}
{"log":"W0726 08:04:01.293312       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.UserAttribute ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch clos  e - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.293398057Z"}
{"log":"W0726 08:04:01.293327       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.Secret ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - wat  ch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.293402927Z"}
{"log":"W0726 08:04:01.293343       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Preference ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close -   watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.293407217Z"}
{"log":"W0726 08:04:01.293341       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.Secret ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - wat  ch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.293415077Z"}
{"log":"W0726 08:04:01.293455       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Cluster ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - wa  tch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.293492947Z"}
{"log":"W0726 08:04:01.293564       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.APIService ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close -   watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.293610127Z"}
{"log":"W0726 08:04:01.293581       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.ClusterRepo ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close   - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:01.293637367Z"}
{"log":"2021/07/26 08:04:01 [FATAL] k3s exited with: exit status 1\n","stream":"stdout","time":"2021-07-26T08:04:01.293727506Z"}
{"log":"2021/07/26 08:04:01 [INFO] Rancher version v2.5.9 (3c5418944) is starting\n","stream":"stdout","time":"2021-07-26T08:04:01.9565208Z"}
{"log":"2021/07/26 08:04:01 [INFO] Rancher arguments {ACMEDomains:[] AddLocal:true Embedded:false BindHost: HTTPListenPort:80 HTTPSListenPort:443 K8sMod  e:auto Debug:false Trace:false NoCACerts:false AuditLogPath:/var/log/auditlog/rancher-api-audit.log AuditLogMaxage:10 AuditLogMaxsize:100 AuditLogMaxbac  kup:10 AuditLevel:0 Agent:false Features: ClusterRegistry:}\n","stream":"stdout","time":"2021-07-26T08:04:01.95655205Z"}
{"log":"2021/07/26 08:04:01 [INFO] Listening on /tmp/log.sock\n","stream":"stdout","time":"2021-07-26T08:04:01.95656369Z"}
{"log":"2021/07/26 08:04:01 [INFO] Running etcd --data-dir=management-state/etcd --heartbeat-interval=500 --election-timeout=5000\n","stream":"stdout","  time":"2021-07-26T08:04:01.9567682Z"}
{"log":"2021-07-26 08:04:01.963210 W | pkg/flags: unrecognized environment variable ETCD_URL_arm64=https://github.com/etcd-io/etcd/releases/download/v3.  4.3/etcd-v3.4.3-linux-arm64.tar.gz\n","stream":"stderr","time":"2021-07-26T08:04:01.963318723Z"}
{"log":"2021-07-26 08:04:01.963229 W | pkg/flags: unrecognized environment variable ETCD_URL_amd64=https://github.com/etcd-io/etcd/releases/download/v3.  4.3/etcd-v3.4.3-linux-amd64.tar.gz\n","stream":"stderr","time":"2021-07-26T08:04:01.963343353Z"}
{"log":"2021-07-26 08:04:01.963231 W | pkg/flags: unrecognized environment variable ETCD_UNSUPPORTED_ARCH=amd64\n","stream":"stderr","time":"2021-07-26T  08:04:01.963346383Z"}
{"log":"2021-07-26 08:04:01.963234 W | pkg/flags: unrecognized environment variable ETCD_URL=ETCD_URL_amd64\n","stream":"stderr","time":"2021-07-26T08:0  4:01.963348753Z"}
{"log":"[WARNING] Deprecated '--logger=capnslog' flag is set; use '--logger=zap' flag instead\n","stream":"stderr","time":"2021-07-26T08:04:01.963351073  Z"}
{"log":"2021-07-26 08:04:01.963250 I | etcdmain: etcd Version: 3.4.3\n","stream":"stderr","time":"2021-07-26T08:04:01.963354173Z"}
{"log":"2021-07-26 08:04:01.963252 I | etcdmain: Git SHA: 3cf2f69b5\n","stream":"stderr","time":"2021-07-26T08:04:01.963357773Z"}
{"log":"2021-07-26 08:04:01.963254 I | etcdmain: Go Version: go1.12.12\n","stream":"stderr","time":"2021-07-26T08:04:01.963361003Z"}
{"log":"2021-07-26 08:04:01.963256 I | etcdmain: Go OS/Arch: linux/amd64\n","stream":"stderr","time":"2021-07-26T08:04:01.963364143Z"}
{"log":"2021-07-26 08:04:01.963258 I | etcdmain: setting maximum number of CPUs to 6, total number of available CPUs is 6\n","stream":"stderr","time":"2  021-07-26T08:04:01.963366913Z"}
{"log":"2021-07-26 08:04:01.963289 N | etcdmain: the server is already initialized as member before, starting as etcd member...\n","stream":"stderr","ti  me":"2021-07-26T08:04:01.963369243Z"}
{"log":"[WARNING] Deprecated '--logger=capnslog' flag is set; use '--logger=zap' flag instead\n","stream":"stderr","time":"2021-07-26T08:04:01.963371403  Z"}
{"log":"2021-07-26 08:04:01.963496 I | embed: name = default\n","stream":"stderr","time":"2021-07-26T08:04:01.963564603Z"}
{"log":"2021-07-26 08:04:01.963504 I | embed: data dir = management-state/etcd\n","stream":"stderr","time":"2021-07-26T08:04:01.963579013Z"}
{"log":"2021-07-26 08:04:01.963506 I | embed: member dir = management-state/etcd/member\n","stream":"stderr","time":"2021-07-26T08:04:01.963584503Z"}
{"log":"2021-07-26 08:04:01.963508 I | embed: heartbeat = 500ms\n","stream":"stderr","time":"2021-07-26T08:04:01.963588963Z"}
{"log":"2021-07-26 08:04:01.963510 I | embed: election = 5000ms\n","stream":"stderr","time":"2021-07-26T08:04:01.963593353Z"}
{"log":"2021-07-26 08:04:01.963512 I | embed: snapshot count = 100000\n","stream":"stderr","time":"2021-07-26T08:04:01.963597653Z"}
{"log":"2021-07-26 08:04:01.963516 I | embed: advertise client URLs = http://localhost:2379\n","stream":"stderr","time":"2021-07-26T08:04:01.963601923Z"  }
{"log":"2021-07-26 08:04:01.963518 I | embed: initial advertise peer URLs = http://localhost:2380\n","stream":"stderr","time":"2021-07-26T08:04:01.96360  6223Z"}
{"log":"2021-07-26 08:04:01.963521 I | embed: initial cluster = \n","stream":"stderr","time":"2021-07-26T08:04:01.963621103Z"}
{"log":"2021-07-26 08:04:01.967504 I | etcdserver: restarting member 8e9e05c52164694d in cluster cdf818194e3a8c32 at commit index 1268\n","stream":"stde  rr","time":"2021-07-26T08:04:01.967579033Z"}
{"log":"raft2021/07/26 08:04:01 INFO: 8e9e05c52164694d switched to configuration voters=()\n","stream":"stderr","time":"2021-07-26T08:04:01.967631143Z"}
{"log":"raft2021/07/26 08:04:01 INFO: 8e9e05c52164694d became follower at term 39\n","stream":"stderr","time":"2021-07-26T08:04:01.967669633Z"}
{"log":"raft2021/07/26 08:04:01 INFO: newRaft 8e9e05c52164694d [peers: [], term: 39, commit: 1268, applied: 0, lastindex: 1268, lastterm: 39]\n","stream  ":"stderr","time":"2021-07-26T08:04:01.967676323Z"}
{"log":"2021-07-26 08:04:01.972542 W | auth: simple token is not cryptographically signed\n","stream":"stderr","time":"2021-07-26T08:04:01.97260017Z"}
{"log":"2021-07-26 08:04:01.974316 I | etcdserver: starting server... [version: 3.4.3, cluster version: to_be_decided]\n","stream":"stderr","time":"2021  -07-26T08:04:01.974363945Z"}
{"log":"raft2021/07/26 08:04:01 INFO: 8e9e05c52164694d switched to configuration voters=(10276657743932975437)\n","stream":"stderr","time":"2021-07-26T0  8:04:01.974593035Z"}
{"log":"2021-07-26 08:04:01.974824 I | etcdserver/membership: added member 8e9e05c52164694d [http://localhost:2380] to cluster cdf818194e3a8c32\n","stre  am":"stderr","time":"2021-07-26T08:04:01.974887754Z"}
{"log":"2021-07-26 08:04:01.974886 I | etcdserver: 8e9e05c52164694d as single-node; fast-forwarding 9 ticks (election ticks 10)\n","stream":"stderr","ti  me":"2021-07-26T08:04:01.974933114Z"}
{"log":"2021-07-26 08:04:01.974907 N | etcdserver/membership: set the initial cluster version to 3.4\n","stream":"stderr","time":"2021-07-26T08:04:01.97  4942875Z"}
{"log":"2021-07-26 08:04:01.974936 I | etcdserver/api: enabled capabilities for version 3.4\n","stream":"stderr","time":"2021-07-26T08:04:01.974992085Z"  }
{"log":"2021-07-26 08:04:01.977200 I | embed: listening for peers on 127.0.0.1:2380\n","stream":"stderr","time":"2021-07-26T08:04:01.977271578Z"}
{"log":"raft2021/07/26 08:04:06 INFO: 8e9e05c52164694d is starting a new election at term 39\n","stream":"stderr","time":"2021-07-26T08:04:06.468128007Z  "}
{"log":"raft2021/07/26 08:04:06 INFO: 8e9e05c52164694d became candidate at term 40\n","stream":"stderr","time":"2021-07-26T08:04:06.468153597Z"}
{"log":"raft2021/07/26 08:04:06 INFO: 8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 40\n","stream":"stderr","time":"2021-07-26T08:  04:06.468158487Z"}
{"log":"raft2021/07/26 08:04:06 INFO: 8e9e05c52164694d became leader at term 40\n","stream":"stderr","time":"2021-07-26T08:04:06.468162267Z"}
{"log":"raft2021/07/26 08:04:06 INFO: raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 40\n","stream":"stderr","time":"2021-07-26T08:  04:06.468165877Z"}
{"log":"2021-07-26 08:04:06.472842 I | etcdserver: published {Name:default ClientURLs:[http://localhost:2379]} to cluster cdf818194e3a8c32\n","stream":"  stderr","time":"2021-07-26T08:04:06.472948855Z"}
{"log":"2021-07-26 08:04:06.472871 I | embed: ready to serve client requests\n","stream":"stderr","time":"2021-07-26T08:04:06.472982764Z"}
{"log":"2021-07-26 08:04:06.473499 N | embed: serving insecure client requests on 127.0.0.1:2379, this is strongly discouraged!\n","stream":"stderr","ti  me":"2021-07-26T08:04:06.473597873Z"}
{"log":"2021/07/26 08:04:06 [INFO] Waiting for server to become available: Get \"https://127.0.0.1:6443/version?timeout=15m0s\": dial tcp 127.0.0.1:6443  : connect: connection refused\n","stream":"stdout","time":"2021-07-26T08:04:06.487200259Z"}
{"log":"time=\"2021-07-26T08:04:06.646021016Z\" level=info msg=\"Starting k3s v1.19.8+k3s1 (95fc76b2)\"\n","stream":"stderr","time":"2021-07-26T08:04:06  .646179896Z"}
{"log":"time=\"2021-07-26T08:04:06.646225635Z\" level=info msg=\"Cluster bootstrap already complete\"\n","stream":"stderr","time":"2021-07-26T08:04:06.6  46316595Z"}
{"log":"time=\"2021-07-26T08:04:06.661474088Z\" level=info msg=\"Running kube-apiserver --advertise-port=6443 --allow-privileged=true --anonymous-auth=f  alse --api-audiences=unknown --authorization-mode=Node,RBAC --bind-address=127.0.0.1 --cert-dir=/var/lib/rancher/k3s/server/tls/temporary-certs --client  -ca-file=/var/lib/rancher/k3s/server/tls/client-ca.crt --enable-admission-plugins=NodeRestriction --etcd-servers=http://localhost:2379 --insecure-port=0   --kubelet-certificate-authority=/var/lib/rancher/k3s/server/tls/server-ca.crt --kubelet-client-certificate=/var/lib/rancher/k3s/server/tls/client-kube-  apiserver.crt --kubelet-client-key=/var/lib/rancher/k3s/server/tls/client-kube-apiserver.key --profiling=false --proxy-client-cert-file=/var/lib/rancher  /k3s/server/tls/client-auth-proxy.crt --proxy-client-key-file=/var/lib/rancher/k3s/server/tls/client-auth-proxy.key --requestheader-allowed-names=system  :auth-proxy --requestheader-client-ca-file=/var/lib/rancher/k3s/server/tls/request-header-ca.crt --requestheader-extra-headers-prefix=X-Remote-Extra- --  requestheader-group-headers=X-Remote-Group --requestheader-username-headers=X-Remote-User --secure-port=6444 --service-account-issuer=k3s --service-acco  unt-key-file=/var/lib/rancher/k3s/server/tls/service.key --service-account-signing-key-file=/var/lib/rancher/k3s/server/tls/service.key --service-cluste  r-ip-range=10.43.0.0/16 --storage-backend=etcd3 --tls-cert-file=/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.crt --tls-private-key-file=/var/l  ib/rancher/k3s/server/tls/serving-kube-apiserver.key\"\n","stream":"stderr","time":"2021-07-26T08:04:06.661619657Z"}
{"log":"I0726 08:04:06.662559      34 server.go:652] external host was not specified, using 172.17.0.2\n","stream":"stderr","time":"2021-07-26T08:04:06.  662617105Z"}
{"log":"I0726 08:04:06.662789      34 server.go:177] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:04:06.662836684Z"}
{"log":"I0726 08:04:06.668326      34 plugins.go:158] Loaded 12 mutating admission controller(s) successfully in the following order: NamespaceLifecycle  ,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,Run  timeClass,DefaultIngressClass,MutatingAdmissionWebhook.\n","stream":"stderr","time":"2021-07-26T08:04:06.668430811Z"}
{"log":"I0726 08:04:06.668480      34 plugins.go:161] Loaded 10 validating admission controller(s) successfully in the following order: LimitRanger,Serv  iceAccount,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,CertificateSubjectRestriction,ValidatingAdmissionWeb  hook,ResourceQuota.\n","stream":"stderr","time":"2021-07-26T08:04:06.66853915Z"}
{"log":"I0726 08:04:06.670289      34 plugins.go:158] Loaded 12 mutating admission controller(s) successfully in the following order: NamespaceLifecycle  ,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,Run  timeClass,DefaultIngressClass,MutatingAdmissionWebhook.\n","stream":"stderr","time":"2021-07-26T08:04:06.670391355Z"}
{"log":"I0726 08:04:06.670309      34 plugins.go:161] Loaded 10 validating admission controller(s) successfully in the following order: LimitRanger,Serv  iceAccount,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,CertificateSubjectRestriction,ValidatingAdmissionWeb  hook,ResourceQuota.\n","stream":"stderr","time":"2021-07-26T08:04:06.670405075Z"}
{"log":"I0726 08:04:06.693170      34 master.go:271] Using reconciler: lease\n","stream":"stderr","time":"2021-07-26T08:04:06.693301568Z"}
{"log":"W0726 08:04:06.938909      34 genericapiserver.go:412] Skipping API batch/v2alpha1 because it has no resources.\n","stream":"stderr","time":"202  1-07-26T08:04:06.939051516Z"}
{"log":"W0726 08:04:06.947692      34 genericapiserver.go:412] Skipping API discovery.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr",  "time":"2021-07-26T08:04:06.947785463Z"}
{"log":"W0726 08:04:06.972446      34 genericapiserver.go:412] Skipping API node.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr","time  ":"2021-07-26T08:04:06.972577102Z"}
{"log":"W0726 08:04:06.993441      34 genericapiserver.go:412] Skipping API rbac.authorization.k8s.io/v1alpha1 because it has no resources.\n","stream":  "stderr","time":"2021-07-26T08:04:06.993548589Z"}
{"log":"W0726 08:04:06.997289      34 genericapiserver.go:412] Skipping API scheduling.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr"  ,"time":"2021-07-26T08:04:06.99735614Z"}
{"log":"W0726 08:04:07.011755      34 genericapiserver.go:412] Skipping API storage.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr","t  ime":"2021-07-26T08:04:07.011828284Z"}
{"log":"W0726 08:04:07.023489      34 genericapiserver.go:412] Skipping API apps/v1beta2 because it has no resources.\n","stream":"stderr","time":"2021-  07-26T08:04:07.023581244Z"}
{"log":"W0726 08:04:07.023504      34 genericapiserver.go:412] Skipping API apps/v1beta1 because it has no resources.\n","stream":"stderr","time":"2021-  07-26T08:04:07.023596164Z"}
{"log":"I0726 08:04:07.029950      34 plugins.go:158] Loaded 12 mutating admission controller(s) successfully in the following order: NamespaceLifecycle  ,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,Run  timeClass,DefaultIngressClass,MutatingAdmissionWebhook.\n","stream":"stderr","time":"2021-07-26T08:04:07.030023778Z"}
{"log":"I0726 08:04:07.029964      34 plugins.go:161] Loaded 10 validating admission controller(s) successfully in the following order: LimitRanger,Serv  iceAccount,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,CertificateSubjectRestriction,ValidatingAdmissionWeb  hook,ResourceQuota.\n","stream":"stderr","time":"2021-07-26T08:04:07.030034748Z"}
{"log":"time=\"2021-07-26T08:04:07.036213032Z\" level=info msg=\"Running kube-scheduler --address=127.0.0.1 --bind-address=127.0.0.1 --kubeconfig=/var/l  ib/rancher/k3s/server/cred/scheduler.kubeconfig --port=10251 --profiling=false --secure-port=0\"\n","stream":"stderr","time":"2021-07-26T08:04:07.036335  902Z"}
{"log":"time=\"2021-07-26T08:04:07.036608441Z\" level=info msg=\"Waiting for API server to become available\"\n","stream":"stderr","time":"2021-07-26T08  :04:07.036728611Z"}
{"log":"I0726 08:04:07.036634      34 registry.go:173] Registering SelectorSpread plugin\n","stream":"stderr","time":"2021-07-26T08:04:07.036929122Z"}
{"log":"I0726 08:04:07.036879      34 registry.go:173] Registering SelectorSpread plugin\n","stream":"stderr","time":"2021-07-26T08:04:07.036944541Z"}
{"log":"time=\"2021-07-26T08:04:07.037199851Z\" level=info msg=\"Running kube-controller-manager --address=127.0.0.1 --allocate-node-cidrs=true --bind-a  ddress=127.0.0.1 --cluster-cidr=10.42.0.0/16 --cluster-signing-cert-file=/var/lib/rancher/k3s/server/tls/client-ca.crt --cluster-signing-key-file=/var/l  ib/rancher/k3s/server/tls/client-ca.key --kubeconfig=/var/lib/rancher/k3s/server/cred/controller.kubeconfig --port=10252 --profiling=false --root-ca-fil  e=/var/lib/rancher/k3s/server/tls/server-ca.crt --secure-port=0 --service-account-private-key-file=/var/lib/rancher/k3s/server/tls/service.key --use-ser  vice-account-credentials=true\"\n","stream":"stderr","time":"2021-07-26T08:04:07.03729619Z"}
{"log":"time=\"2021-07-26T08:04:07.038577417Z\" level=info msg=\"Node token is available at /var/lib/rancher/k3s/server/token\"\n","stream":"stderr","ti  me":"2021-07-26T08:04:07.038678227Z"}
{"log":"time=\"2021-07-26T08:04:07.038604617Z\" level=info msg=\"To join node to cluster: k3s agent -s https://172.17.0.2:6443 -t ${NODE_TOKEN}\"\n","st  ream":"stderr","time":"2021-07-26T08:04:07.038691377Z"}
{"log":"time=\"2021-07-26T08:04:07.039825373Z\" level=info msg=\"Wrote kubeconfig /etc/rancher/k3s/k3s.yaml\"\n","stream":"stderr","time":"2021-07-26T08  :04:07.039925833Z"}
{"log":"time=\"2021-07-26T08:04:07.039852533Z\" level=info msg=\"Run: k3s kubectl\"\n","stream":"stderr","time":"2021-07-26T08:04:07.039940153Z"}
{"log":"time=\"2021-07-26T08:04:07.039968153Z\" level=info msg=\"Module overlay was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:04:07.04  0036213Z"}
{"log":"time=\"2021-07-26T08:04:07.039996843Z\" level=info msg=\"Module nf_conntrack was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:04:  07.040048923Z"}
{"log":"time=\"2021-07-26T08:04:07.040012883Z\" level=info msg=\"Module br_netfilter was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:04:  07.040096083Z"}
{"log":"time=\"2021-07-26T08:04:07.040032603Z\" level=info msg=\"Module iptable_nat was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:04:0  7.040108613Z"}
{"log":"time=\"2021-07-26T08:04:07.051419024Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:04:07 http: TLS handshake error from 127.0.0.1:56384:   remote error: tls: bad certificate\"\n","stream":"stderr","time":"2021-07-26T08:04:07.051537104Z"}
{"log":"time=\"2021-07-26T08:04:07.056013854Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:04:07 http: TLS handshake error from 127.0.0.1:56390:   remote error: tls: bad certificate\"\n","stream":"stderr","time":"2021-07-26T08:04:07.056114953Z"}
{"log":"time=\"2021-07-26T08:04:07.066866155Z\" level=info msg=\"certificate CN=local-node signed by CN=k3s-server-ca@1627286228: notBefore=2021-07-26 0  7:57:08 +0000 UTC notAfter=2022-07-26 08:04:07 +0000 UTC\"\n","stream":"stderr","time":"2021-07-26T08:04:07.066973795Z"}
{"log":"time=\"2021-07-26T08:04:07.069580089Z\" level=info msg=\"certificate CN=system:node:local-node,O=system:nodes signed by CN=k3s-client-ca@1627286  228: notBefore=2021-07-26 07:57:08 +0000 UTC notAfter=2022-07-26 08:04:07 +0000 UTC\"\n","stream":"stderr","time":"2021-07-26T08:04:07.069663349Z"}
{"log":"time=\"2021-07-26T08:04:07.087558734Z\" level=info msg=\"Logging containerd to /var/lib/rancher/k3s/agent/containerd/containerd.log\"\n","stream  ":"stderr","time":"2021-07-26T08:04:07.087695804Z"}
{"log":"time=\"2021-07-26T08:04:07.087838843Z\" level=info msg=\"Running containerd -c /var/lib/rancher/k3s/agent/etc/containerd/config.toml -a /run/k3s  /containerd/containerd.sock --state /run/k3s/containerd --root /var/lib/rancher/k3s/agent/containerd\"\n","stream":"stderr","time":"2021-07-26T08:04:07.  087991413Z"}
{"log":"time=\"2021-07-26T08:04:08.089025397Z\" level=info msg=\"Containerd is now running\"\n","stream":"stderr","time":"2021-07-26T08:04:08.089169417Z  "}
{"log":"time=\"2021-07-26T08:04:08.096022760Z\" level=info msg=\"Connecting to proxy\" url=\"wss://127.0.0.1:6443/v1-k3s/connect\"\n","stream":"stderr",  "time":"2021-07-26T08:04:08.09612291Z"}
{"log":"time=\"2021-07-26T08:04:08.098131185Z\" level=info msg=\"Handling backend connection request [local-node]\"\n","stream":"stderr","time":"2021-07  -26T08:04:08.098231135Z"}
{"log":"time=\"2021-07-26T08:04:08.098564734Z\" level=warning msg=\"Disabling CPU quotas due to missing cpu.cfs_period_us\"\n","stream":"stderr","time":  "2021-07-26T08:04:08.098652244Z"}
{"log":"time=\"2021-07-26T08:04:08.098624684Z\" level=info msg=\"Running kubelet --address=0.0.0.0 --anonymous-auth=false --authentication-token-webhook  =true --authorization-mode=Webhook --cgroup-driver=cgroupfs --client-ca-file=/var/lib/rancher/k3s/agent/client-ca.crt --cloud-provider=external --cluste  r-dns=10.43.0.10 --cluster-domain=cluster.local --cni-bin-dir=/var/lib/rancher/k3s/data/ab1b521f47b3cbc273e0c667521084cff1ea3540d55b425ac7ed30c27daf92f8  /bin --cni-conf-dir=/var/lib/rancher/k3s/agent/etc/cni/net.d --container-runtime-endpoint=/run/k3s/containerd/containerd.sock --container-runtime=remote   --containerd=/run/k3s/containerd/containerd.sock --cpu-cfs-quota=false --eviction-hard=imagefs.available\u003c5%,nodefs.available\u003c5% --eviction-mi  nimum-reclaim=imagefs.available=10%,nodefs.available=10% --fail-swap-on=false --healthz-bind-address=127.0.0.1 --hostname-override=local-node --kubeconf  ig=/var/lib/rancher/k3s/agent/kubelet.kubeconfig --kubelet-cgroups=/k3s --node-labels= --pod-manifest-path=/var/lib/rancher/k3s/agent/pod-manifests --re  ad-only-port=0 --resolv-conf=/etc/resolv.conf --runtime-cgroups=/k3s --serialize-image-pulls=false --tls-cert-file=/var/lib/rancher/k3s/agent/serving-ku  belet.crt --tls-private-key-file=/var/lib/rancher/k3s/agent/serving-kubelet.key\"\n","stream":"stderr","time":"2021-07-26T08:04:08.098721934Z"}
{"log":"time=\"2021-07-26T08:04:08.099328292Z\" level=info msg=\"Running kube-proxy --cluster-cidr=10.42.0.0/16 --healthz-bind-address=127.0.0.1 --hostn  ame-override=local-node --kubeconfig=/var/lib/rancher/k3s/agent/kubeproxy.kubeconfig --proxy-mode=iptables\"\n","stream":"stderr","time":"2021-07-26T08:  04:08.099441922Z"}
{"log":"Flag --cloud-provider has been deprecated, will be removed in 1.23, in favor of removing cloud provider code from Kubelet.\n","stream":"stderr",  "time":"2021-07-26T08:04:08.099553521Z"}
{"log":"Flag --containerd has been deprecated, This is a cadvisor flag that was mistakenly registered with the Kubelet. Due to legacy concerns, it will   follow the standard CLI deprecation timeline before being removed.\n","stream":"stderr","time":"2021-07-26T08:04:08.099575591Z"}
{"log":"W0726 08:04:08.099694      34 server.go:226] WARNING: all flags other than --config, --write-config-to, and --cleanup are deprecated. Please beg  in using a config file ASAP.\n","stream":"stderr","time":"2021-07-26T08:04:08.099765391Z"}
{"log":"I0726 08:04:08.099917      34 server.go:407] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:04:08.09998435Z"}
{"log":"W0726 08:04:08.100189      34 proxier.go:639] Failed to read file /lib/modules/4.15.0-151-generic/modules.builtin with error open /lib/modules/4  .15.0-151-generic/modules.builtin: no such file or directory. You can ignore this message when kube-proxy is running inside container without mounting /  lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:08.100258199Z"}
{"log":"W0726 08:04:08.100719      34 proxier.go:649] Failed to load kernel module ip_vs with modprobe. You can ignore this message when kube-proxy is r  unning inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:08.100776578Z"}
{"log":"W0726 08:04:08.101102      34 proxier.go:649] Failed to load kernel module ip_vs_rr with modprobe. You can ignore this message when kube-proxy i  s running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:08.101167758Z"}
{"log":"time=\"2021-07-26T08:04:08.107112582Z\" level=info msg=\"Waiting for node local-node CIDR not assigned yet\"\n","stream":"stderr","time":"2021-0  7-26T08:04:08.107211092Z"}
{"log":"W0726 08:04:08.111336      34 proxier.go:649] Failed to load kernel module ip_vs_wrr with modprobe. You can ignore this message when kube-proxy   is running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:08.111424301Z"}
{"log":"W0726 08:04:08.111686      34 proxier.go:649] Failed to load kernel module ip_vs_sh with modprobe. You can ignore this message when kube-proxy i  s running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:08.1117475Z"}
{"log":"W0726 08:04:08.112138      34 proxier.go:649] Failed to load kernel module nf_conntrack_ipv4 with modprobe. You can ignore this message when kub  e-proxy is running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:08.112192529Z"}
{"log":"E0726 08:04:08.117954      34 node.go:125] Failed to retrieve node info: nodes \"local-node\" is forbidden: User \"system:kube-proxy\" cannot ge  t resource \"nodes\" in API group \"\" at the cluster scope\n","stream":"stderr","time":"2021-07-26T08:04:08.118035405Z"}
{"log":"I0726 08:04:08.122352      34 dynamic_cafile_content.go:167] Starting client-ca-bundle::/var/lib/rancher/k3s/agent/client-ca.crt\n","stream":"st  derr","time":"2021-07-26T08:04:08.122419054Z"}
{"log":"I0726 08:04:08.321398      34 dynamic_cafile_content.go:167] Starting client-ca-bundle::/var/lib/rancher/k3s/server/tls/client-ca.crt\n","stream  ":"stderr","time":"2021-07-26T08:04:08.321539785Z"}
{"log":"I0726 08:04:08.321398      34 dynamic_cafile_content.go:167] Starting request-header::/var/lib/rancher/k3s/server/tls/request-header-ca.crt\n","  stream":"stderr","time":"2021-07-26T08:04:08.321595275Z"}
{"log":"I0726 08:04:08.321534      34 dynamic_serving_content.go:130] Starting serving-cert::/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.crt:  :/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.key\n","stream":"stderr","time":"2021-07-26T08:04:08.321603595Z"}
{"log":"I0726 08:04:08.321739      34 secure_serving.go:197] Serving securely on 127.0.0.1:6444\n","stream":"stderr","time":"2021-07-26T08:04:08.3218325  44Z"}
{"log":"I0726 08:04:08.321776      34 available_controller.go:475] Starting AvailableConditionController\n","stream":"stderr","time":"2021-07-26T08:04:0  8.321848514Z"}
{"log":"I0726 08:04:08.321787      34 cache.go:32] Waiting for caches to sync for AvailableConditionController controller\n","stream":"stderr","time":"2  021-07-26T08:04:08.321855354Z"}
{"log":"I0726 08:04:08.321804      34 tlsconfig.go:240] Starting DynamicServingCertificateController\n","stream":"stderr","time":"2021-07-26T08:04:08.32  1860994Z"}
{"log":"I0726 08:04:08.321868      34 customresource_discovery_controller.go:209] Starting DiscoveryController\n","stream":"stderr","time":"2021-07-26T0  8:04:08.322065573Z"}
{"log":"I0726 08:04:08.321918      34 apiservice_controller.go:97] Starting APIServiceRegistrationController\n","stream":"stderr","time":"2021-07-26T08:  04:08.322079133Z"}
{"log":"I0726 08:04:08.321928      34 cache.go:32] Waiting for caches to sync for APIServiceRegistrationController controller\n","stream":"stderr","time  ":"2021-07-26T08:04:08.322085143Z"}
{"log":"I0726 08:04:08.321944      34 dynamic_serving_content.go:130] Starting aggregator-proxy-cert::/var/lib/rancher/k3s/server/tls/client-auth-proxy.  crt::/var/lib/rancher/k3s/server/tls/client-auth-proxy.key\n","stream":"stderr","time":"2021-07-26T08:04:08.322101233Z"}
{"log":"I0726 08:04:08.321954      34 autoregister_controller.go:141] Starting autoregister controller\n","stream":"stderr","time":"2021-07-26T08:04:08.  322107333Z"}
{"log":"I0726 08:04:08.321960      34 cache.go:32] Waiting for caches to sync for autoregister controller\n","stream":"stderr","time":"2021-07-26T08:04:  08.322112633Z"}
{"log":"I0726 08:04:08.321976      34 crdregistration_controller.go:111] Starting crd-autoregister controller\n","stream":"stderr","time":"2021-07-26T08  :04:08.322117953Z"}
{"log":"I0726 08:04:08.321982      34 shared_informer.go:240] Waiting for caches to sync for crd-autoregister\n","stream":"stderr","time":"2021-07-26T08  :04:08.322123163Z"}
{"log":"I0726 08:04:08.321989      34 controller.go:83] Starting OpenAPI AggregationController\n","stream":"stderr","time":"2021-07-26T08:04:08.32212836  3Z"}
{"log":"I0726 08:04:08.322335      34 cluster_authentication_trust_controller.go:440] Starting cluster_authentication_trust_controller controller\n","st  ream":"stderr","time":"2021-07-26T08:04:08.322524762Z"}
{"log":"I0726 08:04:08.322346      34 shared_informer.go:240] Waiting for caches to sync for cluster_authentication_trust_controller\n","stream":"stderr  ","time":"2021-07-26T08:04:08.322538832Z"}
{"log":"I0726 08:04:08.322354      34 controller.go:86] Starting OpenAPI controller\n","stream":"stderr","time":"2021-07-26T08:04:08.322544592Z"}
{"log":"I0726 08:04:08.322376      34 naming_controller.go:291] Starting NamingConditionController\n","stream":"stderr","time":"2021-07-26T08:04:08.3225  49652Z"}
{"log":"I0726 08:04:08.322397      34 establishing_controller.go:76] Starting EstablishingController\n","stream":"stderr","time":"2021-07-26T08:04:08.32  2554942Z"}
{"log":"I0726 08:04:08.322425      34 nonstructuralschema_controller.go:186] Starting NonStructuralSchemaConditionController\n","stream":"stderr","time"  :"2021-07-26T08:04:08.322560092Z"}
{"log":"I0726 08:04:08.322446      34 apiapproval_controller.go:186] Starting KubernetesAPIApprovalPolicyConformantConditionController\n","stream":"stde  rr","time":"2021-07-26T08:04:08.322580332Z"}
{"log":"I0726 08:04:08.322469      34 crd_finalizer.go:266] Starting CRDFinalizer\n","stream":"stderr","time":"2021-07-26T08:04:08.322594552Z"}
{"log":"I0726 08:04:08.323023      34 dynamic_cafile_content.go:167] Starting client-ca-bundle::/var/lib/rancher/k3s/server/tls/client-ca.crt\n","stream  ":"stderr","time":"2021-07-26T08:04:08.323097471Z"}
{"log":"I0726 08:04:08.323041      34 dynamic_cafile_content.go:167] Starting request-header::/var/lib/rancher/k3s/server/tls/request-header-ca.crt\n","  stream":"stderr","time":"2021-07-26T08:04:08.323111611Z"}
{"log":"time=\"2021-07-26T08:04:08.326176113Z\" level=info msg=\"Running cloud-controller-manager --allocate-node-cidrs=true --allow-untagged-cloud=true   --bind-address=127.0.0.1 --cloud-provider=k3s --cluster-cidr=10.42.0.0/16 --kubeconfig=/var/lib/rancher/k3s/server/cred/cloud-controller.kubeconfig --n  ode-status-update-frequency=1m --profiling=false --secure-port=0\"\n","stream":"stderr","time":"2021-07-26T08:04:08.326287233Z"}
{"log":"Flag --allow-untagged-cloud has been deprecated, This flag is deprecated and will be removed in a future release. A cluster-id will be required   on cloud instances.\n","stream":"stderr","time":"2021-07-26T08:04:08.326306093Z"}
{"log":"I0726 08:04:08.345265      34 controllermanager.go:127] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:04:08.345403725Z"}
{"log":"W0726 08:04:08.345281      34 controllermanager.go:139] detected a cluster without a ClusterID.  A ClusterID will be required in the future.  Pl  ease tag your cluster to avoid any future issues\n","stream":"stderr","time":"2021-07-26T08:04:08.345442255Z"}
{"log":"I0726 08:04:08.345295      34 leaderelection.go:243] attempting to acquire leader lease  kube-system/cloud-controller-manager...\n","stream":"st  derr","time":"2021-07-26T08:04:08.345448855Z"}
{"log":"E0726 08:04:08.355907      34 controller.go:156] Unable to remove old endpoints from kubernetes service: no master IPs were listed in storage, r  efusing to erase all endpoints for the kubernetes service\n","stream":"stderr","time":"2021-07-26T08:04:08.355995878Z"}
{"log":"I0726 08:04:08.421914      34 cache.go:39] Caches are synced for AvailableConditionController controller\n","stream":"stderr","time":"2021-07-26  T08:04:08.422032233Z"}
{"log":"I0726 08:04:08.421984      34 cache.go:39] Caches are synced for APIServiceRegistrationController controller\n","stream":"stderr","time":"2021-0  7-26T08:04:08.422053233Z"}
{"log":"I0726 08:04:08.422138      34 cache.go:39] Caches are synced for autoregister controller\n","stream":"stderr","time":"2021-07-26T08:04:08.422241  732Z"}
{"log":"I0726 08:04:08.422453      34 shared_informer.go:247] Caches are synced for cluster_authentication_trust_controller \n","stream":"stderr","time"  :"2021-07-26T08:04:08.422546591Z"}
{"log":"I0726 08:04:08.422558      34 shared_informer.go:247] Caches are synced for crd-autoregister \n","stream":"stderr","time":"2021-07-26T08:04:08.4  22629431Z"}
{"log":"W0726 08:04:08.505665       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:08.505838162Z"}
{"log":"W0726 08:04:08.527670       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:08.527812677Z"}
{"log":"W0726 08:04:08.547750       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:08.547876107Z"}
{"log":"W0726 08:04:08.631143       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:08.631295938Z"}
{"log":"W0726 08:04:08.648264       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:08.648359016Z"}
{"log":"2021/07/26 08:04:08 [INFO] Starting API controllers\n","stream":"stdout","time":"2021-07-26T08:04:08.922135429Z"}
{"log":"2021/07/26 08:04:09 [INFO] Starting management.cattle.io/v3, Kind=Token controller\n","stream":"stdout","time":"2021-07-26T08:04:09.149496937Z"}
{"log":"2021/07/26 08:04:09 [INFO] Starting /v1, Kind=Secret controller\n","stream":"stdout","time":"2021-07-26T08:04:09.149536867Z"}
{"log":"2021/07/26 08:04:09 [INFO] Starting management.cattle.io/v3, Kind=UserAttribute controller\n","stream":"stdout","time":"2021-07-26T08:04:09.1495  43077Z"}
{"log":"2021/07/26 08:04:09 [INFO] Starting management.cattle.io/v3, Kind=Group controller\n","stream":"stdout","time":"2021-07-26T08:04:09.149609097Z"}
{"log":"2021/07/26 08:04:09 [INFO] Starting management.cattle.io/v3, Kind=User controller\n","stream":"stdout","time":"2021-07-26T08:04:09.149617917Z"}
{"log":"2021/07/26 08:04:09 [INFO] Starting management.cattle.io/v3, Kind=GroupMember controller\n","stream":"stdout","time":"2021-07-26T08:04:09.149623  087Z"}
{"log":"W0726 08:04:09.166910       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:09.167062663Z"}
{"log":"W0726 08:04:09.175238       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:09.175347582Z"}
{"log":"I0726 08:04:09.230118      34 node.go:136] Successfully retrieved node IP: 172.17.0.2\n","stream":"stderr","time":"2021-07-26T08:04:09.230240265  Z"}
{"log":"I0726 08:04:09.230154      34 server_others.go:143] kube-proxy node IP is an IPv4 address (172.17.0.2), assume IPv4 operation\n","stream":"stder  r","time":"2021-07-26T08:04:09.230274125Z"}
{"log":"I0726 08:04:09.230755      34 server_others.go:186] Using iptables Proxier.\n","stream":"stderr","time":"2021-07-26T08:04:09.230834903Z"}
{"log":"I0726 08:04:09.230932      34 server.go:650] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:04:09.230989763Z"}
{"log":"I0726 08:04:09.231282      34 conntrack.go:103] Set sysctl 'net/netfilter/nf_conntrack_max' to 196608\n","stream":"stderr","time":"2021-07-26T08  :04:09.231354922Z"}
{"log":"F0726 08:04:09.231307      34 server.go:495] open /proc/sys/net/netfilter/nf_conntrack_max: permission denied\n","stream":"stderr","time":"2021-  07-26T08:04:09.231370202Z"}
{"log":"W0726 08:04:09.245252       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1beta1.CustomResourceDefinition ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexp  ected watch close - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245292247Z"}
{"log":"W0726 08:04:09.245325       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.Secret ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - wat  ch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245371747Z"}
{"log":"W0726 08:04:09.245323       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.Role ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - watch   lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245400207Z"}
{"log":"W0726 08:04:09.245329       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Preference ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close -   watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245412797Z"}
{"log":"W0726 08:04:09.245371       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.ClusterRepo ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close   - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245433497Z"}
{"log":"W0726 08:04:09.245372       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.ClusterRoleBinding ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch   close - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245436627Z"}
{"log":"W0726 08:04:09.245397       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.RoleBinding ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close   - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245439207Z"}
{"log":"W0726 08:04:09.245501       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Cluster ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - wa  tch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245569926Z"}
{"log":"W0726 08:04:09.245569       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Setting ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - wa  tch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245616466Z"}
{"log":"W0726 08:04:09.245617       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Feature ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - wa  tch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245660716Z"}
{"log":"W0726 08:04:09.245631       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.Secret ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - wat  ch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245676036Z"}
{"log":"W0726 08:04:09.245649       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.ClusterRole ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close   - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245713376Z"}
{"log":"W0726 08:04:09.245682       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.APIService ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close -   watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245720586Z"}
{"log":"W0726 08:04:09.245715       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.GroupMember ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close   - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245754576Z"}
{"log":"W0726 08:04:09.245717       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.User ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - watch   lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245762896Z"}
{"log":"W0726 08:04:09.245736       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.ConfigMap ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close -   watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245767446Z"}
{"log":"W0726 08:04:09.245812       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Group ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - watc  h lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245858366Z"}
{"log":"W0726 08:04:09.245821       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.UserAttribute ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch clos  e - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245878545Z"}
{"log":"W0726 08:04:09.245856       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Token ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - watc  h lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:09.245936345Z"}
{"log":"2021/07/26 08:04:09 [FATAL] k3s exited with: exit status 1\n","stream":"stdout","time":"2021-07-26T08:04:09.246330084Z"}
{"log":"2021/07/26 08:04:10 [INFO] Rancher version v2.5.9 (3c5418944) is starting\n","stream":"stdout","time":"2021-07-26T08:04:10.11920281Z"}
{"log":"2021/07/26 08:04:10 [INFO] Rancher arguments {ACMEDomains:[] AddLocal:true Embedded:false BindHost: HTTPListenPort:80 HTTPSListenPort:443 K8sMod  e:auto Debug:false Trace:false NoCACerts:false AuditLogPath:/var/log/auditlog/rancher-api-audit.log AuditLogMaxage:10 AuditLogMaxsize:100 AuditLogMaxbac  kup:10 AuditLevel:0 Agent:false Features: ClusterRegistry:}\n","stream":"stdout","time":"2021-07-26T08:04:10.11924818Z"}
{"log":"2021/07/26 08:04:10 [INFO] Listening on /tmp/log.sock\n","stream":"stdout","time":"2021-07-26T08:04:10.11925932Z"}
{"log":"2021/07/26 08:04:10 [INFO] Running etcd --data-dir=management-state/etcd --heartbeat-interval=500 --election-timeout=5000\n","stream":"stdout","  time":"2021-07-26T08:04:10.11947186Z"}
{"log":"2021-07-26 08:04:10.127116 W | pkg/flags: unrecognized environment variable ETCD_URL_arm64=https://github.com/etcd-io/etcd/releases/download/v3.  4.3/etcd-v3.4.3-linux-arm64.tar.gz\n","stream":"stderr","time":"2021-07-26T08:04:10.12725742Z"}
{"log":"2021-07-26 08:04:10.127156 W | pkg/flags: unrecognized environment variable ETCD_URL_amd64=https://github.com/etcd-io/etcd/releases/download/v3.  4.3/etcd-v3.4.3-linux-amd64.tar.gz\n","stream":"stderr","time":"2021-07-26T08:04:10.12728018Z"}
{"log":"2021-07-26 08:04:10.127159 W | pkg/flags: unrecognized environment variable ETCD_UNSUPPORTED_ARCH=amd64\n","stream":"stderr","time":"2021-07-26T  08:04:10.12729758Z"}
{"log":"2021-07-26 08:04:10.127163 W | pkg/flags: unrecognized environment variable ETCD_URL=ETCD_URL_amd64\n","stream":"stderr","time":"2021-07-26T08:0  4:10.12730118Z"}
{"log":"[WARNING] Deprecated '--logger=capnslog' flag is set; use '--logger=zap' flag instead\n","stream":"stderr","time":"2021-07-26T08:04:10.12730435Z  "}
{"log":"2021-07-26 08:04:10.127187 I | etcdmain: etcd Version: 3.4.3\n","stream":"stderr","time":"2021-07-26T08:04:10.12730748Z"}
{"log":"2021-07-26 08:04:10.127191 I | etcdmain: Git SHA: 3cf2f69b5\n","stream":"stderr","time":"2021-07-26T08:04:10.12731049Z"}
{"log":"2021-07-26 08:04:10.127195 I | etcdmain: Go Version: go1.12.12\n","stream":"stderr","time":"2021-07-26T08:04:10.12731342Z"}
{"log":"2021-07-26 08:04:10.127198 I | etcdmain: Go OS/Arch: linux/amd64\n","stream":"stderr","time":"2021-07-26T08:04:10.12731643Z"}
{"log":"2021-07-26 08:04:10.127202 I | etcdmain: setting maximum number of CPUs to 6, total number of available CPUs is 6\n","stream":"stderr","time":"2  021-07-26T08:04:10.12731944Z"}
{"log":"2021-07-26 08:04:10.127242 N | etcdmain: the server is already initialized as member before, starting as etcd member...\n","stream":"stderr","ti  me":"2021-07-26T08:04:10.12732594Z"}
{"log":"[WARNING] Deprecated '--logger=capnslog' flag is set; use '--logger=zap' flag instead\n","stream":"stderr","time":"2021-07-26T08:04:10.12732951Z  "}
{"log":"2021-07-26 08:04:10.127533 I | embed: name = default\n","stream":"stderr","time":"2021-07-26T08:04:10.127594669Z"}
{"log":"2021-07-26 08:04:10.127543 I | embed: data dir = management-state/etcd\n","stream":"stderr","time":"2021-07-26T08:04:10.127608839Z"}
{"log":"2021-07-26 08:04:10.127547 I | embed: member dir = management-state/etcd/member\n","stream":"stderr","time":"2021-07-26T08:04:10.127614039Z"}
{"log":"2021-07-26 08:04:10.127549 I | embed: heartbeat = 500ms\n","stream":"stderr","time":"2021-07-26T08:04:10.127617929Z"}
{"log":"2021-07-26 08:04:10.127552 I | embed: election = 5000ms\n","stream":"stderr","time":"2021-07-26T08:04:10.127621589Z"}
{"log":"2021-07-26 08:04:10.127554 I | embed: snapshot count = 100000\n","stream":"stderr","time":"2021-07-26T08:04:10.127625349Z"}
{"log":"2021-07-26 08:04:10.127560 I | embed: advertise client URLs = http://localhost:2379\n","stream":"stderr","time":"2021-07-26T08:04:10.127629339Z"  }
{"log":"2021-07-26 08:04:10.127564 I | embed: initial advertise peer URLs = http://localhost:2380\n","stream":"stderr","time":"2021-07-26T08:04:10.12763  3389Z"}
{"log":"2021-07-26 08:04:10.127568 I | embed: initial cluster = \n","stream":"stderr","time":"2021-07-26T08:04:10.127637339Z"}
{"log":"2021-07-26 08:04:10.135107 I | etcdserver: restarting member 8e9e05c52164694d in cluster cdf818194e3a8c32 at commit index 1282\n","stream":"stde  rr","time":"2021-07-26T08:04:10.135289509Z"}
{"log":"raft2021/07/26 08:04:10 INFO: 8e9e05c52164694d switched to configuration voters=()\n","stream":"stderr","time":"2021-07-26T08:04:10.135359209Z"}
{"log":"raft2021/07/26 08:04:10 INFO: 8e9e05c52164694d became follower at term 40\n","stream":"stderr","time":"2021-07-26T08:04:10.135425189Z"}
{"log":"raft2021/07/26 08:04:10 INFO: newRaft 8e9e05c52164694d [peers: [], term: 40, commit: 1282, applied: 0, lastindex: 1282, lastterm: 40]\n","stream  ":"stderr","time":"2021-07-26T08:04:10.135436659Z"}
{"log":"2021-07-26 08:04:10.139167 W | auth: simple token is not cryptographically signed\n","stream":"stderr","time":"2021-07-26T08:04:10.13924362Z"}
{"log":"2021-07-26 08:04:10.140913 I | etcdserver: starting server... [version: 3.4.3, cluster version: to_be_decided]\n","stream":"stderr","time":"2021  -07-26T08:04:10.140987015Z"}
{"log":"raft2021/07/26 08:04:10 INFO: 8e9e05c52164694d switched to configuration voters=(10276657743932975437)\n","stream":"stderr","time":"2021-07-26T0  8:04:10.141204374Z"}
{"log":"2021-07-26 08:04:10.141206 I | etcdserver/membership: added member 8e9e05c52164694d [http://localhost:2380] to cluster cdf818194e3a8c32\n","stre  am":"stderr","time":"2021-07-26T08:04:10.141246404Z"}
{"log":"2021-07-26 08:04:10.141281 N | etcdserver/membership: set the initial cluster version to 3.4\n","stream":"stderr","time":"2021-07-26T08:04:10.14  1323324Z"}
{"log":"2021-07-26 08:04:10.141302 I | etcdserver/api: enabled capabilities for version 3.4\n","stream":"stderr","time":"2021-07-26T08:04:10.141332274Z"  }
{"log":"2021-07-26 08:04:10.144687 I | embed: listening for peers on 127.0.0.1:2380\n","stream":"stderr","time":"2021-07-26T08:04:10.144756765Z"}
{"log":"raft2021/07/26 08:04:18 INFO: 8e9e05c52164694d is starting a new election at term 40\n","stream":"stderr","time":"2021-07-26T08:04:18.635834616Z  "}
{"log":"raft2021/07/26 08:04:18 INFO: 8e9e05c52164694d became candidate at term 41\n","stream":"stderr","time":"2021-07-26T08:04:18.635869725Z"}
{"log":"raft2021/07/26 08:04:18 INFO: 8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 41\n","stream":"stderr","time":"2021-07-26T08:  04:18.635875965Z"}
{"log":"raft2021/07/26 08:04:18 INFO: 8e9e05c52164694d became leader at term 41\n","stream":"stderr","time":"2021-07-26T08:04:18.635881895Z"}
{"log":"raft2021/07/26 08:04:18 INFO: raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 41\n","stream":"stderr","time":"2021-07-26T08:  04:18.635887375Z"}
{"log":"2021-07-26 08:04:18.635993 I | etcdserver: published {Name:default ClientURLs:[http://localhost:2379]} to cluster cdf818194e3a8c32\n","stream":"  stderr","time":"2021-07-26T08:04:18.636092835Z"}
{"log":"2021-07-26 08:04:18.636029 I | embed: ready to serve client requests\n","stream":"stderr","time":"2021-07-26T08:04:18.636107625Z"}
{"log":"2021-07-26 08:04:18.636823 N | embed: serving insecure client requests on 127.0.0.1:2379, this is strongly discouraged!\n","stream":"stderr","ti  me":"2021-07-26T08:04:18.636925962Z"}
{"log":"2021/07/26 08:04:18 [INFO] Waiting for server to become available: Get \"https://127.0.0.1:6443/version?timeout=15m0s\": dial tcp 127.0.0.1:6443  : connect: connection refused\n","stream":"stdout","time":"2021-07-26T08:04:18.651747184Z"}
{"log":"time=\"2021-07-26T08:04:18.827743931Z\" level=info msg=\"Starting k3s v1.19.8+k3s1 (95fc76b2)\"\n","stream":"stderr","time":"2021-07-26T08:04:18  .82788527Z"}
{"log":"time=\"2021-07-26T08:04:18.827996560Z\" level=info msg=\"Cluster bootstrap already complete\"\n","stream":"stderr","time":"2021-07-26T08:04:18.8  2808683Z"}
{"log":"time=\"2021-07-26T08:04:18.843108331Z\" level=info msg=\"Running kube-apiserver --advertise-port=6443 --allow-privileged=true --anonymous-auth=f  alse --api-audiences=unknown --authorization-mode=Node,RBAC --bind-address=127.0.0.1 --cert-dir=/var/lib/rancher/k3s/server/tls/temporary-certs --client  -ca-file=/var/lib/rancher/k3s/server/tls/client-ca.crt --enable-admission-plugins=NodeRestriction --etcd-servers=http://localhost:2379 --insecure-port=0   --kubelet-certificate-authority=/var/lib/rancher/k3s/server/tls/server-ca.crt --kubelet-client-certificate=/var/lib/rancher/k3s/server/tls/client-kube-  apiserver.crt --kubelet-client-key=/var/lib/rancher/k3s/server/tls/client-kube-apiserver.key --profiling=false --proxy-client-cert-file=/var/lib/rancher  /k3s/server/tls/client-auth-proxy.crt --proxy-client-key-file=/var/lib/rancher/k3s/server/tls/client-auth-proxy.key --requestheader-allowed-names=system  :auth-proxy --requestheader-client-ca-file=/var/lib/rancher/k3s/server/tls/request-header-ca.crt --requestheader-extra-headers-prefix=X-Remote-Extra- --  requestheader-group-headers=X-Remote-Group --requestheader-username-headers=X-Remote-User --secure-port=6444 --service-account-issuer=k3s --service-acco  unt-key-file=/var/lib/rancher/k3s/server/tls/service.key --service-account-signing-key-file=/var/lib/rancher/k3s/server/tls/service.key --service-cluste  r-ip-range=10.43.0.0/16 --storage-backend=etcd3 --tls-cert-file=/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.crt --tls-private-key-file=/var/l  ib/rancher/k3s/server/tls/serving-kube-apiserver.key\"\n","stream":"stderr","time":"2021-07-26T08:04:18.843244941Z"}
{"log":"I0726 08:04:18.844308      34 server.go:652] external host was not specified, using 172.17.0.2\n","stream":"stderr","time":"2021-07-26T08:04:18.  844369739Z"}
{"log":"I0726 08:04:18.844547      34 server.go:177] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:04:18.844596328Z"}
{"log":"I0726 08:04:18.849262      34 plugins.go:158] Loaded 12 mutating admission controller(s) successfully in the following order: NamespaceLifecycle  ,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,Run  timeClass,DefaultIngressClass,MutatingAdmissionWebhook.\n","stream":"stderr","time":"2021-07-26T08:04:18.849332426Z"}
{"log":"I0726 08:04:18.849278      34 plugins.go:161] Loaded 10 validating admission controller(s) successfully in the following order: LimitRanger,Serv  iceAccount,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,CertificateSubjectRestriction,ValidatingAdmissionWeb  hook,ResourceQuota.\n","stream":"stderr","time":"2021-07-26T08:04:18.849348046Z"}
{"log":"I0726 08:04:18.850277      34 plugins.go:158] Loaded 12 mutating admission controller(s) successfully in the following order: NamespaceLifecycle  ,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,Run  timeClass,DefaultIngressClass,MutatingAdmissionWebhook.\n","stream":"stderr","time":"2021-07-26T08:04:18.850341403Z"}
{"log":"I0726 08:04:18.850292      34 plugins.go:161] Loaded 10 validating admission controller(s) successfully in the following order: LimitRanger,Serv  iceAccount,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,CertificateSubjectRestriction,ValidatingAdmissionWeb  hook,ResourceQuota.\n","stream":"stderr","time":"2021-07-26T08:04:18.850352503Z"}
{"log":"I0726 08:04:18.872268      34 master.go:271] Using reconciler: lease\n","stream":"stderr","time":"2021-07-26T08:04:18.872413556Z"}
{"log":"W0726 08:04:19.129091      34 genericapiserver.go:412] Skipping API batch/v2alpha1 because it has no resources.\n","stream":"stderr","time":"202  1-07-26T08:04:19.129231429Z"}
{"log":"W0726 08:04:19.137919      34 genericapiserver.go:412] Skipping API discovery.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr",  "time":"2021-07-26T08:04:19.138015956Z"}
{"log":"W0726 08:04:19.147825      34 genericapiserver.go:412] Skipping API node.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr","time  ":"2021-07-26T08:04:19.14791553Z"}
{"log":"W0726 08:04:19.162348      34 genericapiserver.go:412] Skipping API rbac.authorization.k8s.io/v1alpha1 because it has no resources.\n","stream":  "stderr","time":"2021-07-26T08:04:19.162446653Z"}
{"log":"W0726 08:04:19.170632      34 genericapiserver.go:412] Skipping API scheduling.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr"  ,"time":"2021-07-26T08:04:19.170766432Z"}
{"log":"W0726 08:04:19.182603      34 genericapiserver.go:412] Skipping API storage.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr","t  ime":"2021-07-26T08:04:19.182706931Z"}
{"log":"W0726 08:04:19.194285      34 genericapiserver.go:412] Skipping API apps/v1beta2 because it has no resources.\n","stream":"stderr","time":"2021-  07-26T08:04:19.194399821Z"}
{"log":"W0726 08:04:19.194304      34 genericapiserver.go:412] Skipping API apps/v1beta1 because it has no resources.\n","stream":"stderr","time":"2021-  07-26T08:04:19.194431391Z"}
{"log":"I0726 08:04:19.200843      34 plugins.go:158] Loaded 12 mutating admission controller(s) successfully in the following order: NamespaceLifecycle  ,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,Run  timeClass,DefaultIngressClass,MutatingAdmissionWebhook.\n","stream":"stderr","time":"2021-07-26T08:04:19.200924424Z"}
{"log":"I0726 08:04:19.200857      34 plugins.go:161] Loaded 10 validating admission controller(s) successfully in the following order: LimitRanger,Serv  iceAccount,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,CertificateSubjectRestriction,ValidatingAdmissionWeb  hook,ResourceQuota.\n","stream":"stderr","time":"2021-07-26T08:04:19.200937573Z"}
{"log":"time=\"2021-07-26T08:04:19.207108248Z\" level=info msg=\"Running kube-scheduler --address=127.0.0.1 --bind-address=127.0.0.1 --kubeconfig=/var/l  ib/rancher/k3s/server/cred/scheduler.kubeconfig --port=10251 --profiling=false --secure-port=0\"\n","stream":"stderr","time":"2021-07-26T08:04:19.207238  327Z"}
{"log":"time=\"2021-07-26T08:04:19.207170508Z\" level=info msg=\"Waiting for API server to become available\"\n","stream":"stderr","time":"2021-07-26T08  :04:19.207304447Z"}
{"log":"I0726 08:04:19.207484      34 registry.go:173] Registering SelectorSpread plugin\n","stream":"stderr","time":"2021-07-26T08:04:19.207558467Z"}
{"log":"I0726 08:04:19.207502      34 registry.go:173] Registering SelectorSpread plugin\n","stream":"stderr","time":"2021-07-26T08:04:19.207566297Z"}
{"log":"time=\"2021-07-26T08:04:19.207807456Z\" level=info msg=\"Running kube-controller-manager --address=127.0.0.1 --allocate-node-cidrs=true --bind-a  ddress=127.0.0.1 --cluster-cidr=10.42.0.0/16 --cluster-signing-cert-file=/var/lib/rancher/k3s/server/tls/client-ca.crt --cluster-signing-key-file=/var/l  ib/rancher/k3s/server/tls/client-ca.key --kubeconfig=/var/lib/rancher/k3s/server/cred/controller.kubeconfig --port=10252 --profiling=false --root-ca-fil  e=/var/lib/rancher/k3s/server/tls/server-ca.crt --secure-port=0 --service-account-private-key-file=/var/lib/rancher/k3s/server/tls/service.key --use-ser  vice-account-credentials=true\"\n","stream":"stderr","time":"2021-07-26T08:04:19.207886886Z"}
{"log":"time=\"2021-07-26T08:04:19.209050044Z\" level=info msg=\"Node token is available at /var/lib/rancher/k3s/server/token\"\n","stream":"stderr","ti  me":"2021-07-26T08:04:19.209132763Z"}
{"log":"time=\"2021-07-26T08:04:19.209076723Z\" level=info msg=\"To join node to cluster: k3s agent -s https://172.17.0.2:6443 -t ${NODE_TOKEN}\"\n","st  ream":"stderr","time":"2021-07-26T08:04:19.209150203Z"}
{"log":"time=\"2021-07-26T08:04:19.210071101Z\" level=info msg=\"Wrote kubeconfig /etc/rancher/k3s/k3s.yaml\"\n","stream":"stderr","time":"2021-07-26T08  :04:19.210139111Z"}
{"log":"time=\"2021-07-26T08:04:19.210096041Z\" level=info msg=\"Run: k3s kubectl\"\n","stream":"stderr","time":"2021-07-26T08:04:19.210146541Z"}
{"log":"time=\"2021-07-26T08:04:19.210181520Z\" level=info msg=\"Module overlay was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:04:19.21  025196Z"}
{"log":"time=\"2021-07-26T08:04:19.210200540Z\" level=info msg=\"Module nf_conntrack was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:04:  19.21026579Z"}
{"log":"time=\"2021-07-26T08:04:19.210210980Z\" level=info msg=\"Module br_netfilter was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:04:  19.2102705Z"}
{"log":"time=\"2021-07-26T08:04:19.210223970Z\" level=info msg=\"Module iptable_nat was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:04:1  9.21027548Z"}
{"log":"time=\"2021-07-26T08:04:19.220982773Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:04:19 http: TLS handshake error from 127.0.0.1:56746:   remote error: tls: bad certificate\"\n","stream":"stderr","time":"2021-07-26T08:04:19.221114062Z"}
{"log":"time=\"2021-07-26T08:04:19.224668684Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:04:19 http: TLS handshake error from 127.0.0.1:56752:   remote error: tls: bad certificate\"\n","stream":"stderr","time":"2021-07-26T08:04:19.224771513Z"}
{"log":"time=\"2021-07-26T08:04:19.234845346Z\" level=info msg=\"certificate CN=local-node signed by CN=k3s-server-ca@1627286228: notBefore=2021-07-26 0  7:57:08 +0000 UTC notAfter=2022-07-26 08:04:19 +0000 UTC\"\n","stream":"stderr","time":"2021-07-26T08:04:19.234964056Z"}
{"log":"time=\"2021-07-26T08:04:19.237605310Z\" level=info msg=\"certificate CN=system:node:local-node,O=system:nodes signed by CN=k3s-client-ca@1627286  228: notBefore=2021-07-26 07:57:08 +0000 UTC notAfter=2022-07-26 08:04:19 +0000 UTC\"\n","stream":"stderr","time":"2021-07-26T08:04:19.23769726Z"}
{"log":"time=\"2021-07-26T08:04:19.243574565Z\" level=info msg=\"Logging containerd to /var/lib/rancher/k3s/agent/containerd/containerd.log\"\n","stream  ":"stderr","time":"2021-07-26T08:04:19.243677625Z"}
{"log":"time=\"2021-07-26T08:04:19.243719525Z\" level=info msg=\"Running containerd -c /var/lib/rancher/k3s/agent/etc/containerd/config.toml -a /run/k3s  /containerd/containerd.sock --state /run/k3s/containerd --root /var/lib/rancher/k3s/agent/containerd\"\n","stream":"stderr","time":"2021-07-26T08:04:19.  243804834Z"}
{"log":"time=\"2021-07-26T08:04:20.245118162Z\" level=info msg=\"Containerd is now running\"\n","stream":"stderr","time":"2021-07-26T08:04:20.245282612Z  "}
{"log":"time=\"2021-07-26T08:04:20.251532286Z\" level=info msg=\"Connecting to proxy\" url=\"wss://127.0.0.1:6443/v1-k3s/connect\"\n","stream":"stderr",  "time":"2021-07-26T08:04:20.251666016Z"}
{"log":"time=\"2021-07-26T08:04:20.253686140Z\" level=info msg=\"Handling backend connection request [local-node]\"\n","stream":"stderr","time":"2021-07  -26T08:04:20.253778491Z"}
{"log":"time=\"2021-07-26T08:04:20.254145660Z\" level=warning msg=\"Disabling CPU quotas due to missing cpu.cfs_period_us\"\n","stream":"stderr","time":  "2021-07-26T08:04:20.254237589Z"}
{"log":"time=\"2021-07-26T08:04:20.254222929Z\" level=info msg=\"Running kubelet --address=0.0.0.0 --anonymous-auth=false --authentication-token-webhook  =true --authorization-mode=Webhook --cgroup-driver=cgroupfs --client-ca-file=/var/lib/rancher/k3s/agent/client-ca.crt --cloud-provider=external --cluste  r-dns=10.43.0.10 --cluster-domain=cluster.local --cni-bin-dir=/var/lib/rancher/k3s/data/ab1b521f47b3cbc273e0c667521084cff1ea3540d55b425ac7ed30c27daf92f8  /bin --cni-conf-dir=/var/lib/rancher/k3s/agent/etc/cni/net.d --container-runtime-endpoint=/run/k3s/containerd/containerd.sock --container-runtime=remote   --containerd=/run/k3s/containerd/containerd.sock --cpu-cfs-quota=false --eviction-hard=imagefs.available\u003c5%,nodefs.available\u003c5% --eviction-mi  nimum-reclaim=imagefs.available=10%,nodefs.available=10% --fail-swap-on=false --healthz-bind-address=127.0.0.1 --hostname-override=local-node --kubeconf  ig=/var/lib/rancher/k3s/agent/kubelet.kubeconfig --kubelet-cgroups=/k3s --node-labels= --pod-manifest-path=/var/lib/rancher/k3s/agent/pod-manifests --re  ad-only-port=0 --resolv-conf=/etc/resolv.conf --runtime-cgroups=/k3s --serialize-image-pulls=false --tls-cert-file=/var/lib/rancher/k3s/agent/serving-ku  belet.crt --tls-private-key-file=/var/lib/rancher/k3s/agent/serving-kubelet.key\"\n","stream":"stderr","time":"2021-07-26T08:04:20.254338189Z"}
{"log":"time=\"2021-07-26T08:04:20.254864718Z\" level=info msg=\"Running kube-proxy --cluster-cidr=10.42.0.0/16 --healthz-bind-address=127.0.0.1 --hostn  ame-override=local-node --kubeconfig=/var/lib/rancher/k3s/agent/kubeproxy.kubeconfig --proxy-mode=iptables\"\n","stream":"stderr","time":"2021-07-26T08:  04:20.254944277Z"}
{"log":"Flag --cloud-provider has been deprecated, will be removed in 1.23, in favor of removing cloud provider code from Kubelet.\n","stream":"stderr",  "time":"2021-07-26T08:04:20.255071247Z"}
{"log":"Flag --containerd has been deprecated, This is a cadvisor flag that was mistakenly registered with the Kubelet. Due to legacy concerns, it will   follow the standard CLI deprecation timeline before being removed.\n","stream":"stderr","time":"2021-07-26T08:04:20.255077707Z"}
{"log":"W0726 08:04:20.255165      34 server.go:226] WARNING: all flags other than --config, --write-config-to, and --cleanup are deprecated. Please beg  in using a config file ASAP.\n","stream":"stderr","time":"2021-07-26T08:04:20.255220077Z"}
{"log":"I0726 08:04:20.255465      34 server.go:407] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:04:20.255512096Z"}
{"log":"W0726 08:04:20.255572      34 proxier.go:639] Failed to read file /lib/modules/4.15.0-151-generic/modules.builtin with error open /lib/modules/4  .15.0-151-generic/modules.builtin: no such file or directory. You can ignore this message when kube-proxy is running inside container without mounting /  lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:20.255615625Z"}
{"log":"W0726 08:04:20.256087      34 proxier.go:649] Failed to load kernel module ip_vs with modprobe. You can ignore this message when kube-proxy is r  unning inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:20.256135914Z"}
{"log":"time=\"2021-07-26T08:04:20.261957889Z\" level=info msg=\"Waiting for node local-node CIDR not assigned yet\"\n","stream":"stderr","time":"2021-0  7-26T08:04:20.262045499Z"}
{"log":"W0726 08:04:20.271423      34 proxier.go:649] Failed to load kernel module ip_vs_rr with modprobe. You can ignore this message when kube-proxy i  s running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:20.271557264Z"}
{"log":"W0726 08:04:20.271778      34 proxier.go:649] Failed to load kernel module ip_vs_wrr with modprobe. You can ignore this message when kube-proxy   is running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:20.271853364Z"}
{"log":"W0726 08:04:20.272087      34 proxier.go:649] Failed to load kernel module ip_vs_sh with modprobe. You can ignore this message when kube-proxy i  s running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:20.272159583Z"}
{"log":"W0726 08:04:20.272425      34 proxier.go:649] Failed to load kernel module nf_conntrack_ipv4 with modprobe. You can ignore this message when kub  e-proxy is running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:20.272506602Z"}
{"log":"I0726 08:04:20.275545      34 dynamic_cafile_content.go:167] Starting client-ca-bundle::/var/lib/rancher/k3s/agent/client-ca.crt\n","stream":"st  derr","time":"2021-07-26T08:04:20.275630924Z"}
{"log":"E0726 08:04:20.277984      34 node.go:125] Failed to retrieve node info: nodes \"local-node\" is forbidden: User \"system:kube-proxy\" cannot ge  t resource \"nodes\" in API group \"\" at the cluster scope\n","stream":"stderr","time":"2021-07-26T08:04:20.278066608Z"}
{"log":"I0726 08:04:20.470800      34 dynamic_cafile_content.go:167] Starting request-header::/var/lib/rancher/k3s/server/tls/request-header-ca.crt\n","  stream":"stderr","time":"2021-07-26T08:04:20.470946712Z"}
{"log":"I0726 08:04:20.470809      34 dynamic_cafile_content.go:167] Starting client-ca-bundle::/var/lib/rancher/k3s/server/tls/client-ca.crt\n","stream  ":"stderr","time":"2021-07-26T08:04:20.470977112Z"}
{"log":"I0726 08:04:20.470888      34 dynamic_serving_content.go:130] Starting serving-cert::/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.crt:  :/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.key\n","stream":"stderr","time":"2021-07-26T08:04:20.470981782Z"}
{"log":"I0726 08:04:20.471062      34 secure_serving.go:197] Serving securely on 127.0.0.1:6444\n","stream":"stderr","time":"2021-07-26T08:04:20.4711399  71Z"}
{"log":"I0726 08:04:20.471208      34 apiservice_controller.go:97] Starting APIServiceRegistrationController\n","stream":"stderr","time":"2021-07-26T08:  04:20.471297951Z"}
{"log":"I0726 08:04:20.471368      34 cache.go:32] Waiting for caches to sync for APIServiceRegistrationController controller\n","stream":"stderr","time  ":"2021-07-26T08:04:20.47143124Z"}
{"log":"I0726 08:04:20.471532      34 autoregister_controller.go:141] Starting autoregister controller\n","stream":"stderr","time":"2021-07-26T08:04:20.  471599Z"}
{"log":"I0726 08:04:20.471538      34 crdregistration_controller.go:111] Starting crd-autoregister controller\n","stream":"stderr","time":"2021-07-26T08  :04:20.47160787Z"}
{"log":"I0726 08:04:20.471547      34 cache.go:32] Waiting for caches to sync for autoregister controller\n","stream":"stderr","time":"2021-07-26T08:04:  20.47161174Z"}
{"log":"I0726 08:04:20.471552      34 shared_informer.go:240] Waiting for caches to sync for crd-autoregister\n","stream":"stderr","time":"2021-07-26T08  :04:20.47161544Z"}
{"log":"I0726 08:04:20.471219      34 tlsconfig.go:240] Starting DynamicServingCertificateController\n","stream":"stderr","time":"2021-07-26T08:04:20.47  169257Z"}
{"log":"I0726 08:04:20.471645      34 naming_controller.go:291] Starting NamingConditionController\n","stream":"stderr","time":"2021-07-26T08:04:20.4717  1361Z"}
{"log":"I0726 08:04:20.471735      34 establishing_controller.go:76] Starting EstablishingController\n","stream":"stderr","time":"2021-07-26T08:04:20.47  1791199Z"}
{"log":"I0726 08:04:20.471245      34 controller.go:83] Starting OpenAPI AggregationController\n","stream":"stderr","time":"2021-07-26T08:04:20.47179827  9Z"}
{"log":"I0726 08:04:20.471768      34 nonstructuralschema_controller.go:186] Starting NonStructuralSchemaConditionController\n","stream":"stderr","time"  :"2021-07-26T08:04:20.471801919Z"}
{"log":"I0726 08:04:20.471787      34 apiapproval_controller.go:186] Starting KubernetesAPIApprovalPolicyConformantConditionController\n","stream":"stde  rr","time":"2021-07-26T08:04:20.471815129Z"}
{"log":"I0726 08:04:20.471817      34 crd_finalizer.go:266] Starting CRDFinalizer\n","stream":"stderr","time":"2021-07-26T08:04:20.471863339Z"}
{"log":"I0726 08:04:20.471296      34 dynamic_serving_content.go:130] Starting aggregator-proxy-cert::/var/lib/rancher/k3s/server/tls/client-auth-proxy.  crt::/var/lib/rancher/k3s/server/tls/client-auth-proxy.key\n","stream":"stderr","time":"2021-07-26T08:04:20.471951369Z"}
{"log":"I0726 08:04:20.471316      34 available_controller.go:475] Starting AvailableConditionController\n","stream":"stderr","time":"2021-07-26T08:04:2  0.471967559Z"}
{"log":"I0726 08:04:20.471901      34 cache.go:32] Waiting for caches to sync for AvailableConditionController controller\n","stream":"stderr","time":"2  021-07-26T08:04:20.471973639Z"}
{"log":"I0726 08:04:20.471350      34 customresource_discovery_controller.go:209] Starting DiscoveryController\n","stream":"stderr","time":"2021-07-26T0  8:04:20.472059749Z"}
{"log":"I0726 08:04:20.472245      34 controller.go:86] Starting OpenAPI controller\n","stream":"stderr","time":"2021-07-26T08:04:20.472323288Z"}
{"log":"I0726 08:04:20.472501      34 cluster_authentication_trust_controller.go:440] Starting cluster_authentication_trust_controller controller\n","st  ream":"stderr","time":"2021-07-26T08:04:20.472556677Z"}
{"log":"I0726 08:04:20.472569      34 shared_informer.go:240] Waiting for caches to sync for cluster_authentication_trust_controller\n","stream":"stderr  ","time":"2021-07-26T08:04:20.472631157Z"}
{"log":"I0726 08:04:20.472675      34 dynamic_cafile_content.go:167] Starting client-ca-bundle::/var/lib/rancher/k3s/server/tls/client-ca.crt\n","stream  ":"stderr","time":"2021-07-26T08:04:20.472725967Z"}
{"log":"I0726 08:04:20.472768      34 dynamic_cafile_content.go:167] Starting request-header::/var/lib/rancher/k3s/server/tls/request-header-ca.crt\n","  stream":"stderr","time":"2021-07-26T08:04:20.472832216Z"}
{"log":"E0726 08:04:20.488143      34 controller.go:156] Unable to remove old endpoints from kubernetes service: no master IPs were listed in storage, r  efusing to erase all endpoints for the kubernetes service\n","stream":"stderr","time":"2021-07-26T08:04:20.488251007Z"}
{"log":"time=\"2021-07-26T08:04:20.502688430Z\" level=info msg=\"Running cloud-controller-manager --allocate-node-cidrs=true --allow-untagged-cloud=true   --bind-address=127.0.0.1 --cloud-provider=k3s --cluster-cidr=10.42.0.0/16 --kubeconfig=/var/lib/rancher/k3s/server/cred/cloud-controller.kubeconfig --n  ode-status-update-frequency=1m --profiling=false --secure-port=0\"\n","stream":"stderr","time":"2021-07-26T08:04:20.502866049Z"}
{"log":"Flag --allow-untagged-cloud has been deprecated, This flag is deprecated and will be removed in a future release. A cluster-id will be required   on cloud instances.\n","stream":"stderr","time":"2021-07-26T08:04:20.502900559Z"}
{"log":"I0726 08:04:20.506301      34 controllermanager.go:127] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:04:20.50637647Z"}
{"log":"W0726 08:04:20.506425      34 controllermanager.go:139] detected a cluster without a ClusterID.  A ClusterID will be required in the future.  Pl  ease tag your cluster to avoid any future issues\n","stream":"stderr","time":"2021-07-26T08:04:20.50645835Z"}
{"log":"I0726 08:04:20.506527      34 leaderelection.go:243] attempting to acquire leader lease  kube-system/cloud-controller-manager...\n","stream":"st  derr","time":"2021-07-26T08:04:20.50655759Z"}
{"log":"I0726 08:04:20.571515      34 cache.go:39] Caches are synced for APIServiceRegistrationController controller\n","stream":"stderr","time":"2021-0  7-26T08:04:20.571628953Z"}
{"log":"I0726 08:04:20.571621      34 cache.go:39] Caches are synced for autoregister controller\n","stream":"stderr","time":"2021-07-26T08:04:20.571657  923Z"}
{"log":"I0726 08:04:20.571628      34 shared_informer.go:247] Caches are synced for crd-autoregister \n","stream":"stderr","time":"2021-07-26T08:04:20.5  71663383Z"}
{"log":"I0726 08:04:20.572276      34 cache.go:39] Caches are synced for AvailableConditionController controller\n","stream":"stderr","time":"2021-07-26  T08:04:20.572321651Z"}
{"log":"I0726 08:04:20.572773      34 shared_informer.go:247] Caches are synced for cluster_authentication_trust_controller \n","stream":"stderr","time"  :"2021-07-26T08:04:20.572837419Z"}
{"log":"W0726 08:04:20.662201       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:20.662301499Z"}
{"log":"W0726 08:04:20.682408       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:20.682483477Z"}
{"log":"W0726 08:04:20.702070       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:20.702165886Z"}
{"log":"W0726 08:04:20.740076       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:20.740179379Z"}
{"log":"W0726 08:04:20.755082       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:20.75519634Z"}
{"log":"2021/07/26 08:04:20 [INFO] Starting API controllers\n","stream":"stdout","time":"2021-07-26T08:04:20.99247407Z"}
{"log":"2021/07/26 08:04:21 [INFO] Starting /v1, Kind=Secret controller\n","stream":"stdout","time":"2021-07-26T08:04:21.119211183Z"}
{"log":"2021/07/26 08:04:21 [INFO] Starting management.cattle.io/v3, Kind=User controller\n","stream":"stdout","time":"2021-07-26T08:04:21.119285853Z"}
{"log":"2021/07/26 08:04:21 [INFO] Starting management.cattle.io/v3, Kind=UserAttribute controller\n","stream":"stdout","time":"2021-07-26T08:04:21.1192  93293Z"}
{"log":"2021/07/26 08:04:21 [INFO] Starting management.cattle.io/v3, Kind=Token controller\n","stream":"stdout","time":"2021-07-26T08:04:21.119298853Z"}
{"log":"2021/07/26 08:04:21 [INFO] Starting management.cattle.io/v3, Kind=Group controller\n","stream":"stdout","time":"2021-07-26T08:04:21.119304143Z"}
{"log":"2021/07/26 08:04:21 [INFO] Starting management.cattle.io/v3, Kind=GroupMember controller\n","stream":"stdout","time":"2021-07-26T08:04:21.119310  873Z"}
{"log":"W0726 08:04:21.138973       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:21.139110732Z"}
{"log":"W0726 08:04:21.147204       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:21.147313071Z"}
{"log":"2021/07/26 08:04:21 [INFO] Starting rbac.authorization.k8s.io/v1, Kind=RoleBinding controller\n","stream":"stdout","time":"2021-07-26T08:04:21.2  37550798Z"}
{"log":"2021/07/26 08:04:21 [INFO] Starting apiregistration.k8s.io/v1, Kind=APIService controller\n","stream":"stdout","time":"2021-07-26T08:04:21.23758  3538Z"}
{"log":"2021/07/26 08:04:21 [INFO] Starting management.cattle.io/v3, Kind=Feature controller\n","stream":"stdout","time":"2021-07-26T08:04:21.237588118Z  "}
{"log":"2021/07/26 08:04:21 [INFO] Starting rbac.authorization.k8s.io/v1, Kind=ClusterRole controller\n","stream":"stdout","time":"2021-07-26T08:04:21.2  37809697Z"}
{"log":"2021/07/26 08:04:21 [INFO] Starting management.cattle.io/v3, Kind=Setting controller\n","stream":"stdout","time":"2021-07-26T08:04:21.237828307Z  "}
{"log":"2021/07/26 08:04:21 [INFO] Starting rbac.authorization.k8s.io/v1, Kind=Role controller\n","stream":"stdout","time":"2021-07-26T08:04:21.23791465  7Z"}
{"log":"2021/07/26 08:04:21 [INFO] Starting rbac.authorization.k8s.io/v1, Kind=ClusterRoleBinding controller\n","stream":"stdout","time":"2021-07-26T08:  04:21.238046528Z"}
{"log":"2021/07/26 08:04:21 [INFO] Starting catalog.cattle.io/v1, Kind=ClusterRepo controller\n","stream":"stdout","time":"2021-07-26T08:04:21.238144747  Z"}
{"log":"2021/07/26 08:04:21 [INFO] Starting management.cattle.io/v3, Kind=Cluster controller\n","stream":"stdout","time":"2021-07-26T08:04:21.238514906Z  "}
{"log":"2021/07/26 08:04:21 [INFO] Starting /v1, Kind=ConfigMap controller\n","stream":"stdout","time":"2021-07-26T08:04:21.239238764Z"}
{"log":"2021/07/26 08:04:21 [INFO] Starting apiextensions.k8s.io/v1beta1, Kind=CustomResourceDefinition controller\n","stream":"stdout","time":"2021-07-  26T08:04:21.239250484Z"}
{"log":"2021/07/26 08:04:21 [INFO] Starting /v1, Kind=Secret controller\n","stream":"stdout","time":"2021-07-26T08:04:21.239303464Z"}
{"log":"2021/07/26 08:04:21 [INFO] Starting management.cattle.io/v3, Kind=Preference controller\n","stream":"stdout","time":"2021-07-26T08:04:21.2395076  34Z"}
{"log":"I0726 08:04:21.240217       8 leaderelection.go:243] attempting to acquire leader lease kube-system/cattle-controllers...\n","stream":"stderr","  time":"2021-07-26T08:04:21.240289391Z"}
{"log":"I0726 08:04:21.245210       8 leaderelection.go:253] successfully acquired lease kube-system/cattle-controllers\n","stream":"stderr","time":"202  1-07-26T08:04:21.245329708Z"}
{"log":"time=\"2021-07-26T08:04:21.248070251Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:04:21 http: TLS handshake error from 127.0.0.1:57014:   EOF\"\n","stream":"stderr","time":"2021-07-26T08:04:21.248209461Z"}
{"log":"W0726 08:04:21.267750       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:21.26790366Z"}
{"log":"W0726 08:04:21.287754       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:21.287885279Z"}
{"log":"W0726 08:04:21.299765       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:21.299867498Z"}
{"log":"2021/07/26 08:04:21 [INFO] Running in single server mode, will not peer connections\n","stream":"stdout","time":"2021-07-26T08:04:21.303978278Z"  }
{"log":"W0726 08:04:21.315251       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:21.315374308Z"}
{"log":"W0726 08:04:21.315826       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:21.315885346Z"}
{"log":"2021/07/26 08:04:21 [INFO] Steve auth startup complete\n","stream":"stdout","time":"2021-07-26T08:04:21.321588341Z"}
{"log":"W0726 08:04:21.324888       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:21.324969913Z"}
{"log":"W0726 08:04:21.330052       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:21.33012071Z"}
{"log":"I0726 08:04:21.404519      34 node.go:136] Successfully retrieved node IP: 172.17.0.2\n","stream":"stderr","time":"2021-07-26T08:04:21.404637627  Z"}
{"log":"I0726 08:04:21.404550      34 server_others.go:143] kube-proxy node IP is an IPv4 address (172.17.0.2), assume IPv4 operation\n","stream":"stder  r","time":"2021-07-26T08:04:21.404679247Z"}
{"log":"I0726 08:04:21.405130      34 server_others.go:186] Using iptables Proxier.\n","stream":"stderr","time":"2021-07-26T08:04:21.405196896Z"}
{"log":"I0726 08:04:21.405311      34 server.go:650] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:04:21.405408236Z"}
{"log":"I0726 08:04:21.405656      34 conntrack.go:103] Set sysctl 'net/netfilter/nf_conntrack_max' to 196608\n","stream":"stderr","time":"2021-07-26T08  :04:21.405799775Z"}
{"log":"F0726 08:04:21.405731      34 server.go:495] open /proc/sys/net/netfilter/nf_conntrack_max: permission denied\n","stream":"stderr","time":"2021-  07-26T08:04:21.405836025Z"}
{"log":"W0726 08:04:21.418889       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.Role ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - watch   lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:21.418992041Z"}
{"log":"W0726 08:04:21.420419       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Preference ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close -   watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:21.420545007Z"}
{"log":"W0726 08:04:21.418917       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Cluster ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - wa  tch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:21.420650927Z"}
{"log":"W0726 08:04:21.419261       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.GroupMember ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close   - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:21.420659857Z"}
{"log":"W0726 08:04:21.420501       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.APIService ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close -   watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:21.420665307Z"}
{"log":"W0726 08:04:21.419512       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.User ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - watch   lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:21.420670567Z"}
{"log":"W0726 08:04:21.419529       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Token ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - watc  h lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:21.420688447Z"}
{"log":"W0726 08:04:21.419544       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Group ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - watc  h lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:21.420693907Z"}
{"log":"W0726 08:04:21.419558       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.UserAttribute ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch clos  e - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:21.420709917Z"}
{"log":"2021/07/26 08:04:21 [FATAL] k3s exited with: exit status 1\n","stream":"stdout","time":"2021-07-26T08:04:21.420772607Z"}
{"log":"2021/07/26 08:04:22 [INFO] Rancher version v2.5.9 (3c5418944) is starting\n","stream":"stdout","time":"2021-07-26T08:04:22.193876973Z"}
{"log":"2021/07/26 08:04:22 [INFO] Rancher arguments {ACMEDomains:[] AddLocal:true Embedded:false BindHost: HTTPListenPort:80 HTTPSListenPort:443 K8sMod  e:auto Debug:false Trace:false NoCACerts:false AuditLogPath:/var/log/auditlog/rancher-api-audit.log AuditLogMaxage:10 AuditLogMaxsize:100 AuditLogMaxbac  kup:10 AuditLevel:0 Agent:false Features: ClusterRegistry:}\n","stream":"stdout","time":"2021-07-26T08:04:22.193907332Z"}
{"log":"2021/07/26 08:04:22 [INFO] Listening on /tmp/log.sock\n","stream":"stdout","time":"2021-07-26T08:04:22.193916582Z"}
{"log":"2021/07/26 08:04:22 [INFO] Running etcd --data-dir=management-state/etcd --heartbeat-interval=500 --election-timeout=5000\n","stream":"stdout","  time":"2021-07-26T08:04:22.194287091Z"}
{"log":"2021-07-26 08:04:22.202991 W | pkg/flags: unrecognized environment variable ETCD_URL_arm64=https://github.com/etcd-io/etcd/releases/download/v3.  4.3/etcd-v3.4.3-linux-arm64.tar.gz\n","stream":"stderr","time":"2021-07-26T08:04:22.203082148Z"}
{"log":"2021-07-26 08:04:22.203023 W | pkg/flags: unrecognized environment variable ETCD_URL_amd64=https://github.com/etcd-io/etcd/releases/download/v3.  4.3/etcd-v3.4.3-linux-amd64.tar.gz\n","stream":"stderr","time":"2021-07-26T08:04:22.203091048Z"}
{"log":"2021-07-26 08:04:22.203028 W | pkg/flags: unrecognized environment variable ETCD_UNSUPPORTED_ARCH=amd64\n","stream":"stderr","time":"2021-07-26T  08:04:22.203093718Z"}
{"log":"2021-07-26 08:04:22.203033 W | pkg/flags: unrecognized environment variable ETCD_URL=ETCD_URL_amd64\n","stream":"stderr","time":"2021-07-26T08:0  4:22.203097718Z"}
{"log":"[WARNING] Deprecated '--logger=capnslog' flag is set; use '--logger=zap' flag instead\n","stream":"stderr","time":"2021-07-26T08:04:22.203100088  Z"}
{"log":"2021-07-26 08:04:22.203068 I | etcdmain: etcd Version: 3.4.3\n","stream":"stderr","time":"2021-07-26T08:04:22.203106638Z"}
{"log":"2021-07-26 08:04:22.203075 I | etcdmain: Git SHA: 3cf2f69b5\n","stream":"stderr","time":"2021-07-26T08:04:22.203109098Z"}
{"log":"2021-07-26 08:04:22.203080 I | etcdmain: Go Version: go1.12.12\n","stream":"stderr","time":"2021-07-26T08:04:22.203111298Z"}
{"log":"2021-07-26 08:04:22.203086 I | etcdmain: Go OS/Arch: linux/amd64\n","stream":"stderr","time":"2021-07-26T08:04:22.203121308Z"}
{"log":"2021-07-26 08:04:22.203092 I | etcdmain: setting maximum number of CPUs to 6, total number of available CPUs is 6\n","stream":"stderr","time":"2  021-07-26T08:04:22.203138768Z"}
{"log":"2021-07-26 08:04:22.203178 N | etcdmain: the server is already initialized as member before, starting as etcd member...\n","stream":"stderr","ti  me":"2021-07-26T08:04:22.203238548Z"}
{"log":"[WARNING] Deprecated '--logger=capnslog' flag is set; use '--logger=zap' flag instead\n","stream":"stderr","time":"2021-07-26T08:04:22.203251068  Z"}
{"log":"2021-07-26 08:04:22.203621 I | embed: name = default\n","stream":"stderr","time":"2021-07-26T08:04:22.203688228Z"}
{"log":"2021-07-26 08:04:22.203637 I | embed: data dir = management-state/etcd\n","stream":"stderr","time":"2021-07-26T08:04:22.203700928Z"}
{"log":"2021-07-26 08:04:22.203642 I | embed: member dir = management-state/etcd/member\n","stream":"stderr","time":"2021-07-26T08:04:22.203704608Z"}
{"log":"2021-07-26 08:04:22.203647 I | embed: heartbeat = 500ms\n","stream":"stderr","time":"2021-07-26T08:04:22.203707548Z"}
{"log":"2021-07-26 08:04:22.203652 I | embed: election = 5000ms\n","stream":"stderr","time":"2021-07-26T08:04:22.203710288Z"}
{"log":"2021-07-26 08:04:22.203659 I | embed: snapshot count = 100000\n","stream":"stderr","time":"2021-07-26T08:04:22.203712898Z"}
{"log":"2021-07-26 08:04:22.203671 I | embed: advertise client URLs = http://localhost:2379\n","stream":"stderr","time":"2021-07-26T08:04:22.203715778Z"  }
{"log":"2021-07-26 08:04:22.203678 I | embed: initial advertise peer URLs = http://localhost:2380\n","stream":"stderr","time":"2021-07-26T08:04:22.20371  8597Z"}
{"log":"2021-07-26 08:04:22.203685 I | embed: initial cluster = \n","stream":"stderr","time":"2021-07-26T08:04:22.203723257Z"}
{"log":"2021-07-26 08:04:22.209470 I | etcdserver: restarting member 8e9e05c52164694d in cluster cdf818194e3a8c32 at commit index 1305\n","stream":"stde  rr","time":"2021-07-26T08:04:22.209540202Z"}
{"log":"raft2021/07/26 08:04:22 INFO: 8e9e05c52164694d switched to configuration voters=()\n","stream":"stderr","time":"2021-07-26T08:04:22.209777731Z"}
{"log":"raft2021/07/26 08:04:22 INFO: 8e9e05c52164694d became follower at term 41\n","stream":"stderr","time":"2021-07-26T08:04:22.209787931Z"}
{"log":"raft2021/07/26 08:04:22 INFO: newRaft 8e9e05c52164694d [peers: [], term: 41, commit: 1305, applied: 0, lastindex: 1305, lastterm: 41]\n","stream  ":"stderr","time":"2021-07-26T08:04:22.209791491Z"}
{"log":"2021-07-26 08:04:22.213975 W | auth: simple token is not cryptographically signed\n","stream":"stderr","time":"2021-07-26T08:04:22.2140431Z"}
{"log":"2021-07-26 08:04:22.215713 I | etcdserver: starting server... [version: 3.4.3, cluster version: to_be_decided]\n","stream":"stderr","time":"2021  -07-26T08:04:22.215776456Z"}
{"log":"raft2021/07/26 08:04:22 INFO: 8e9e05c52164694d switched to configuration voters=(10276657743932975437)\n","stream":"stderr","time":"2021-07-26T0  8:04:22.216135545Z"}
{"log":"2021-07-26 08:04:22.216165 I | etcdserver/membership: added member 8e9e05c52164694d [http://localhost:2380] to cluster cdf818194e3a8c32\n","stre  am":"stderr","time":"2021-07-26T08:04:22.216235865Z"}
{"log":"2021-07-26 08:04:22.216274 N | etcdserver/membership: set the initial cluster version to 3.4\n","stream":"stderr","time":"2021-07-26T08:04:22.21  6328184Z"}
{"log":"2021-07-26 08:04:22.216310 I | etcdserver/api: enabled capabilities for version 3.4\n","stream":"stderr","time":"2021-07-26T08:04:22.216366094Z"  }
{"log":"2021-07-26 08:04:22.216957 I | embed: listening for peers on 127.0.0.1:2380\n","stream":"stderr","time":"2021-07-26T08:04:22.217058962Z"}
{"log":"raft2021/07/26 08:04:31 INFO: 8e9e05c52164694d is starting a new election at term 41\n","stream":"stderr","time":"2021-07-26T08:04:31.710341969Z  "}
{"log":"raft2021/07/26 08:04:31 INFO: 8e9e05c52164694d became candidate at term 42\n","stream":"stderr","time":"2021-07-26T08:04:31.710379239Z"}
{"log":"raft2021/07/26 08:04:31 INFO: 8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 42\n","stream":"stderr","time":"2021-07-26T08:  04:31.710384439Z"}
{"log":"raft2021/07/26 08:04:31 INFO: 8e9e05c52164694d became leader at term 42\n","stream":"stderr","time":"2021-07-26T08:04:31.710388359Z"}
{"log":"raft2021/07/26 08:04:31 INFO: raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 42\n","stream":"stderr","time":"2021-07-26T08:  04:31.710409959Z"}
{"log":"2021-07-26 08:04:31.711637 I | embed: ready to serve client requests\n","stream":"stderr","time":"2021-07-26T08:04:31.711730066Z"}
{"log":"2021-07-26 08:04:31.711662 I | etcdserver: published {Name:default ClientURLs:[http://localhost:2379]} to cluster cdf818194e3a8c32\n","stream":"  stderr","time":"2021-07-26T08:04:31.711759436Z"}
{"log":"2021-07-26 08:04:31.712060 N | embed: serving insecure client requests on 127.0.0.1:2379, this is strongly discouraged!\n","stream":"stderr","ti  me":"2021-07-26T08:04:31.712140615Z"}
{"log":"2021/07/26 08:04:31 [INFO] Waiting for server to become available: Get \"https://127.0.0.1:6443/version?timeout=15m0s\": dial tcp 127.0.0.1:6443  : connect: connection refused\n","stream":"stdout","time":"2021-07-26T08:04:31.725692159Z"}
{"log":"time=\"2021-07-26T08:04:31.915805460Z\" level=info msg=\"Starting k3s v1.19.8+k3s1 (95fc76b2)\"\n","stream":"stderr","time":"2021-07-26T08:04:31  .915955559Z"}
{"log":"time=\"2021-07-26T08:04:31.916015069Z\" level=info msg=\"Cluster bootstrap already complete\"\n","stream":"stderr","time":"2021-07-26T08:04:31.9  16098199Z"}
{"log":"time=\"2021-07-26T08:04:31.931111369Z\" level=info msg=\"Running kube-apiserver --advertise-port=6443 --allow-privileged=true --anonymous-auth=f  alse --api-audiences=unknown --authorization-mode=Node,RBAC --bind-address=127.0.0.1 --cert-dir=/var/lib/rancher/k3s/server/tls/temporary-certs --client  -ca-file=/var/lib/rancher/k3s/server/tls/client-ca.crt --enable-admission-plugins=NodeRestriction --etcd-servers=http://localhost:2379 --insecure-port=0   --kubelet-certificate-authority=/var/lib/rancher/k3s/server/tls/server-ca.crt --kubelet-client-certificate=/var/lib/rancher/k3s/server/tls/client-kube-  apiserver.crt --kubelet-client-key=/var/lib/rancher/k3s/server/tls/client-kube-apiserver.key --profiling=false --proxy-client-cert-file=/var/lib/rancher  /k3s/server/tls/client-auth-proxy.crt --proxy-client-key-file=/var/lib/rancher/k3s/server/tls/client-auth-proxy.key --requestheader-allowed-names=system  :auth-proxy --requestheader-client-ca-file=/var/lib/rancher/k3s/server/tls/request-header-ca.crt --requestheader-extra-headers-prefix=X-Remote-Extra- --  requestheader-group-headers=X-Remote-Group --requestheader-username-headers=X-Remote-User --secure-port=6444 --service-account-issuer=k3s --service-acco  unt-key-file=/var/lib/rancher/k3s/server/tls/service.key --service-account-signing-key-file=/var/lib/rancher/k3s/server/tls/service.key --service-cluste  r-ip-range=10.43.0.0/16 --storage-backend=etcd3 --tls-cert-file=/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.crt --tls-private-key-file=/var/l  ib/rancher/k3s/server/tls/serving-kube-apiserver.key\"\n","stream":"stderr","time":"2021-07-26T08:04:31.931284239Z"}
{"log":"I0726 08:04:31.932199      35 server.go:652] external host was not specified, using 172.17.0.2\n","stream":"stderr","time":"2021-07-26T08:04:31.  932287647Z"}
{"log":"I0726 08:04:31.932475      35 server.go:177] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:04:31.932547356Z"}
{"log":"I0726 08:04:31.937266      35 plugins.go:158] Loaded 12 mutating admission controller(s) successfully in the following order: NamespaceLifecycle  ,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,Run  timeClass,DefaultIngressClass,MutatingAdmissionWebhook.\n","stream":"stderr","time":"2021-07-26T08:04:31.937332353Z"}
{"log":"I0726 08:04:31.937285      35 plugins.go:161] Loaded 10 validating admission controller(s) successfully in the following order: LimitRanger,Serv  iceAccount,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,CertificateSubjectRestriction,ValidatingAdmissionWeb  hook,ResourceQuota.\n","stream":"stderr","time":"2021-07-26T08:04:31.937340253Z"}
{"log":"I0726 08:04:31.938337      35 plugins.go:158] Loaded 12 mutating admission controller(s) successfully in the following order: NamespaceLifecycle  ,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,Run  timeClass,DefaultIngressClass,MutatingAdmissionWebhook.\n","stream":"stderr","time":"2021-07-26T08:04:31.93840229Z"}
{"log":"I0726 08:04:31.938352      35 plugins.go:161] Loaded 10 validating admission controller(s) successfully in the following order: LimitRanger,Serv  iceAccount,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,CertificateSubjectRestriction,ValidatingAdmissionWeb  hook,ResourceQuota.\n","stream":"stderr","time":"2021-07-26T08:04:31.93843468Z"}
{"log":"I0726 08:04:31.965142      35 master.go:271] Using reconciler: lease\n","stream":"stderr","time":"2021-07-26T08:04:31.96523164Z"}
{"log":"W0726 08:04:32.231028      35 genericapiserver.go:412] Skipping API batch/v2alpha1 because it has no resources.\n","stream":"stderr","time":"202  1-07-26T08:04:32.231204539Z"}
{"log":"W0726 08:04:32.239840      35 genericapiserver.go:412] Skipping API discovery.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr",  "time":"2021-07-26T08:04:32.239949106Z"}
{"log":"W0726 08:04:32.261528      35 genericapiserver.go:412] Skipping API node.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr","time  ":"2021-07-26T08:04:32.261668339Z"}
{"log":"W0726 08:04:32.284653      35 genericapiserver.go:412] Skipping API rbac.authorization.k8s.io/v1alpha1 because it has no resources.\n","stream":  "stderr","time":"2021-07-26T08:04:32.284792798Z"}
{"log":"W0726 08:04:32.288661      35 genericapiserver.go:412] Skipping API scheduling.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr"  ,"time":"2021-07-26T08:04:32.288732237Z"}
{"log":"W0726 08:04:32.303950      35 genericapiserver.go:412] Skipping API storage.k8s.io/v1alpha1 because it has no resources.\n","stream":"stderr","t  ime":"2021-07-26T08:04:32.304021897Z"}
{"log":"W0726 08:04:32.319281      35 genericapiserver.go:412] Skipping API apps/v1beta2 because it has no resources.\n","stream":"stderr","time":"2021-  07-26T08:04:32.319350147Z"}
{"log":"W0726 08:04:32.319296      35 genericapiserver.go:412] Skipping API apps/v1beta1 because it has no resources.\n","stream":"stderr","time":"2021-  07-26T08:04:32.319355417Z"}
{"log":"I0726 08:04:32.325545      35 plugins.go:158] Loaded 12 mutating admission controller(s) successfully in the following order: NamespaceLifecycle  ,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,Run  timeClass,DefaultIngressClass,MutatingAdmissionWebhook.\n","stream":"stderr","time":"2021-07-26T08:04:32.32562436Z"}
{"log":"I0726 08:04:32.325561      35 plugins.go:161] Loaded 10 validating admission controller(s) successfully in the following order: LimitRanger,Serv  iceAccount,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,CertificateSubjectRestriction,ValidatingAdmissionWeb  hook,ResourceQuota.\n","stream":"stderr","time":"2021-07-26T08:04:32.32564355Z"}
{"log":"time=\"2021-07-26T08:04:32.334822597Z\" level=info msg=\"Running kube-scheduler --address=127.0.0.1 --bind-address=127.0.0.1 --kubeconfig=/var/l  ib/rancher/k3s/server/cred/scheduler.kubeconfig --port=10251 --profiling=false --secure-port=0\"\n","stream":"stderr","time":"2021-07-26T08:04:32.334928  386Z"}
{"log":"time=\"2021-07-26T08:04:32.334919506Z\" level=info msg=\"Waiting for API server to become available\"\n","stream":"stderr","time":"2021-07-26T08  :04:32.334984566Z"}
{"log":"I0726 08:04:32.335201      35 registry.go:173] Registering SelectorSpread plugin\n","stream":"stderr","time":"2021-07-26T08:04:32.335287745Z"}
{"log":"I0726 08:04:32.335221      35 registry.go:173] Registering SelectorSpread plugin\n","stream":"stderr","time":"2021-07-26T08:04:32.335306255Z"}
{"log":"time=\"2021-07-26T08:04:32.335583294Z\" level=info msg=\"Running kube-controller-manager --address=127.0.0.1 --allocate-node-cidrs=true --bind-a  ddress=127.0.0.1 --cluster-cidr=10.42.0.0/16 --cluster-signing-cert-file=/var/lib/rancher/k3s/server/tls/client-ca.crt --cluster-signing-key-file=/var/l  ib/rancher/k3s/server/tls/client-ca.key --kubeconfig=/var/lib/rancher/k3s/server/cred/controller.kubeconfig --port=10252 --profiling=false --root-ca-fil  e=/var/lib/rancher/k3s/server/tls/server-ca.crt --secure-port=0 --service-account-private-key-file=/var/lib/rancher/k3s/server/tls/service.key --use-ser  vice-account-credentials=true\"\n","stream":"stderr","time":"2021-07-26T08:04:32.335673364Z"}
{"log":"time=\"2021-07-26T08:04:32.337074930Z\" level=info msg=\"Node token is available at /var/lib/rancher/k3s/server/token\"\n","stream":"stderr","ti  me":"2021-07-26T08:04:32.33716291Z"}
{"log":"time=\"2021-07-26T08:04:32.337104660Z\" level=info msg=\"To join node to cluster: k3s agent -s https://172.17.0.2:6443 -t ${NODE_TOKEN}\"\n","st  ream":"stderr","time":"2021-07-26T08:04:32.33717691Z"}
{"log":"time=\"2021-07-26T08:04:32.338245797Z\" level=info msg=\"Wrote kubeconfig /etc/rancher/k3s/k3s.yaml\"\n","stream":"stderr","time":"2021-07-26T08  :04:32.338320237Z"}
{"log":"time=\"2021-07-26T08:04:32.338275647Z\" level=info msg=\"Run: k3s kubectl\"\n","stream":"stderr","time":"2021-07-26T08:04:32.338332828Z"}
{"log":"time=\"2021-07-26T08:04:32.338398887Z\" level=info msg=\"Module overlay was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:04:32.33  8482117Z"}
{"log":"time=\"2021-07-26T08:04:32.338426137Z\" level=info msg=\"Module nf_conntrack was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:04:  32.338492407Z"}
{"log":"time=\"2021-07-26T08:04:32.338442207Z\" level=info msg=\"Module br_netfilter was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:04:  32.338498187Z"}
{"log":"time=\"2021-07-26T08:04:32.338457947Z\" level=info msg=\"Module iptable_nat was already loaded\"\n","stream":"stderr","time":"2021-07-26T08:04:3  2.338518317Z"}
{"log":"time=\"2021-07-26T08:04:32.350037527Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:04:32 http: TLS handshake error from 127.0.0.1:57174:   remote error: tls: bad certificate\"\n","stream":"stderr","time":"2021-07-26T08:04:32.350158336Z"}
{"log":"time=\"2021-07-26T08:04:32.354637274Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:04:32 http: TLS handshake error from 127.0.0.1:57180:   remote error: tls: bad certificate\"\n","stream":"stderr","time":"2021-07-26T08:04:32.354749944Z"}
{"log":"time=\"2021-07-26T08:04:32.365563775Z\" level=info msg=\"certificate CN=local-node signed by CN=k3s-server-ca@1627286228: notBefore=2021-07-26 0  7:57:08 +0000 UTC notAfter=2022-07-26 08:04:32 +0000 UTC\"\n","stream":"stderr","time":"2021-07-26T08:04:32.365672975Z"}
{"log":"time=\"2021-07-26T08:04:32.368480507Z\" level=info msg=\"certificate CN=system:node:local-node,O=system:nodes signed by CN=k3s-client-ca@1627286  228: notBefore=2021-07-26 07:57:08 +0000 UTC notAfter=2022-07-26 08:04:32 +0000 UTC\"\n","stream":"stderr","time":"2021-07-26T08:04:32.368587017Z"}
{"log":"time=\"2021-07-26T08:04:32.374623451Z\" level=info msg=\"Logging containerd to /var/lib/rancher/k3s/agent/containerd/containerd.log\"\n","stream  ":"stderr","time":"2021-07-26T08:04:32.374689161Z"}
{"log":"time=\"2021-07-26T08:04:32.374739741Z\" level=info msg=\"Running containerd -c /var/lib/rancher/k3s/agent/etc/containerd/config.toml -a /run/k3s  /containerd/containerd.sock --state /run/k3s/containerd --root /var/lib/rancher/k3s/agent/containerd\"\n","stream":"stderr","time":"2021-07-26T08:04:32.  374789941Z"}
{"log":"time=\"2021-07-26T08:04:33.376359060Z\" level=info msg=\"Containerd is now running\"\n","stream":"stderr","time":"2021-07-26T08:04:33.37650459Z"  }
{"log":"time=\"2021-07-26T08:04:33.381637446Z\" level=info msg=\"Connecting to proxy\" url=\"wss://127.0.0.1:6443/v1-k3s/connect\"\n","stream":"stderr",  "time":"2021-07-26T08:04:33.381743345Z"}
{"log":"time=\"2021-07-26T08:04:33.383749871Z\" level=info msg=\"Handling backend connection request [local-node]\"\n","stream":"stderr","time":"2021-07  -26T08:04:33.38383235Z"}
{"log":"time=\"2021-07-26T08:04:33.384204289Z\" level=warning msg=\"Disabling CPU quotas due to missing cpu.cfs_period_us\"\n","stream":"stderr","time":  "2021-07-26T08:04:33.384280149Z"}
{"log":"time=\"2021-07-26T08:04:33.384256499Z\" level=info msg=\"Running kubelet --address=0.0.0.0 --anonymous-auth=false --authentication-token-webhook  =true --authorization-mode=Webhook --cgroup-driver=cgroupfs --client-ca-file=/var/lib/rancher/k3s/agent/client-ca.crt --cloud-provider=external --cluste  r-dns=10.43.0.10 --cluster-domain=cluster.local --cni-bin-dir=/var/lib/rancher/k3s/data/ab1b521f47b3cbc273e0c667521084cff1ea3540d55b425ac7ed30c27daf92f8  /bin --cni-conf-dir=/var/lib/rancher/k3s/agent/etc/cni/net.d --container-runtime-endpoint=/run/k3s/containerd/containerd.sock --container-runtime=remote   --containerd=/run/k3s/containerd/containerd.sock --cpu-cfs-quota=false --eviction-hard=imagefs.available\u003c5%,nodefs.available\u003c5% --eviction-mi  nimum-reclaim=imagefs.available=10%,nodefs.available=10% --fail-swap-on=false --healthz-bind-address=127.0.0.1 --hostname-override=local-node --kubeconf  ig=/var/lib/rancher/k3s/agent/kubelet.kubeconfig --kubelet-cgroups=/k3s --node-labels= --pod-manifest-path=/var/lib/rancher/k3s/agent/pod-manifests --re  ad-only-port=0 --resolv-conf=/etc/resolv.conf --runtime-cgroups=/k3s --serialize-image-pulls=false --tls-cert-file=/var/lib/rancher/k3s/agent/serving-ku  belet.crt --tls-private-key-file=/var/lib/rancher/k3s/agent/serving-kubelet.key\"\n","stream":"stderr","time":"2021-07-26T08:04:33.384372349Z"}
{"log":"time=\"2021-07-26T08:04:33.384891797Z\" level=info msg=\"Running kube-proxy --cluster-cidr=10.42.0.0/16 --healthz-bind-address=127.0.0.1 --hostn  ame-override=local-node --kubeconfig=/var/lib/rancher/k3s/agent/kubeproxy.kubeconfig --proxy-mode=iptables\"\n","stream":"stderr","time":"2021-07-26T08:  04:33.384969367Z"}
{"log":"Flag --cloud-provider has been deprecated, will be removed in 1.23, in favor of removing cloud provider code from Kubelet.\n","stream":"stderr",  "time":"2021-07-26T08:04:33.385069747Z"}
{"log":"Flag --containerd has been deprecated, This is a cadvisor flag that was mistakenly registered with the Kubelet. Due to legacy concerns, it will   follow the standard CLI deprecation timeline before being removed.\n","stream":"stderr","time":"2021-07-26T08:04:33.385076317Z"}
{"log":"W0726 08:04:33.385170      35 server.go:226] WARNING: all flags other than --config, --write-config-to, and --cleanup are deprecated. Please beg  in using a config file ASAP.\n","stream":"stderr","time":"2021-07-26T08:04:33.385244186Z"}
{"log":"I0726 08:04:33.385563      35 server.go:407] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:04:33.385635385Z"}
{"log":"W0726 08:04:33.385760      35 proxier.go:639] Failed to read file /lib/modules/4.15.0-151-generic/modules.builtin with error open /lib/modules/4  .15.0-151-generic/modules.builtin: no such file or directory. You can ignore this message when kube-proxy is running inside container without mounting /  lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:33.385811285Z"}
{"log":"W0726 08:04:33.386220      35 proxier.go:649] Failed to load kernel module ip_vs with modprobe. You can ignore this message when kube-proxy is r  unning inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:33.386272723Z"}
{"log":"W0726 08:04:33.386566      35 proxier.go:649] Failed to load kernel module ip_vs_rr with modprobe. You can ignore this message when kube-proxy i  s running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:33.386620352Z"}
{"log":"W0726 08:04:33.386850      35 proxier.go:649] Failed to load kernel module ip_vs_wrr with modprobe. You can ignore this message when kube-proxy   is running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:33.386882892Z"}
{"log":"W0726 08:04:33.387075      35 proxier.go:649] Failed to load kernel module ip_vs_sh with modprobe. You can ignore this message when kube-proxy i  s running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:33.387104371Z"}
{"log":"W0726 08:04:33.387374      35 proxier.go:649] Failed to load kernel module nf_conntrack_ipv4 with modprobe. You can ignore this message when kub  e-proxy is running inside container without mounting /lib/modules\n","stream":"stderr","time":"2021-07-26T08:04:33.387400511Z"}
{"log":"E0726 08:04:33.391718      35 node.go:125] Failed to retrieve node info: nodes \"local-node\" is forbidden: User \"system:kube-proxy\" cannot ge  t resource \"nodes\" in API group \"\" at the cluster scope\n","stream":"stderr","time":"2021-07-26T08:04:33.391765859Z"}
{"log":"time=\"2021-07-26T08:04:33.392978926Z\" level=info msg=\"Waiting for node local-node CIDR not assigned yet\"\n","stream":"stderr","time":"2021-0  7-26T08:04:33.393013996Z"}
{"log":"I0726 08:04:33.406223      35 dynamic_cafile_content.go:167] Starting client-ca-bundle::/var/lib/rancher/k3s/agent/client-ca.crt\n","stream":"st  derr","time":"2021-07-26T08:04:33.406270771Z"}
{"log":"I0726 08:04:33.545674      35 dynamic_cafile_content.go:167] Starting request-header::/var/lib/rancher/k3s/server/tls/request-header-ca.crt\n","  stream":"stderr","time":"2021-07-26T08:04:33.545746292Z"}
{"log":"I0726 08:04:33.545674      35 dynamic_cafile_content.go:167] Starting client-ca-bundle::/var/lib/rancher/k3s/server/tls/client-ca.crt\n","stream  ":"stderr","time":"2021-07-26T08:04:33.545761442Z"}
{"log":"I0726 08:04:33.545799      35 dynamic_serving_content.go:130] Starting serving-cert::/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.crt:  :/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.key\n","stream":"stderr","time":"2021-07-26T08:04:33.545887262Z"}
{"log":"I0726 08:04:33.546003      35 secure_serving.go:197] Serving securely on 127.0.0.1:6444\n","stream":"stderr","time":"2021-07-26T08:04:33.5460566  51Z"}
{"log":"I0726 08:04:33.546025      35 tlsconfig.go:240] Starting DynamicServingCertificateController\n","stream":"stderr","time":"2021-07-26T08:04:33.54  6102621Z"}
{"log":"I0726 08:04:33.546039      35 dynamic_serving_content.go:130] Starting aggregator-proxy-cert::/var/lib/rancher/k3s/server/tls/client-auth-proxy.  crt::/var/lib/rancher/k3s/server/tls/client-auth-proxy.key\n","stream":"stderr","time":"2021-07-26T08:04:33.546109141Z"}
{"log":"I0726 08:04:33.546061      35 controller.go:83] Starting OpenAPI AggregationController\n","stream":"stderr","time":"2021-07-26T08:04:33.54611325  1Z"}
{"log":"I0726 08:04:33.546091      35 customresource_discovery_controller.go:209] Starting DiscoveryController\n","stream":"stderr","time":"2021-07-26T0  8:04:33.546132071Z"}
{"log":"I0726 08:04:33.546405      35 apiservice_controller.go:97] Starting APIServiceRegistrationController\n","stream":"stderr","time":"2021-07-26T08:  04:33.5464457Z"}
{"log":"I0726 08:04:33.546423      35 cache.go:32] Waiting for caches to sync for APIServiceRegistrationController controller\n","stream":"stderr","time  ":"2021-07-26T08:04:33.54645272Z"}
{"log":"I0726 08:04:33.546443      35 available_controller.go:475] Starting AvailableConditionController\n","stream":"stderr","time":"2021-07-26T08:04:3  3.54648105Z"}
{"log":"I0726 08:04:33.546447      35 cache.go:32] Waiting for caches to sync for AvailableConditionController controller\n","stream":"stderr","time":"2  021-07-26T08:04:33.54648587Z"}
{"log":"I0726 08:04:33.547959      35 cluster_authentication_trust_controller.go:440] Starting cluster_authentication_trust_controller controller\n","st  ream":"stderr","time":"2021-07-26T08:04:33.547998166Z"}
{"log":"I0726 08:04:33.547975      35 shared_informer.go:240] Waiting for caches to sync for cluster_authentication_trust_controller\n","stream":"stderr  ","time":"2021-07-26T08:04:33.548006736Z"}
{"log":"I0726 08:04:33.547999      35 autoregister_controller.go:141] Starting autoregister controller\n","stream":"stderr","time":"2021-07-26T08:04:33.  548034306Z"}
{"log":"I0726 08:04:33.548002      35 cache.go:32] Waiting for caches to sync for autoregister controller\n","stream":"stderr","time":"2021-07-26T08:04:  33.548039216Z"}
{"log":"I0726 08:04:33.548040      35 controller.go:86] Starting OpenAPI controller\n","stream":"stderr","time":"2021-07-26T08:04:33.548056826Z"}
{"log":"I0726 08:04:33.548052      35 naming_controller.go:291] Starting NamingConditionController\n","stream":"stderr","time":"2021-07-26T08:04:33.5480  97736Z"}
{"log":"I0726 08:04:33.548063      35 establishing_controller.go:76] Starting EstablishingController\n","stream":"stderr","time":"2021-07-26T08:04:33.54  8102676Z"}
{"log":"I0726 08:04:33.548077      35 nonstructuralschema_controller.go:186] Starting NonStructuralSchemaConditionController\n","stream":"stderr","time"  :"2021-07-26T08:04:33.548106196Z"}
{"log":"I0726 08:04:33.548090      35 apiapproval_controller.go:186] Starting KubernetesAPIApprovalPolicyConformantConditionController\n","stream":"stde  rr","time":"2021-07-26T08:04:33.548131176Z"}
{"log":"I0726 08:04:33.548102      35 crd_finalizer.go:266] Starting CRDFinalizer\n","stream":"stderr","time":"2021-07-26T08:04:33.548135876Z"}
{"log":"I0726 08:04:33.548149      35 dynamic_cafile_content.go:167] Starting client-ca-bundle::/var/lib/rancher/k3s/server/tls/client-ca.crt\n","stream  ":"stderr","time":"2021-07-26T08:04:33.548176876Z"}
{"log":"I0726 08:04:33.548169      35 dynamic_cafile_content.go:167] Starting request-header::/var/lib/rancher/k3s/server/tls/request-header-ca.crt\n","  stream":"stderr","time":"2021-07-26T08:04:33.548214686Z"}
{"log":"I0726 08:04:33.548414      35 crdregistration_controller.go:111] Starting crd-autoregister controller\n","stream":"stderr","time":"2021-07-26T08  :04:33.548449705Z"}
{"log":"I0726 08:04:33.548427      35 shared_informer.go:240] Waiting for caches to sync for crd-autoregister\n","stream":"stderr","time":"2021-07-26T08  :04:33.548456235Z"}
{"log":"time=\"2021-07-26T08:04:33.551191368Z\" level=info msg=\"Running cloud-controller-manager --allocate-node-cidrs=true --allow-untagged-cloud=true   --bind-address=127.0.0.1 --cloud-provider=k3s --cluster-cidr=10.42.0.0/16 --kubeconfig=/var/lib/rancher/k3s/server/cred/cloud-controller.kubeconfig --n  ode-status-update-frequency=1m --profiling=false --secure-port=0\"\n","stream":"stderr","time":"2021-07-26T08:04:33.551291848Z"}
{"log":"Flag --allow-untagged-cloud has been deprecated, This flag is deprecated and will be removed in a future release. A cluster-id will be required   on cloud instances.\n","stream":"stderr","time":"2021-07-26T08:04:33.551309738Z"}
{"log":"I0726 08:04:33.553024      35 controllermanager.go:127] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:04:33.553087973Z"}
{"log":"W0726 08:04:33.553037      35 controllermanager.go:139] detected a cluster without a ClusterID.  A ClusterID will be required in the future.  Pl  ease tag your cluster to avoid any future issues\n","stream":"stderr","time":"2021-07-26T08:04:33.553100263Z"}
{"log":"I0726 08:04:33.553060      35 leaderelection.go:243] attempting to acquire leader lease  kube-system/cloud-controller-manager...\n","stream":"st  derr","time":"2021-07-26T08:04:33.553104623Z"}
{"log":"E0726 08:04:33.565666      35 controller.go:156] Unable to remove old endpoints from kubernetes service: no master IPs were listed in storage, r  efusing to erase all endpoints for the kubernetes service\n","stream":"stderr","time":"2021-07-26T08:04:33.565764029Z"}
{"log":"I0726 08:04:33.646560      35 cache.go:39] Caches are synced for AvailableConditionController controller\n","stream":"stderr","time":"2021-07-26  T08:04:33.646688716Z"}
{"log":"I0726 08:04:33.646578      35 cache.go:39] Caches are synced for APIServiceRegistrationController controller\n","stream":"stderr","time":"2021-0  7-26T08:04:33.646712986Z"}
{"log":"I0726 08:04:33.648123      35 cache.go:39] Caches are synced for autoregister controller\n","stream":"stderr","time":"2021-07-26T08:04:33.648207  332Z"}
{"log":"I0726 08:04:33.648152      35 shared_informer.go:247] Caches are synced for cluster_authentication_trust_controller \n","stream":"stderr","time"  :"2021-07-26T08:04:33.64894508Z"}
{"log":"I0726 08:04:33.650420      35 shared_informer.go:247] Caches are synced for crd-autoregister \n","stream":"stderr","time":"2021-07-26T08:04:33.6  50495816Z"}
{"log":"W0726 08:04:33.741181       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:33.741301226Z"}
{"log":"W0726 08:04:33.758915       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:33.759023759Z"}
{"log":"W0726 08:04:33.777072       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:33.777175651Z"}
{"log":"W0726 08:04:33.836101       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:33.836231356Z"}
{"log":"W0726 08:04:33.851429       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:33.851539795Z"}
{"log":"2021/07/26 08:04:34 [INFO] Starting API controllers\n","stream":"stdout","time":"2021-07-26T08:04:34.132460863Z"}
{"log":"time=\"2021-07-26T08:04:34.256828803Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:04:34 http: TLS handshake error from 127.0.0.1:57368:   EOF\"\n","stream":"stderr","time":"2021-07-26T08:04:34.256942623Z"}
{"log":"2021/07/26 08:04:34 [INFO] Starting management.cattle.io/v3, Kind=User controller\n","stream":"stdout","time":"2021-07-26T08:04:34.260898572Z"}
{"log":"2021/07/26 08:04:34 [INFO] Starting management.cattle.io/v3, Kind=Group controller\n","stream":"stdout","time":"2021-07-26T08:04:34.260908202Z"}
{"log":"2021/07/26 08:04:34 [INFO] Starting management.cattle.io/v3, Kind=UserAttribute controller\n","stream":"stdout","time":"2021-07-26T08:04:34.2609  10512Z"}
{"log":"2021/07/26 08:04:34 [INFO] Starting management.cattle.io/v3, Kind=Token controller\n","stream":"stdout","time":"2021-07-26T08:04:34.261040372Z"}
{"log":"2021/07/26 08:04:34 [INFO] Starting /v1, Kind=Secret controller\n","stream":"stdout","time":"2021-07-26T08:04:34.261209541Z"}
{"log":"2021/07/26 08:04:34 [INFO] Starting management.cattle.io/v3, Kind=GroupMember controller\n","stream":"stdout","time":"2021-07-26T08:04:34.261320  042Z"}
{"log":"W0726 08:04:34.281119       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:34.281228209Z"}
{"log":"W0726 08:04:34.286527       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:34.286624075Z"}
{"log":"2021/07/26 08:04:34 [INFO] Starting /v1, Kind=Secret controller\n","stream":"stdout","time":"2021-07-26T08:04:34.385828752Z"}
{"log":"I0726 08:04:34.385869       8 leaderelection.go:243] attempting to acquire leader lease kube-system/cattle-controllers...\n","stream":"stderr","  time":"2021-07-26T08:04:34.385938822Z"}
{"log":"2021/07/26 08:04:34 [INFO] Starting management.cattle.io/v3, Kind=Feature controller\n","stream":"stdout","time":"2021-07-26T08:04:34.385961972Z  "}
{"log":"2021/07/26 08:04:34 [INFO] Starting rbac.authorization.k8s.io/v1, Kind=Role controller\n","stream":"stdout","time":"2021-07-26T08:04:34.38609704  1Z"}
{"log":"2021/07/26 08:04:34 [INFO] Starting rbac.authorization.k8s.io/v1, Kind=ClusterRole controller\n","stream":"stdout","time":"2021-07-26T08:04:34.3  86277761Z"}
{"log":"2021/07/26 08:04:34 [INFO] Starting catalog.cattle.io/v1, Kind=ClusterRepo controller\n","stream":"stdout","time":"2021-07-26T08:04:34.3864859Z"  }
{"log":"2021/07/26 08:04:34 [INFO] Starting management.cattle.io/v3, Kind=Preference controller\n","stream":"stdout","time":"2021-07-26T08:04:34.3865849  1Z"}
{"log":"2021/07/26 08:04:34 [INFO] Starting rbac.authorization.k8s.io/v1, Kind=ClusterRoleBinding controller\n","stream":"stdout","time":"2021-07-26T08:  04:34.386685861Z"}
{"log":"2021/07/26 08:04:34 [INFO] Starting apiextensions.k8s.io/v1beta1, Kind=CustomResourceDefinition controller\n","stream":"stdout","time":"2021-07-  26T08:04:34.38685865Z"}
{"log":"2021/07/26 08:04:34 [INFO] Starting apiregistration.k8s.io/v1, Kind=APIService controller\n","stream":"stdout","time":"2021-07-26T08:04:34.39432  641Z"}
{"log":"2021/07/26 08:04:34 [INFO] Starting management.cattle.io/v3, Kind=Setting controller\n","stream":"stdout","time":"2021-07-26T08:04:34.39451673Z"  }
{"log":"2021/07/26 08:04:34 [INFO] Starting rbac.authorization.k8s.io/v1, Kind=RoleBinding controller\n","stream":"stdout","time":"2021-07-26T08:04:34.3  94715479Z"}
{"log":"2021/07/26 08:04:34 [INFO] Starting /v1, Kind=ConfigMap controller\n","stream":"stdout","time":"2021-07-26T08:04:34.394844509Z"}
{"log":"time=\"2021-07-26T08:04:34.395093178Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:04:34 http: TLS handshake error from 127.0.0.1:57432:   EOF\"\n","stream":"stderr","time":"2021-07-26T08:04:34.395203378Z"}
{"log":"time=\"2021-07-26T08:04:34.395445117Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:04:34 http: TLS handshake error from 127.0.0.1:57428:   EOF\"\n","stream":"stderr","time":"2021-07-26T08:04:34.395540727Z"}
{"log":"time=\"2021-07-26T08:04:34.395899536Z\" level=info msg=\"Cluster-Http-Server 2021/07/26 08:04:34 http: TLS handshake error from 127.0.0.1:57430:   EOF\"\n","stream":"stderr","time":"2021-07-26T08:04:34.395983576Z"}
{"log":"2021/07/26 08:04:34 [INFO] Starting management.cattle.io/v3, Kind=Cluster controller\n","stream":"stdout","time":"2021-07-26T08:04:34.401340231Z  "}
{"log":"W0726 08:04:34.412609       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:34.412717781Z"}
{"log":"I0726 08:04:34.417677       8 leaderelection.go:253] successfully acquired lease kube-system/cattle-controllers\n","stream":"stderr","time":"202  1-07-26T08:04:34.417775748Z"}
{"log":"W0726 08:04:34.428918       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:34.429016288Z"}
{"log":"W0726 08:04:34.439903       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:34.439981169Z"}
{"log":"2021/07/26 08:04:34 [INFO] Running in single server mode, will not peer connections\n","stream":"stdout","time":"2021-07-26T08:04:34.442493422Z"  }
{"log":"W0726 08:04:34.452809       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:34.452892325Z"}
{"log":"W0726 08:04:34.453145       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:34.453238154Z"}
{"log":"W0726 08:04:34.465417       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:34.465508441Z"}
{"log":"W0726 08:04:34.465604       8 warnings.go:80] apiextensions.k8s.io/v1beta1 CustomResourceDefinition is deprecated in v1.16+, unavailable in v1.2  2+; use apiextensions.k8s.io/v1 CustomResourceDefinition\n","stream":"stderr","time":"2021-07-26T08:04:34.465683921Z"}
{"log":"2021/07/26 08:04:34 [INFO] Steve auth startup complete\n","stream":"stdout","time":"2021-07-26T08:04:34.475702365Z"}
{"log":"I0726 08:04:34.534234      35 node.go:136] Successfully retrieved node IP: 172.17.0.2\n","stream":"stderr","time":"2021-07-26T08:04:34.534318169  Z"}
{"log":"I0726 08:04:34.534253      35 server_others.go:143] kube-proxy node IP is an IPv4 address (172.17.0.2), assume IPv4 operation\n","stream":"stder  r","time":"2021-07-26T08:04:34.534344329Z"}
{"log":"I0726 08:04:34.534730      35 server_others.go:186] Using iptables Proxier.\n","stream":"stderr","time":"2021-07-26T08:04:34.534786558Z"}
{"log":"I0726 08:04:34.534837      35 server.go:650] Version: v1.19.8+k3s1\n","stream":"stderr","time":"2021-07-26T08:04:34.534909738Z"}
{"log":"I0726 08:04:34.535014      35 conntrack.go:103] Set sysctl 'net/netfilter/nf_conntrack_max' to 196608\n","stream":"stderr","time":"2021-07-26T08  :04:34.535052008Z"}
{"log":"F0726 08:04:34.535045      35 server.go:495] open /proc/sys/net/netfilter/nf_conntrack_max: permission denied\n","stream":"stderr","time":"2021-  07-26T08:04:34.535088598Z"}
{"log":"W0726 08:04:34.543870       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.ClusterRepo ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close   - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.543944274Z"}
{"log":"W0726 08:04:34.543902       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1beta1.CustomResourceDefinition ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexp  ected watch close - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.543961354Z"}
{"log":"W0726 08:04:34.543921       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Setting ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - wa  tch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.544004494Z"}
{"log":"W0726 08:04:34.543949       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.APIService ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close -   watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.544010994Z"}
{"log":"W0726 08:04:34.544048       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.ClusterRoleBinding ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch   close - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.544083154Z"}
{"log":"W0726 08:04:34.544053       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.ClusterRole ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close   - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.544090094Z"}
{"log":"W0726 08:04:34.544126       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.Role ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - watch   lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.544205203Z"}
{"log":"W0726 08:04:34.544144       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Preference ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close -   watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.544225703Z"}
{"log":"W0726 08:04:34.544194       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Cluster ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - wa  tch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.544268923Z"}
{"log":"W0726 08:04:34.544196       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Feature ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - wa  tch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.544275383Z"}
{"log":"W0726 08:04:34.544281       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.RoleBinding ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close   - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.544339263Z"}
{"log":"W0726 08:04:34.544323       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.GroupMember ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close   - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.544353143Z"}
{"log":"W0726 08:04:34.544397       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.UserAttribute ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch clos  e - watch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.544444073Z"}
{"log":"W0726 08:04:34.544417       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Token ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - watc  h lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.544451953Z"}
{"log":"W0726 08:04:34.544434       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.Secret ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - wat  ch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.544490403Z"}
{"log":"W0726 08:04:34.544478       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.User ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - watch   lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.544548232Z"}
{"log":"W0726 08:04:34.544495       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  1.Secret ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - wat  ch lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.544570812Z"}
{"log":"W0726 08:04:34.544578       8 reflector.go:437] pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: watch of *v  3.Group ended with: very short watch: pkg/mod/github.com/rancher/client-go@v1.20.0-rancher.1/tools/cache/reflector.go:168: Unexpected watch close - watc  h lasted less than a second and no items received\n","stream":"stderr","time":"2021-07-26T08:04:34.544636932Z"}
{"log":"2021/07/26 08:04:34 [FATAL] k3s exited with: exit status 1\n","stream":"stdout","time":"2021-07-26T08:04:34.544962311Z"}
{"log":"2021/07/26 08:04:35 [INFO] Rancher version v2.5.9 (3c5418944) is starting\n","stream":"stdout","time":"2021-07-26T08:04:35.356175004Z"}
{"log":"2021/07/26 08:04:35 [INFO] Listening on /tmp/log.sock\n","stream":"stdout","time":"2021-07-26T08:04:35.356204744Z"}
{"log":"2021/07/26 08:04:35 [INFO] Rancher arguments {ACMEDomains:[] AddLocal:true Embedded:false BindHost: HTTPListenPort:80 HTTPSListenPort:443 K8sMod  e:auto Debug:false Trace:false NoCACerts:false AuditLogPath:/var/log/auditlog/rancher-api-audit.log AuditLogMaxage:10 AuditLogMaxsize:100 AuditLogMaxbac  kup:10 AuditLevel:0 Agent:false Features: ClusterRegistry:}\n","stream":"stdout","time":"2021-07-26T08:04:35.356238574Z"}
{"log":"2021/07/26 08:04:35 [INFO] Running etcd --data-dir=management-state/etcd --heartbeat-interval=500 --election-timeout=5000\n","stream":"stdout","  time":"2021-07-26T08:04:35.356604453Z"}
{"log":"2021-07-26 08:04:35.365051 W | pkg/flags: unrecognized environment variable ETCD_URL_arm64=https://github.com/etcd-io/etcd/releases/download/v3.  4.3/etcd-v3.4.3-linux-arm64.tar.gz\n","stream":"stderr","time":"2021-07-26T08:04:35.3651924Z"}
{"log":"2021-07-26 08:04:35.365079 W | pkg/flags: unrecognized environment variable ETCD_URL_amd64=https://github.com/etcd-io/etcd/releases/download/v3.  4.3/etcd-v3.4.3-linux-amd64.tar.gz\n","stream":"stderr","time":"2021-07-26T08:04:35.365223099Z"}
{"log":"2021-07-26 08:04:35.365084 W | pkg/flags: unrecognized environment variable ETCD_UNSUPPORTED_ARCH=amd64\n","stream":"stderr","time":"2021-07-26T  08:04:35.365229059Z"}
{"log":"2021-07-26 08:04:35.365090 W | pkg/flags: unrecognized environment variable ETCD_URL=ETCD_URL_amd64\n","stream":"stderr","time":"2021-07-26T08:0  4:35.365233539Z"}
{"log":"[WARNING] Deprecated '--logger=capnslog' flag is set; use '--logger=zap' flag instead\n","stream":"stderr","time":"2021-07-26T08:04:35.365237909  Z"}
{"log":"2021-07-26 08:04:35.365124 I | etcdmain: etcd Version: 3.4.3\n","stream":"stderr","time":"2021-07-26T08:04:35.365242269Z"}
{"log":"2021-07-26 08:04:35.365132 I | etcdmain: Git SHA: 3cf2f69b5\n","stream":"stderr","time":"2021-07-26T08:04:35.365246799Z"}
{"log":"2021-07-26 08:04:35.365136 I | etcdmain: Go Version: go1.12.12\n","stream":"stderr","time":"2021-07-26T08:04:35.365251089Z"}
{"log":"2021-07-26 08:04:35.365139 I | etcdmain: Go OS/Arch: linux/amd64\n","stream":"stderr","time":"2021-07-26T08:04:35.365255309Z"}
{"log":"2021-07-26 08:04:35.365145 I | etcdmain: setting maximum number of CPUs to 6, total number of available CPUs is 6\n","stream":"stderr","time":"2  021-07-26T08:04:35.365259589Z"}
{"log":"2021-07-26 08:04:35.365195 N | etcdmain: the server is already initialized as member before, starting as etcd member...\n","stream":"stderr","ti  me":"2021-07-26T08:04:35.365268859Z"}
{"log":"[WARNING] Deprecated '--logger=capnslog' flag is set; use '--logger=zap' flag instead\n","stream":"stderr","time":"2021-07-26T08:04:35.365273859  Z"}
{"log":"2021-07-26 08:04:35.365604 I | embed: name = default\n","stream":"stderr","time":"2021-07-26T08:04:35.365682008Z"}
{"log":"2021-07-26 08:04:35.365630 I | embed: data dir = management-state/etcd\n","stream":"stderr","time":"2021-07-26T08:04:35.365705788Z"}
{"log":"2021-07-26 08:04:35.365635 I | embed: member dir = management-state/etcd/member\n","stream":"stderr","time":"2021-07-26T08:04:35.365711448Z"}
{"log":"2021-07-26 08:04:35.365638 I | embed: heartbeat = 500ms\n","stream":"stderr","time":"2021-07-26T08:04:35.365726468Z"}
{"log":"2021-07-26 08:04:35.365641 I | embed: election = 5000ms\n","stream":"stderr","time":"2021-07-26T08:04:35.365730548Z"}
{"log":"2021-07-26 08:04:35.365645 I | embed: snapshot count = 100000\n","stream":"stderr","time":"2021-07-26T08:04:35.365734338Z"}
{"log":"2021-07-26 08:04:35.365652 I | embed: advertise client URLs = http://localhost:2379\n","stream":"stderr","time":"2021-07-26T08:04:35.365738098Z"  }
{"log":"2021-07-26 08:04:35.365656 I | embed: initial advertise peer URLs = http://localhost:2380\n","stream":"stderr","time":"2021-07-26T08:04:35.36574  1868Z"}
{"log":"2021-07-26 08:04:35.365661 I | embed: initial cluster = \n","stream":"stderr","time":"2021-07-26T08:04:35.365745478Z"}
{"log":"2021-07-26 08:04:35.373740 I | etcdserver: restarting member 8e9e05c52164694d in cluster cdf818194e3a8c32 at commit index 1334\n","stream":"stde  rr","time":"2021-07-26T08:04:35.373875176Z"}
{"log":"raft2021/07/26 08:04:35 INFO: 8e9e05c52164694d switched to configuration voters=()\n","stream":"stderr","time":"2021-07-26T08:04:35.373984166Z"}
{"log":"raft2021/07/26 08:04:35 INFO: 8e9e05c52164694d became follower at term 42\n","stream":"stderr","time":"2021-07-26T08:04:35.373988186Z"}
{"log":"raft2021/07/26 08:04:35 INFO: newRaft 8e9e05c52164694d [peers: [], term: 42, commit: 1334, applied: 0, lastindex: 1334, lastterm: 42]\n","stream  ":"stderr","time":"2021-07-26T08:04:35.373990536Z"}
{"log":"2021-07-26 08:04:35.379247 W | auth: simple token is not cryptographically signed\n","stream":"stderr","time":"2021-07-26T08:04:35.379328352Z"}
{"log":"2021-07-26 08:04:35.380993 I | etcdserver: starting server... [version: 3.4.3, cluster version: to_be_decided]\n","stream":"stderr","time":"2021  -07-26T08:04:35.381066447Z"}
{"log":"raft2021/07/26 08:04:35 INFO: 8e9e05c52164694d switched to configuration voters=(10276657743932975437)\n","stream":"stderr","time":"2021-07-26T0  8:04:35.381474536Z"}
{"log":"2021-07-26 08:04:35.381456 I | etcdserver/membership: added member 8e9e05c52164694d [http://localhost:2380] to cluster cdf818194e3a8c32\n","stre  am":"stderr","time":"2021-07-26T08:04:35.381498526Z"}
{"log":"2021-07-26 08:04:35.381557 N | etcdserver/membership: set the initial cluster version to 3.4\n","stream":"stderr","time":"2021-07-26T08:04:35.38  1633226Z"}
{"log":"2021-07-26 08:04:35.381622 I | etcdserver/api: enabled capabilities for version 3.4\n","stream":"stderr","time":"2021-07-26T08:04:35.381674635Z"  }
{"log":"2021-07-26 08:04:35.384705 I | embed: listening for peers on 127.0.0.1:2380\n","stream":"stderr","time":"2021-07-26T08:04:35.384804497Z"}

Can confirm. Same issue here. Fresh install of Ubuntu 20.04.2 with docker.io from apt. Running: sudo docker run --privileged -d --restart=unless-stopped -p 80:80 -p 443:443 rancher/rancher = BUSTED. Tested rancher/server, same issue with -p 80:80 -p 443:443 but with -p 8080:8080 I can get the UI to come up just fine. Curl on localhost to localhost will show some error results, but anything to Domain Name or IP = Connection Refused.

:~$ curl -k -v https://localhost

  • Trying 127.0.0.1:443…
  • TCP_NODELAY set
  • Connected to localhost (127.0.0.1) port 443 (#0)
  • ALPN, offering h2
  • ALPN, offering http/1.1
  • successfully set certificate verify locations:
  • CAfile: /etc/ssl/certs/ca-certificates.crt
    CApath: /etc/ssl/certs
  • TLSv1.3 (OUT), TLS handshake, Client hello (1):
  • OpenSSL SSL_connect: SSL_ERROR_SYSCALL in connection to localhost:443
  • Closing connection 0
    curl: (35) OpenSSL SSL_connect: SSL_ERROR_SYSCALL in connection to localhost:443

Apparently new users like myself are limited to 2 links in a post. Here is the other output. Yes, this is a fresh install with no ufw installed or running.

:~$ curl -k -v https://198.18.0.50

  • Trying 198.18.0.50:443…
  • TCP_NODELAY set
  • connect to 198.18.0.50 port 443 failed: Connection refused
  • Failed to connect to 198.18.0.50 port 443: Connection refused
  • Closing connection 0
    curl: (7) Failed to connect to 198.18.0.50 port 443: Connection refused

This is an issue with newer kernels (containing this commit, which was backported into Ubuntu 5.4.0-80) and older k3s versions. Rancher only runs “in” a k8s cluster, so the docker container version runs a copy of k3s inside it to provide that cluster. That version needs to be updated in an upcoming release, or you need a kernel that doesn’t have that change, or install following the helm/HA installation instructions instead of docker run ....

I use this command line :

curl https://releases.rancher.com/install-docker/20.10.sh | sh

on : Rancher Docs: Installing Docker
Do you think I use a wrong version on docker?

It sure would be nice if the quick start guide reflected this or had a warning. The below statements make it appear super easy.

To install and run Rancher, execute the following Docker command on your host:

$ sudo docker run --privileged -d --restart=unless-stopped -p 80:80 -p 443:443 rancher/rancher

I also could not find anything noting these caveats in the support Matrix. The distro I’m using is listed as officially supported.

This literally started happening a few days ago when an external event happened (the kernel was updated) and was isolated yesterday from info in this (among other) threads. It’s not something we intend to be unsupported; stuff happens and it will be fixed.

As far as the support matrix goes, support (for people that are customers) is only for installing Rancher on to a cluster with Helm… The docker container and its embedded cluster is basically for development and demos/kicking the tires. Customers aren’t running on one container with ephemeral storage.

1 Like

That version has to be updated in a future release, or you’ll require a kernel without that change, or you’ll need to instal using the helm/HA instructions instead of docker run.