Rancher UI can't be reached

I installed rancher and portainer on docker on ubuntu server on proxmox. I have acces to the portainer UI but still can’t get the Rancher UI : ERR_CONNECTION_REFUSED

I have set a static ip 192.168.1.240 then I got to https://192.168.1.240 and https://192.168.1.240:443. Please help me.

I wonder if the issue is related with entrypoint.sh as command?

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.