I0512 01:41:24.470694 1 serving.go:348] Generated self-signed cert in-memory I0512 01:41:24.884503 1 controllermanager.go:189] "Starting" version="v1.28.13" I0512 01:41:24.884531 1 controllermanager.go:191] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" I0512 01:41:24.889593 1 dynamic_cafile_content.go:157] "Starting controller" name="request-header::/etc/kubernetes/pki/front-proxy-ca.crt" I0512 01:41:24.889611 1 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" I0512 01:41:24.890791 1 secure_serving.go:213] Serving securely on [::]:10257 I0512 01:41:24.891245 1 leaderelection.go:250] attempting to acquire leader lease kube-system/kube-controller-manager... I0512 01:41:24.890880 1 tlsconfig.go:240] "Starting DynamicServingCertificateController" E0512 01:41:26.413887 1 leaderelection.go:332] error retrieving resource lock kube-system/kube-controller-manager: leases.coordination.k8s.io "kube-controller-manager" is forbidden: User "system:kube-controller-manager" cannot get resource "leases" in API group "coordination.k8s.io" in the namespace "kube-system" I0512 01:41:29.173856 1 leaderelection.go:260] successfully acquired lease kube-system/kube-controller-manager I0512 01:41:29.174149 1 event.go:307] "Event occurred" object="kube-system/kube-controller-manager" fieldPath="" kind="Lease" apiVersion="coordination.k8s.io/v1" type="Normal" reason="LeaderElection" message="instance_25ad56aa-778b-40f7-9780-91eec16e651e became leader" I0512 01:41:29.184676 1 shared_informer.go:311] Waiting for caches to sync for tokens I0512 01:41:29.217923 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="jobs.batch" I0512 01:41:29.217982 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="rolebindings.rbac.authorization.k8s.io" I0512 01:41:29.218004 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="podtemplates" I0512 01:41:29.218044 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="daemonsets.apps" I0512 01:41:29.218110 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="cronjobs.batch" I0512 01:41:29.218142 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="networkpolicies.networking.k8s.io" I0512 01:41:29.218265 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="endpointslices.discovery.k8s.io" I0512 01:41:29.218305 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="statefulsets.apps" I0512 01:41:29.218332 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="horizontalpodautoscalers.autoscaling" I0512 01:41:29.218354 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="poddisruptionbudgets.policy" W0512 01:41:29.218377 1 shared_informer.go:593] resyncPeriod 16h20m40.90752744s is smaller than resyncCheckPeriod 19h23m27.726873293s and the informer has already started. Changing it to 19h23m27.726873293s I0512 01:41:29.218412 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="serviceaccounts" I0512 01:41:29.218437 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="replicasets.apps" I0512 01:41:29.218461 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="ingresses.networking.k8s.io" I0512 01:41:29.218513 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="leases.coordination.k8s.io" I0512 01:41:29.218649 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="endpoints" I0512 01:41:29.218730 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="controllerrevisions.apps" W0512 01:41:29.218748 1 shared_informer.go:593] resyncPeriod 15h13m20.777005866s is smaller than resyncCheckPeriod 19h23m27.726873293s and the informer has already started. Changing it to 19h23m27.726873293s I0512 01:41:29.218875 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="roles.rbac.authorization.k8s.io" I0512 01:41:29.218904 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="csistoragecapacities.storage.k8s.io" I0512 01:41:29.218921 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="limitranges" I0512 01:41:29.218983 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="deployments.apps" I0512 01:41:29.219077 1 controllermanager.go:642] "Started controller" controller="resourcequota-controller" I0512 01:41:29.219185 1 resource_quota_controller.go:294] "Starting resource quota controller" I0512 01:41:29.219223 1 shared_informer.go:311] Waiting for caches to sync for resource quota I0512 01:41:29.219327 1 resource_quota_monitor.go:305] "QuotaMonitor running" I0512 01:41:29.229470 1 controllermanager.go:642] "Started controller" controller="daemonset-controller" I0512 01:41:29.229608 1 daemon_controller.go:291] "Starting daemon sets controller" I0512 01:41:29.229628 1 shared_informer.go:311] Waiting for caches to sync for daemon sets I0512 01:41:29.243887 1 controllermanager.go:642] "Started controller" controller="horizontal-pod-autoscaler-controller" I0512 01:41:29.244001 1 horizontal.go:200] "Starting HPA controller" I0512 01:41:29.244029 1 shared_informer.go:311] Waiting for caches to sync for HPA I0512 01:41:29.251993 1 controllermanager.go:642] "Started controller" controller="disruption-controller" I0512 01:41:29.252191 1 disruption.go:433] "Sending events to api server." I0512 01:41:29.252250 1 disruption.go:444] "Starting disruption controller" I0512 01:41:29.252268 1 shared_informer.go:311] Waiting for caches to sync for disruption I0512 01:41:29.259695 1 controllermanager.go:642] "Started controller" controller="certificatesigningrequest-cleaner-controller" I0512 01:41:29.259735 1 cleaner.go:83] "Starting CSR cleaner controller" I0512 01:41:29.268858 1 controllermanager.go:642] "Started controller" controller="statefulset-controller" I0512 01:41:29.268951 1 stateful_set.go:163] "Starting stateful set controller" I0512 01:41:29.268972 1 shared_informer.go:311] Waiting for caches to sync for stateful set I0512 01:41:29.278334 1 controllermanager.go:642] "Started controller" controller="ttl-after-finished-controller" I0512 01:41:29.278696 1 ttlafterfinished_controller.go:109] "Starting TTL after finished controller" I0512 01:41:29.278742 1 shared_informer.go:311] Waiting for caches to sync for TTL after finished I0512 01:41:29.285327 1 shared_informer.go:318] Caches are synced for tokens I0512 01:41:29.291947 1 node_lifecycle_controller.go:431] "Controller will reconcile labels" I0512 01:41:29.292059 1 controllermanager.go:642] "Started controller" controller="node-lifecycle-controller" I0512 01:41:29.292338 1 node_lifecycle_controller.go:465] "Sending events to api server" I0512 01:41:29.294582 1 node_lifecycle_controller.go:476] "Starting node controller" I0512 01:41:29.294634 1 shared_informer.go:311] Waiting for caches to sync for taint I0512 01:41:29.453434 1 controllermanager.go:642] "Started controller" controller="persistentvolume-attach-detach-controller" I0512 01:41:29.453532 1 attach_detach_controller.go:337] "Starting attach detach controller" I0512 01:41:29.453555 1 shared_informer.go:311] Waiting for caches to sync for attach detach I0512 01:41:29.585747 1 controllermanager.go:642] "Started controller" controller="clusterrole-aggregation-controller" I0512 01:41:29.585810 1 clusterroleaggregation_controller.go:189] "Starting ClusterRoleAggregator controller" I0512 01:41:29.585822 1 shared_informer.go:311] Waiting for caches to sync for ClusterRoleAggregator I0512 01:41:29.754789 1 controllermanager.go:642] "Started controller" controller="persistentvolume-protection-controller" I0512 01:41:29.754965 1 pv_protection_controller.go:78] "Starting PV protection controller" I0512 01:41:29.754985 1 shared_informer.go:311] Waiting for caches to sync for PV protection I0512 01:41:29.938175 1 controllermanager.go:642] "Started controller" controller="root-ca-certificate-publisher-controller" I0512 01:41:29.938357 1 publisher.go:102] "Starting root CA cert publisher controller" I0512 01:41:29.938366 1 shared_informer.go:311] Waiting for caches to sync for crt configmap I0512 01:41:30.030397 1 controllermanager.go:642] "Started controller" controller="endpoints-controller" I0512 01:41:30.030491 1 endpoints_controller.go:177] "Starting endpoint controller" I0512 01:41:30.030500 1 shared_informer.go:311] Waiting for caches to sync for endpoint I0512 01:41:30.195324 1 controllermanager.go:642] "Started controller" controller="replicationcontroller-controller" I0512 01:41:30.195612 1 replica_set.go:214] "Starting controller" name="replicationcontroller" I0512 01:41:30.195702 1 shared_informer.go:311] Waiting for caches to sync for ReplicationController I0512 01:41:30.398384 1 controllermanager.go:642] "Started controller" controller="job-controller" I0512 01:41:30.398553 1 job_controller.go:226] "Starting job controller" I0512 01:41:30.398588 1 shared_informer.go:311] Waiting for caches to sync for job I0512 01:41:30.556392 1 controllermanager.go:642] "Started controller" controller="deployment-controller" I0512 01:41:30.556598 1 deployment_controller.go:168] "Starting controller" controller="deployment" I0512 01:41:30.556639 1 shared_informer.go:311] Waiting for caches to sync for deployment I0512 01:41:30.736824 1 controllermanager.go:642] "Started controller" controller="persistentvolume-binder-controller" I0512 01:41:30.737052 1 pv_controller_base.go:319] "Starting persistent volume controller" I0512 01:41:30.737103 1 shared_informer.go:311] Waiting for caches to sync for persistent volume I0512 01:41:30.883395 1 controllermanager.go:642] "Started controller" controller="persistentvolume-expander-controller" I0512 01:41:30.883665 1 expand_controller.go:328] "Starting expand controller" I0512 01:41:30.883701 1 shared_informer.go:311] Waiting for caches to sync for expand I0512 01:41:30.963665 1 controllermanager.go:642] "Started controller" controller="endpointslice-mirroring-controller" I0512 01:41:30.963855 1 endpointslicemirroring_controller.go:223] "Starting EndpointSliceMirroring controller" I0512 01:41:30.963882 1 shared_informer.go:311] Waiting for caches to sync for endpoint_slice_mirroring I0512 01:41:31.234063 1 garbagecollector.go:155] "Starting controller" controller="garbagecollector" I0512 01:41:31.234098 1 shared_informer.go:311] Waiting for caches to sync for garbage collector I0512 01:41:31.234131 1 controllermanager.go:642] "Started controller" controller="garbage-collector-controller" I0512 01:41:31.234182 1 graph_builder.go:294] "Running" component="GraphBuilder" I0512 01:41:31.434352 1 controllermanager.go:642] "Started controller" controller="ephemeral-volume-controller" I0512 01:41:31.434464 1 controller.go:169] "Starting ephemeral volume controller" I0512 01:41:31.434476 1 shared_informer.go:311] Waiting for caches to sync for ephemeral I0512 01:41:31.582362 1 controllermanager.go:642] "Started controller" controller="serviceaccount-controller" I0512 01:41:31.582442 1 serviceaccounts_controller.go:111] "Starting service account controller" I0512 01:41:31.582451 1 shared_informer.go:311] Waiting for caches to sync for service account I0512 01:41:31.729680 1 controllermanager.go:642] "Started controller" controller="replicaset-controller" I0512 01:41:31.729766 1 replica_set.go:214] "Starting controller" name="replicaset" I0512 01:41:31.729774 1 shared_informer.go:311] Waiting for caches to sync for ReplicaSet I0512 01:41:32.033998 1 controllermanager.go:642] "Started controller" controller="cronjob-controller" I0512 01:41:32.034160 1 cronjob_controllerv2.go:139] "Starting cronjob controller v2" I0512 01:41:32.034185 1 shared_informer.go:311] Waiting for caches to sync for cronjob I0512 01:41:32.047080 1 controllermanager.go:642] "Started controller" controller="ttl-controller" I0512 01:41:32.047107 1 controllermanager.go:620] "Warning: skipping controller" controller="node-ipam-controller" I0512 01:41:32.047127 1 ttl_controller.go:124] "Starting TTL controller" I0512 01:41:32.047160 1 shared_informer.go:311] Waiting for caches to sync for TTL E0512 01:41:32.092115 1 core.go:213] "Failed to start cloud node lifecycle controller" err="no cloud provider provided" I0512 01:41:32.092141 1 controllermanager.go:620] "Warning: skipping controller" controller="cloud-node-lifecycle-controller" I0512 01:41:32.233359 1 controllermanager.go:642] "Started controller" controller="pod-garbage-collector-controller" I0512 01:41:32.233412 1 gc_controller.go:101] "Starting GC controller" I0512 01:41:32.233420 1 shared_informer.go:311] Waiting for caches to sync for GC I0512 01:41:32.482926 1 controllermanager.go:642] "Started controller" controller="namespace-controller" I0512 01:41:32.482963 1 namespace_controller.go:197] "Starting namespace controller" I0512 01:41:32.482978 1 shared_informer.go:311] Waiting for caches to sync for namespace I0512 01:41:33.135603 1 controllermanager.go:642] "Started controller" controller="certificatesigningrequest-approving-controller" I0512 01:41:33.135879 1 certificate_controller.go:115] "Starting certificate controller" name="csrapproving" I0512 01:41:33.135917 1 shared_informer.go:311] Waiting for caches to sync for certificate-csrapproving I0512 01:41:34.147741 1 controllermanager.go:642] "Started controller" controller="bootstrap-signer-controller" I0512 01:41:34.147914 1 shared_informer.go:311] Waiting for caches to sync for bootstrap_signer I0512 01:41:34.161233 1 controllermanager.go:642] "Started controller" controller="token-cleaner-controller" I0512 01:41:34.161574 1 tokencleaner.go:112] "Starting token cleaner controller" I0512 01:41:34.161629 1 shared_informer.go:311] Waiting for caches to sync for token_cleaner I0512 01:41:34.161649 1 shared_informer.go:318] Caches are synced for token_cleaner I0512 01:41:34.170061 1 controllermanager.go:642] "Started controller" controller="persistentvolumeclaim-protection-controller" I0512 01:41:34.170219 1 pvc_protection_controller.go:102] "Starting PVC protection controller" I0512 01:41:34.170248 1 shared_informer.go:311] Waiting for caches to sync for PVC protection I0512 01:41:34.180459 1 controllermanager.go:642] "Started controller" controller="endpointslice-controller" I0512 01:41:34.180596 1 endpointslice_controller.go:264] "Starting endpoint slice controller" I0512 01:41:34.180629 1 shared_informer.go:311] Waiting for caches to sync for endpoint_slice I0512 01:41:34.183670 1 certificate_controller.go:115] "Starting certificate controller" name="csrsigning-kubelet-serving" I0512 01:41:34.183748 1 shared_informer.go:311] Waiting for caches to sync for certificate-csrsigning-kubelet-serving I0512 01:41:34.183701 1 dynamic_serving_content.go:132] "Starting controller" name="csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key" I0512 01:41:34.184228 1 certificate_controller.go:115] "Starting certificate controller" name="csrsigning-kubelet-client" I0512 01:41:34.184273 1 shared_informer.go:311] Waiting for caches to sync for certificate-csrsigning-kubelet-client I0512 01:41:34.184364 1 dynamic_serving_content.go:132] "Starting controller" name="csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key" I0512 01:41:34.185042 1 certificate_controller.go:115] "Starting certificate controller" name="csrsigning-kube-apiserver-client" I0512 01:41:34.185067 1 shared_informer.go:311] Waiting for caches to sync for certificate-csrsigning-kube-apiserver-client I0512 01:41:34.185097 1 dynamic_serving_content.go:132] "Starting controller" name="csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key" I0512 01:41:34.185493 1 controllermanager.go:642] "Started controller" controller="certificatesigningrequest-signing-controller" I0512 01:41:34.185620 1 certificate_controller.go:115] "Starting certificate controller" name="csrsigning-legacy-unknown" I0512 01:41:34.185641 1 shared_informer.go:311] Waiting for caches to sync for certificate-csrsigning-legacy-unknown I0512 01:41:34.185708 1 dynamic_serving_content.go:132] "Starting controller" name="csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key" E0512 01:41:34.200281 1 core.go:92] "Failed to start service controller" err="WARNING: no cloud provider provided, services of type LoadBalancer will fail" I0512 01:41:34.200380 1 controllermanager.go:620] "Warning: skipping controller" controller="service-lb-controller" I0512 01:41:34.200455 1 core.go:224] "Will not configure cloud provider routes for allocate-node-cidrs" CIDRs=false routes=true I0512 01:41:34.200488 1 controllermanager.go:620] "Warning: skipping controller" controller="node-route-controller" I0512 01:41:34.202812 1 shared_informer.go:311] Waiting for caches to sync for resource quota I0512 01:41:34.216246 1 actual_state_of_world.go:547] "Failed to update statusUpdateNeeded field in actual state of world" err="Failed to set statusUpdateNeeded to needed true, because nodeName=\"instance\" does not exist" I0512 01:41:34.228649 1 shared_informer.go:311] Waiting for caches to sync for garbage collector I0512 01:41:34.230810 1 shared_informer.go:318] Caches are synced for daemon sets I0512 01:41:34.230816 1 shared_informer.go:318] Caches are synced for ReplicaSet I0512 01:41:34.234680 1 shared_informer.go:318] Caches are synced for GC I0512 01:41:34.234700 1 shared_informer.go:318] Caches are synced for cronjob I0512 01:41:34.234784 1 shared_informer.go:318] Caches are synced for ephemeral I0512 01:41:34.236693 1 shared_informer.go:318] Caches are synced for certificate-csrapproving I0512 01:41:34.237237 1 shared_informer.go:318] Caches are synced for persistent volume I0512 01:41:34.238956 1 shared_informer.go:318] Caches are synced for crt configmap I0512 01:41:34.244771 1 shared_informer.go:318] Caches are synced for HPA I0512 01:41:34.248056 1 shared_informer.go:318] Caches are synced for bootstrap_signer I0512 01:41:34.248132 1 shared_informer.go:318] Caches are synced for TTL I0512 01:41:34.252845 1 shared_informer.go:318] Caches are synced for disruption I0512 01:41:34.254017 1 shared_informer.go:318] Caches are synced for attach detach I0512 01:41:34.255189 1 shared_informer.go:318] Caches are synced for PV protection I0512 01:41:34.257111 1 shared_informer.go:318] Caches are synced for deployment I0512 01:41:34.259388 1 event.go:307] "Event occurred" object="kube-system/kube-proxy" fieldPath="" kind="DaemonSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: kube-proxy-mcz9d" I0512 01:41:34.271662 1 shared_informer.go:318] Caches are synced for PVC protection I0512 01:41:34.271727 1 shared_informer.go:318] Caches are synced for stateful set I0512 01:41:34.278946 1 shared_informer.go:318] Caches are synced for TTL after finished I0512 01:41:34.282254 1 event.go:307] "Event occurred" object="kube-system/coredns" fieldPath="" kind="Deployment" apiVersion="apps/v1" type="Normal" reason="ScalingReplicaSet" message="Scaled up replica set coredns-7c96b6546b to 2" I0512 01:41:34.283748 1 shared_informer.go:318] Caches are synced for expand I0512 01:41:34.283847 1 shared_informer.go:318] Caches are synced for certificate-csrsigning-kubelet-serving I0512 01:41:34.285114 1 shared_informer.go:318] Caches are synced for certificate-csrsigning-kube-apiserver-client I0512 01:41:34.285419 1 shared_informer.go:318] Caches are synced for certificate-csrsigning-kubelet-client I0512 01:41:34.285712 1 shared_informer.go:318] Caches are synced for certificate-csrsigning-legacy-unknown I0512 01:41:34.286036 1 shared_informer.go:318] Caches are synced for ClusterRoleAggregator I0512 01:41:34.295612 1 shared_informer.go:318] Caches are synced for taint I0512 01:41:34.295742 1 node_lifecycle_controller.go:1225] "Initializing eviction metric for zone" zone="" I0512 01:41:34.295844 1 node_lifecycle_controller.go:877] "Missing timestamp for Node. Assuming now as a timestamp" node="instance" I0512 01:41:34.295862 1 shared_informer.go:318] Caches are synced for ReplicationController I0512 01:41:34.295926 1 node_lifecycle_controller.go:1029] "Controller detected that all Nodes are not-Ready. Entering master disruption mode" I0512 01:41:34.296122 1 event.go:307] "Event occurred" object="instance" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="RegisteredNode" message="Node instance event: Registered Node instance in Controller" I0512 01:41:34.296306 1 taint_manager.go:205] "Starting NoExecuteTaintManager" I0512 01:41:34.296399 1 taint_manager.go:210] "Sending events to api server" I0512 01:41:34.298976 1 shared_informer.go:318] Caches are synced for job I0512 01:41:34.301265 1 event.go:307] "Event occurred" object="kube-system/coredns-7c96b6546b" fieldPath="" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: coredns-7c96b6546b-hsgqw" I0512 01:41:34.312804 1 event.go:307] "Event occurred" object="kube-system/coredns-7c96b6546b" fieldPath="" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: coredns-7c96b6546b-kxzlf" I0512 01:41:34.326817 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="45.45265ms" I0512 01:41:34.327452 1 event.go:307] "Event occurred" object="kube-system/kube-vip-instance" fieldPath="" kind="Pod" apiVersion="v1" type="Warning" reason="NodeNotReady" message="Node is not ready" I0512 01:41:34.336208 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="9.335941ms" I0512 01:41:34.336507 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="236.917µs" I0512 01:41:34.339723 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="135.764µs" I0512 01:41:34.382800 1 shared_informer.go:318] Caches are synced for service account I0512 01:41:34.383346 1 shared_informer.go:318] Caches are synced for namespace I0512 01:41:34.420093 1 shared_informer.go:318] Caches are synced for resource quota I0512 01:41:34.431627 1 shared_informer.go:318] Caches are synced for endpoint I0512 01:41:34.464779 1 shared_informer.go:318] Caches are synced for endpoint_slice_mirroring I0512 01:41:34.481571 1 shared_informer.go:318] Caches are synced for endpoint_slice I0512 01:41:34.503720 1 shared_informer.go:318] Caches are synced for resource quota I0512 01:41:34.829180 1 shared_informer.go:318] Caches are synced for garbage collector I0512 01:41:34.835313 1 shared_informer.go:318] Caches are synced for garbage collector I0512 01:41:34.835353 1 garbagecollector.go:166] "All resource monitors have synced. Proceeding to collect garbage" I0512 01:41:41.864565 1 event.go:307] "Event occurred" object="kube-system/coredns" fieldPath="" kind="Deployment" apiVersion="apps/v1" type="Normal" reason="ScalingReplicaSet" message="Scaled up replica set coredns-67659f764b to 1" I0512 01:41:41.879157 1 event.go:307] "Event occurred" object="kube-system/coredns-67659f764b" fieldPath="" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: coredns-67659f764b-99qh2" I0512 01:41:41.889263 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-67659f764b" duration="26.877138ms" I0512 01:41:41.890174 1 event.go:307] "Event occurred" object="kube-system/coredns" fieldPath="" kind="Deployment" apiVersion="apps/v1" type="Normal" reason="ScalingReplicaSet" message="Scaled down replica set coredns-7c96b6546b to 1 from 2" I0512 01:41:41.911266 1 event.go:307] "Event occurred" object="kube-system/coredns-7c96b6546b" fieldPath="" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulDelete" message="Deleted pod: coredns-7c96b6546b-kxzlf" I0512 01:41:41.925274 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="35.708318ms" I0512 01:41:41.926058 1 event.go:307] "Event occurred" object="kube-system/coredns" fieldPath="" kind="Deployment" apiVersion="apps/v1" type="Normal" reason="ScalingReplicaSet" message="Scaled up replica set coredns-67659f764b to 2 from 1" I0512 01:41:41.929882 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-67659f764b" duration="40.489716ms" E0512 01:41:41.929971 1 replica_set.go:557] sync "kube-system/coredns-67659f764b" failed with Operation cannot be fulfilled on replicasets.apps "coredns-67659f764b": the object has been modified; please apply your changes to the latest version and try again I0512 01:41:41.948536 1 event.go:307] "Event occurred" object="kube-system/coredns-67659f764b" fieldPath="" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: coredns-67659f764b-245zs" I0512 01:41:41.963336 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-67659f764b" duration="33.295422ms" I0512 01:41:41.969179 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="43.778485ms" I0512 01:41:41.974063 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-67659f764b" duration="10.666229ms" I0512 01:41:41.974272 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-67659f764b" duration="159.704µs" I0512 01:41:41.982353 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="13.087384ms" I0512 01:41:41.982448 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="62.161µs" I0512 01:41:54.177560 1 event.go:307] "Event occurred" object="kube-system/cilium" fieldPath="" kind="DaemonSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: cilium-hh2mm" I0512 01:41:54.184828 1 event.go:307] "Event occurred" object="kube-system/cilium-operator" fieldPath="" kind="Deployment" apiVersion="apps/v1" type="Normal" reason="ScalingReplicaSet" message="Scaled up replica set cilium-operator-869df985b8 to 1" I0512 01:41:54.210127 1 event.go:307] "Event occurred" object="kube-system/cilium-operator-869df985b8" fieldPath="" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: cilium-operator-869df985b8-zl8jn" I0512 01:41:54.276210 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/cilium-operator-869df985b8" duration="92.432147ms" I0512 01:41:54.467706 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/cilium-operator-869df985b8" duration="191.431862ms" I0512 01:41:54.468091 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/cilium-operator-869df985b8" duration="334.748µs" I0512 01:41:54.919080 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/cilium-operator-869df985b8" duration="97.942µs" I0512 01:42:04.510034 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="tcproutes.gateway.networking.k8s.io" I0512 01:42:04.510078 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="xbackendtrafficpolicies.gateway.networking.x-k8s.io" I0512 01:42:04.510124 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="envoyextensionpolicies.gateway.envoyproxy.io" I0512 01:42:04.510154 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="backendtlspolicies.gateway.networking.k8s.io" I0512 01:42:04.510174 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="gateways.gateway.networking.k8s.io" I0512 01:42:04.510201 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="backendtrafficpolicies.gateway.envoyproxy.io" I0512 01:42:04.510222 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="xlistenersets.gateway.networking.x-k8s.io" I0512 01:42:04.510251 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="referencegrants.gateway.networking.k8s.io" I0512 01:42:04.510273 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="udproutes.gateway.networking.k8s.io" I0512 01:42:04.510294 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="envoyproxies.gateway.envoyproxy.io" I0512 01:42:04.510315 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="backends.gateway.envoyproxy.io" I0512 01:42:04.510339 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="httproutes.gateway.networking.k8s.io" I0512 01:42:04.510360 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="grpcroutes.gateway.networking.k8s.io" I0512 01:42:04.510396 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="envoypatchpolicies.gateway.envoyproxy.io" I0512 01:42:04.510424 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="httproutefilters.gateway.envoyproxy.io" I0512 01:42:04.510446 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="tlsroutes.gateway.networking.k8s.io" I0512 01:42:04.510469 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="securitypolicies.gateway.envoyproxy.io" I0512 01:42:04.510502 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="clienttrafficpolicies.gateway.envoyproxy.io" I0512 01:42:04.510658 1 shared_informer.go:311] Waiting for caches to sync for resource quota I0512 01:42:04.843517 1 shared_informer.go:311] Waiting for caches to sync for garbage collector I0512 01:42:06.611216 1 shared_informer.go:318] Caches are synced for resource quota I0512 01:42:06.643901 1 shared_informer.go:318] Caches are synced for garbage collector I0512 01:42:06.734549 1 job_controller.go:562] "enqueueing job" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" I0512 01:42:06.756317 1 job_controller.go:562] "enqueueing job" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" I0512 01:42:06.756799 1 event.go:307] "Event occurred" object="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" fieldPath="" kind="Job" apiVersion="batch/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: envoy-gateway-gateway-helm-certgen-hzwq9" I0512 01:42:06.764734 1 job_controller.go:562] "enqueueing job" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" I0512 01:42:06.770852 1 job_controller.go:562] "enqueueing job" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" I0512 01:42:09.201062 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/cilium-operator-869df985b8" duration="20.922231ms" I0512 01:42:09.202210 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/cilium-operator-869df985b8" duration="113.812µs" I0512 01:42:14.302921 1 node_lifecycle_controller.go:1048] "Controller detected that some Nodes are Ready. Exiting master disruption mode" I0512 01:42:17.202412 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="122.004µs" I0512 01:42:17.205712 1 job_controller.go:562] "enqueueing job" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" I0512 01:42:17.223541 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="73.602µs" I0512 01:42:17.232641 1 job_controller.go:562] "enqueueing job" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" I0512 01:42:18.971612 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="143.733µs" I0512 01:42:18.995407 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="9.989928ms" I0512 01:42:18.995694 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="146.513µs" I0512 01:42:21.974146 1 job_controller.go:562] "enqueueing job" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" I0512 01:42:23.463580 1 job_controller.go:562] "enqueueing job" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" I0512 01:42:23.983235 1 job_controller.go:562] "enqueueing job" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" I0512 01:42:24.473180 1 job_controller.go:562] "enqueueing job" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" I0512 01:42:25.991374 1 job_controller.go:562] "enqueueing job" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" I0512 01:42:27.000782 1 job_controller.go:562] "enqueueing job" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" I0512 01:42:27.016565 1 job_controller.go:562] "enqueueing job" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" I0512 01:42:27.030325 1 job_controller.go:562] "enqueueing job" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" I0512 01:42:27.031675 1 event.go:307] "Event occurred" object="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" fieldPath="" kind="Job" apiVersion="batch/v1" type="Normal" reason="Completed" message="Job completed" I0512 01:42:27.121080 1 event.go:307] "Event occurred" object="envoy-gateway-system/envoy-gateway" fieldPath="" kind="Deployment" apiVersion="apps/v1" type="Normal" reason="ScalingReplicaSet" message="Scaled up replica set envoy-gateway-78446f96c9 to 1" I0512 01:42:27.131936 1 event.go:307] "Event occurred" object="envoy-gateway-system/envoy-gateway-78446f96c9" fieldPath="" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: envoy-gateway-78446f96c9-ghdng" I0512 01:42:27.142571 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-78446f96c9" duration="22.345732ms" I0512 01:42:27.164163 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-78446f96c9" duration="21.548028ms" I0512 01:42:27.164258 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-78446f96c9" duration="59.831µs" I0512 01:42:27.164327 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-78446f96c9" duration="48.351µs" I0512 01:42:28.003811 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-78446f96c9" duration="140.613µs" I0512 01:42:30.607738 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-67659f764b" duration="109.562µs" I0512 01:42:30.611836 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-67659f764b" duration="112.982µs" I0512 01:42:30.628480 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-67659f764b" duration="94.631µs" I0512 01:42:30.643842 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-67659f764b" duration="97.912µs" I0512 01:42:32.025158 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-67659f764b" duration="106.932µs" I0512 01:42:32.040041 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-67659f764b" duration="64.201µs" I0512 01:42:32.061324 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-67659f764b" duration="10.071471ms" I0512 01:42:32.061573 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-67659f764b" duration="71.121µs" I0512 01:42:32.072506 1 event.go:307] "Event occurred" object="kube-system/coredns" fieldPath="" kind="Deployment" apiVersion="apps/v1" type="Normal" reason="ScalingReplicaSet" message="Scaled down replica set coredns-7c96b6546b to 0 from 1" I0512 01:42:32.080643 1 event.go:307] "Event occurred" object="kube-system/coredns-7c96b6546b" fieldPath="" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulDelete" message="Deleted pod: coredns-7c96b6546b-hsgqw" I0512 01:42:32.094633 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="22.330109ms" I0512 01:42:32.104750 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="10.05486ms" I0512 01:42:32.105050 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="161.783µs" I0512 01:42:35.731114 1 event.go:307] "Event occurred" object="local-path-storage/local-path-provisioner" fieldPath="" kind="Deployment" apiVersion="apps/v1" type="Normal" reason="ScalingReplicaSet" message="Scaled up replica set local-path-provisioner-679c578f5 to 1" I0512 01:42:35.741097 1 event.go:307] "Event occurred" object="local-path-storage/local-path-provisioner-679c578f5" fieldPath="" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: local-path-provisioner-679c578f5-qxfp5" I0512 01:42:35.753376 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="local-path-storage/local-path-provisioner-679c578f5" duration="22.528805ms" I0512 01:42:35.770733 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="local-path-storage/local-path-provisioner-679c578f5" duration="17.311684ms" I0512 01:42:35.770862 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="local-path-storage/local-path-provisioner-679c578f5" duration="97.242µs" I0512 01:42:35.770972 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="local-path-storage/local-path-provisioner-679c578f5" duration="83.761µs" I0512 01:42:36.618319 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="ciliumendpoints.cilium.io" I0512 01:42:36.618386 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="ciliumnetworkpolicies.cilium.io" I0512 01:42:36.618438 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="ciliumnodeconfigs.cilium.io" I0512 01:42:36.618535 1 shared_informer.go:311] Waiting for caches to sync for resource quota I0512 01:42:36.657850 1 shared_informer.go:311] Waiting for caches to sync for garbage collector I0512 01:42:36.719335 1 shared_informer.go:318] Caches are synced for resource quota I0512 01:42:36.758922 1 shared_informer.go:318] Caches are synced for garbage collector I0512 01:42:37.382403 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="80.952µs" I0512 01:42:37.482199 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-78446f96c9" duration="13.689806ms" I0512 01:42:37.482413 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-78446f96c9" duration="91.022µs" I0512 01:42:38.042304 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="119.102µs" I0512 01:42:38.046517 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="85.291µs" I0512 01:42:39.080451 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="local-path-storage/local-path-provisioner-679c578f5" duration="9.279018ms" I0512 01:42:39.081161 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="local-path-storage/local-path-provisioner-679c578f5" duration="180.794µs" I0512 01:42:40.944909 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-67659f764b" duration="9.513642ms" I0512 01:42:40.945362 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-67659f764b" duration="105.912µs" I0512 01:42:57.128914 1 job_controller.go:562] "enqueueing job" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" I0512 01:42:57.283993 1 job_controller.go:562] "enqueueing job" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" I0512 01:44:03.184650 1 event.go:307] "Event occurred" object="default/test-pvc" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="WaitForFirstConsumer" message="waiting for first consumer to be created before binding" I0512 01:44:03.915230 1 event.go:307] "Event occurred" object="default/test-pvc" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="ExternalProvisioning" message="Waiting for a volume to be created either by the external provisioner 'cluster.local/local-path-provisioner' or manually by the system administrator. If volume creation is delayed, please verify that the provisioner is running and correctly registered." I0512 01:44:04.243655 1 event.go:307] "Event occurred" object="default/test-pvc" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="ExternalProvisioning" message="Waiting for a volume to be created either by the external provisioner 'cluster.local/local-path-provisioner' or manually by the system administrator. If volume creation is delayed, please verify that the provisioner is running and correctly registered."