I0425 00:47:34.693721 1 serving.go:348] Generated self-signed cert in-memory I0425 00:47:35.303295 1 controllermanager.go:189] "Starting" version="v1.28.13" I0425 00:47:35.303323 1 controllermanager.go:191] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" I0425 00:47:35.304834 1 dynamic_cafile_content.go:157] "Starting controller" name="request-header::/etc/kubernetes/pki/front-proxy-ca.crt" I0425 00:47:35.304950 1 tlsconfig.go:240] "Starting DynamicServingCertificateController" I0425 00:47:35.304856 1 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" I0425 00:47:35.304928 1 secure_serving.go:213] Serving securely on [::]:10257 I0425 00:47:35.305256 1 leaderelection.go:250] attempting to acquire leader lease kube-system/kube-controller-manager... I0425 00:47:52.411459 1 leaderelection.go:260] successfully acquired lease kube-system/kube-controller-manager I0425 00:47:52.411894 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_1f7d116f-f1ae-4d93-b590-fc949d767a89 became leader" I0425 00:47:52.422527 1 shared_informer.go:311] Waiting for caches to sync for tokens I0425 00:47:52.435951 1 controllermanager.go:642] "Started controller" controller="cronjob-controller" I0425 00:47:52.436086 1 cronjob_controllerv2.go:139] "Starting cronjob controller v2" I0425 00:47:52.436121 1 shared_informer.go:311] Waiting for caches to sync for cronjob I0425 00:47:52.439064 1 certificate_controller.go:115] "Starting certificate controller" name="csrsigning-kubelet-serving" I0425 00:47:52.439088 1 shared_informer.go:311] Waiting for caches to sync for certificate-csrsigning-kubelet-serving I0425 00:47:52.439145 1 dynamic_serving_content.go:132] "Starting controller" name="csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key" I0425 00:47:52.439503 1 dynamic_serving_content.go:132] "Starting controller" name="csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key" I0425 00:47:52.439638 1 certificate_controller.go:115] "Starting certificate controller" name="csrsigning-kubelet-client" I0425 00:47:52.439653 1 shared_informer.go:311] Waiting for caches to sync for certificate-csrsigning-kubelet-client I0425 00:47:52.441191 1 certificate_controller.go:115] "Starting certificate controller" name="csrsigning-kube-apiserver-client" I0425 00:47:52.441208 1 shared_informer.go:311] Waiting for caches to sync for certificate-csrsigning-kube-apiserver-client I0425 00:47:52.441331 1 dynamic_serving_content.go:132] "Starting controller" name="csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key" I0425 00:47:52.441606 1 controllermanager.go:642] "Started controller" controller="certificatesigningrequest-signing-controller" I0425 00:47:52.441676 1 core.go:224] "Will not configure cloud provider routes for allocate-node-cidrs" CIDRs=false routes=true I0425 00:47:52.441696 1 controllermanager.go:620] "Warning: skipping controller" controller="node-route-controller" I0425 00:47:52.441943 1 certificate_controller.go:115] "Starting certificate controller" name="csrsigning-legacy-unknown" I0425 00:47:52.441956 1 shared_informer.go:311] Waiting for caches to sync for certificate-csrsigning-legacy-unknown I0425 00:47:52.441986 1 dynamic_serving_content.go:132] "Starting controller" name="csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key" I0425 00:47:52.446851 1 controllermanager.go:642] "Started controller" controller="root-ca-certificate-publisher-controller" I0425 00:47:52.446897 1 publisher.go:102] "Starting root CA cert publisher controller" I0425 00:47:52.446980 1 shared_informer.go:311] Waiting for caches to sync for crt configmap I0425 00:47:52.450066 1 controllermanager.go:642] "Started controller" controller="endpointslice-controller" I0425 00:47:52.450421 1 endpointslice_controller.go:264] "Starting endpoint slice controller" I0425 00:47:52.450443 1 shared_informer.go:311] Waiting for caches to sync for endpoint_slice I0425 00:47:52.453133 1 controllermanager.go:642] "Started controller" controller="replicationcontroller-controller" I0425 00:47:52.453605 1 replica_set.go:214] "Starting controller" name="replicationcontroller" I0425 00:47:52.453622 1 shared_informer.go:311] Waiting for caches to sync for ReplicationController I0425 00:47:52.457390 1 controllermanager.go:642] "Started controller" controller="ttl-after-finished-controller" I0425 00:47:52.457837 1 ttlafterfinished_controller.go:109] "Starting TTL after finished controller" I0425 00:47:52.457860 1 shared_informer.go:311] Waiting for caches to sync for TTL after finished I0425 00:47:52.460572 1 controllermanager.go:642] "Started controller" controller="ttl-controller" I0425 00:47:52.460725 1 ttl_controller.go:124] "Starting TTL controller" I0425 00:47:52.460778 1 shared_informer.go:311] Waiting for caches to sync for TTL I0425 00:47:52.463891 1 controllermanager.go:642] "Started controller" controller="persistentvolume-protection-controller" I0425 00:47:52.464089 1 pv_protection_controller.go:78] "Starting PV protection controller" I0425 00:47:52.464101 1 shared_informer.go:311] Waiting for caches to sync for PV protection I0425 00:47:52.465848 1 controllermanager.go:642] "Started controller" controller="certificatesigningrequest-approving-controller" I0425 00:47:52.465946 1 certificate_controller.go:115] "Starting certificate controller" name="csrapproving" I0425 00:47:52.465962 1 shared_informer.go:311] Waiting for caches to sync for certificate-csrapproving I0425 00:47:52.468500 1 controllermanager.go:642] "Started controller" controller="bootstrap-signer-controller" I0425 00:47:52.468641 1 shared_informer.go:311] Waiting for caches to sync for bootstrap_signer I0425 00:47:52.470400 1 controllermanager.go:642] "Started controller" controller="token-cleaner-controller" I0425 00:47:52.470516 1 tokencleaner.go:112] "Starting token cleaner controller" I0425 00:47:52.470533 1 shared_informer.go:311] Waiting for caches to sync for token_cleaner I0425 00:47:52.470542 1 shared_informer.go:318] Caches are synced for token_cleaner I0425 00:47:52.472980 1 controllermanager.go:642] "Started controller" controller="clusterrole-aggregation-controller" I0425 00:47:52.473026 1 clusterroleaggregation_controller.go:189] "Starting ClusterRoleAggregator controller" I0425 00:47:52.473039 1 shared_informer.go:311] Waiting for caches to sync for ClusterRoleAggregator I0425 00:47:52.481903 1 controllermanager.go:642] "Started controller" controller="garbage-collector-controller" I0425 00:47:52.481970 1 garbagecollector.go:155] "Starting controller" controller="garbagecollector" I0425 00:47:52.482006 1 shared_informer.go:311] Waiting for caches to sync for garbage collector I0425 00:47:52.482060 1 graph_builder.go:294] "Running" component="GraphBuilder" I0425 00:47:52.485501 1 controllermanager.go:642] "Started controller" controller="job-controller" I0425 00:47:52.485810 1 job_controller.go:226] "Starting job controller" I0425 00:47:52.485828 1 shared_informer.go:311] Waiting for caches to sync for job I0425 00:47:52.490865 1 controllermanager.go:642] "Started controller" controller="certificatesigningrequest-cleaner-controller" I0425 00:47:52.491048 1 cleaner.go:83] "Starting CSR cleaner controller" I0425 00:47:52.493870 1 controllermanager.go:642] "Started controller" controller="persistentvolume-binder-controller" I0425 00:47:52.494340 1 pv_controller_base.go:319] "Starting persistent volume controller" I0425 00:47:52.494351 1 shared_informer.go:311] Waiting for caches to sync for persistent volume I0425 00:47:52.496443 1 controllermanager.go:642] "Started controller" controller="persistentvolumeclaim-protection-controller" I0425 00:47:52.496662 1 pvc_protection_controller.go:102] "Starting PVC protection controller" I0425 00:47:52.496672 1 shared_informer.go:311] Waiting for caches to sync for PVC protection I0425 00:47:52.498641 1 controllermanager.go:642] "Started controller" controller="endpoints-controller" I0425 00:47:52.498772 1 endpoints_controller.go:177] "Starting endpoint controller" I0425 00:47:52.498833 1 shared_informer.go:311] Waiting for caches to sync for endpoint I0425 00:47:52.514781 1 controllermanager.go:642] "Started controller" controller="namespace-controller" I0425 00:47:52.514842 1 namespace_controller.go:197] "Starting namespace controller" I0425 00:47:52.515048 1 shared_informer.go:311] Waiting for caches to sync for namespace I0425 00:47:52.520822 1 controllermanager.go:642] "Started controller" controller="disruption-controller" I0425 00:47:52.521040 1 disruption.go:433] "Sending events to api server." I0425 00:47:52.521071 1 disruption.go:444] "Starting disruption controller" I0425 00:47:52.521078 1 shared_informer.go:311] Waiting for caches to sync for disruption I0425 00:47:52.522842 1 shared_informer.go:318] Caches are synced for tokens I0425 00:47:52.523538 1 controllermanager.go:642] "Started controller" controller="statefulset-controller" I0425 00:47:52.523613 1 controllermanager.go:620] "Warning: skipping controller" controller="node-ipam-controller" I0425 00:47:52.523617 1 stateful_set.go:163] "Starting stateful set controller" I0425 00:47:52.523704 1 shared_informer.go:311] Waiting for caches to sync for stateful set I0425 00:47:52.527380 1 controllermanager.go:642] "Started controller" controller="persistentvolume-attach-detach-controller" I0425 00:47:52.527559 1 attach_detach_controller.go:337] "Starting attach detach controller" I0425 00:47:52.527589 1 shared_informer.go:311] Waiting for caches to sync for attach detach I0425 00:47:52.530554 1 controllermanager.go:642] "Started controller" controller="endpointslice-mirroring-controller" I0425 00:47:52.530832 1 endpointslicemirroring_controller.go:223] "Starting EndpointSliceMirroring controller" I0425 00:47:52.530888 1 shared_informer.go:311] Waiting for caches to sync for endpoint_slice_mirroring I0425 00:47:52.533868 1 serviceaccounts_controller.go:111] "Starting service account controller" I0425 00:47:52.533895 1 shared_informer.go:311] Waiting for caches to sync for service account I0425 00:47:52.533822 1 controllermanager.go:642] "Started controller" controller="serviceaccount-controller" I0425 00:47:52.565329 1 controllermanager.go:642] "Started controller" controller="daemonset-controller" I0425 00:47:52.565954 1 daemon_controller.go:291] "Starting daemon sets controller" I0425 00:47:52.566007 1 shared_informer.go:311] Waiting for caches to sync for daemon sets E0425 00:47:52.616565 1 core.go:213] "Failed to start cloud node lifecycle controller" err="no cloud provider provided" I0425 00:47:52.616610 1 controllermanager.go:620] "Warning: skipping controller" controller="cloud-node-lifecycle-controller" I0425 00:47:52.666624 1 controllermanager.go:642] "Started controller" controller="pod-garbage-collector-controller" I0425 00:47:52.666711 1 gc_controller.go:101] "Starting GC controller" I0425 00:47:52.666721 1 shared_informer.go:311] Waiting for caches to sync for GC I0425 00:47:52.875578 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="roles.rbac.authorization.k8s.io" I0425 00:47:52.875643 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="udproutes.gateway.networking.k8s.io" I0425 00:47:52.875668 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="csistoragecapacities.storage.k8s.io" I0425 00:47:52.875686 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="endpointslices.discovery.k8s.io" I0425 00:47:52.875713 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="envoyproxies.gateway.envoyproxy.io" I0425 00:47:52.875737 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="envoyextensionpolicies.gateway.envoyproxy.io" I0425 00:47:52.875767 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="backendtlspolicies.gateway.networking.k8s.io" W0425 00:47:52.875786 1 shared_informer.go:593] resyncPeriod 21h1m43.431510723s is smaller than resyncCheckPeriod 23h35m44.147877315s and the informer has already started. Changing it to 23h35m44.147877315s I0425 00:47:52.875862 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="cronjobs.batch" I0425 00:47:52.875883 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="xlistenersets.gateway.networking.x-k8s.io" I0425 00:47:52.875906 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="rolebindings.rbac.authorization.k8s.io" I0425 00:47:52.875924 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="clienttrafficpolicies.gateway.envoyproxy.io" I0425 00:47:52.875951 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="envoypatchpolicies.gateway.envoyproxy.io" I0425 00:47:52.875973 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="tcproutes.gateway.networking.k8s.io" I0425 00:47:52.875993 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="xbackendtrafficpolicies.gateway.networking.x-k8s.io" I0425 00:47:52.876015 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="deployments.apps" I0425 00:47:52.876039 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="horizontalpodautoscalers.autoscaling" I0425 00:47:52.876060 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="jobs.batch" I0425 00:47:52.876099 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="networkpolicies.networking.k8s.io" I0425 00:47:52.876117 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="ciliumnetworkpolicies.cilium.io" I0425 00:47:52.876141 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="httproutefilters.gateway.envoyproxy.io" I0425 00:47:52.876173 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="limitranges" I0425 00:47:52.876270 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="daemonsets.apps" I0425 00:47:52.876321 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="ingresses.networking.k8s.io" I0425 00:47:52.876347 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="poddisruptionbudgets.policy" I0425 00:47:52.876367 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="ciliumendpoints.cilium.io" I0425 00:47:52.876390 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="ciliumnodeconfigs.cilium.io" I0425 00:47:52.876423 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="endpoints" I0425 00:47:52.876445 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="securitypolicies.gateway.envoyproxy.io" I0425 00:47:52.876480 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="grpcroutes.gateway.networking.k8s.io" I0425 00:47:52.876499 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="referencegrants.gateway.networking.k8s.io" I0425 00:47:52.876520 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="tlsroutes.gateway.networking.k8s.io" I0425 00:47:52.876539 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="podtemplates" I0425 00:47:52.876560 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="backends.gateway.envoyproxy.io" I0425 00:47:52.876579 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="httproutes.gateway.networking.k8s.io" W0425 00:47:52.876600 1 shared_informer.go:593] resyncPeriod 21h8m18.907033522s is smaller than resyncCheckPeriod 23h35m44.147877315s and the informer has already started. Changing it to 23h35m44.147877315s I0425 00:47:52.876711 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="serviceaccounts" I0425 00:47:52.876782 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="leases.coordination.k8s.io" I0425 00:47:52.876812 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="backendtrafficpolicies.gateway.envoyproxy.io" I0425 00:47:52.876833 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="gateways.gateway.networking.k8s.io" I0425 00:47:52.876854 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="controllerrevisions.apps" I0425 00:47:52.876868 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="replicasets.apps" I0425 00:47:52.876887 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="statefulsets.apps" I0425 00:47:52.876912 1 controllermanager.go:642] "Started controller" controller="resourcequota-controller" I0425 00:47:52.876981 1 resource_quota_controller.go:294] "Starting resource quota controller" I0425 00:47:52.877016 1 shared_informer.go:311] Waiting for caches to sync for resource quota I0425 00:47:52.877090 1 resource_quota_monitor.go:305] "QuotaMonitor running" I0425 00:47:52.915069 1 controllermanager.go:642] "Started controller" controller="replicaset-controller" I0425 00:47:52.915172 1 replica_set.go:214] "Starting controller" name="replicaset" I0425 00:47:52.915188 1 shared_informer.go:311] Waiting for caches to sync for ReplicaSet I0425 00:47:52.964917 1 controllermanager.go:642] "Started controller" controller="persistentvolume-expander-controller" I0425 00:47:52.965024 1 expand_controller.go:328] "Starting expand controller" I0425 00:47:52.965054 1 shared_informer.go:311] Waiting for caches to sync for expand I0425 00:47:53.015369 1 node_lifecycle_controller.go:431] "Controller will reconcile labels" I0425 00:47:53.015435 1 controllermanager.go:642] "Started controller" controller="node-lifecycle-controller" I0425 00:47:53.015609 1 node_lifecycle_controller.go:465] "Sending events to api server" I0425 00:47:53.015687 1 node_lifecycle_controller.go:476] "Starting node controller" I0425 00:47:53.015708 1 shared_informer.go:311] Waiting for caches to sync for taint E0425 00:47:53.065469 1 core.go:92] "Failed to start service controller" err="WARNING: no cloud provider provided, services of type LoadBalancer will fail" I0425 00:47:53.065495 1 controllermanager.go:620] "Warning: skipping controller" controller="service-lb-controller" I0425 00:47:53.115457 1 controllermanager.go:642] "Started controller" controller="ephemeral-volume-controller" I0425 00:47:53.115554 1 controller.go:169] "Starting ephemeral volume controller" I0425 00:47:53.115572 1 shared_informer.go:311] Waiting for caches to sync for ephemeral I0425 00:47:53.164767 1 controllermanager.go:642] "Started controller" controller="deployment-controller" I0425 00:47:53.164959 1 deployment_controller.go:168] "Starting controller" controller="deployment" I0425 00:47:53.165005 1 shared_informer.go:311] Waiting for caches to sync for deployment I0425 00:47:53.365408 1 controllermanager.go:642] "Started controller" controller="horizontal-pod-autoscaler-controller" I0425 00:47:53.365456 1 horizontal.go:200] "Starting HPA controller" I0425 00:47:53.365484 1 shared_informer.go:311] Waiting for caches to sync for HPA I0425 00:47:53.371966 1 shared_informer.go:311] Waiting for caches to sync for resource quota I0425 00:47:53.379373 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" I0425 00:47:53.396028 1 shared_informer.go:311] Waiting for caches to sync for garbage collector I0425 00:47:53.415234 1 shared_informer.go:318] Caches are synced for namespace I0425 00:47:53.431872 1 shared_informer.go:318] Caches are synced for endpoint_slice_mirroring I0425 00:47:53.434251 1 shared_informer.go:318] Caches are synced for service account I0425 00:47:53.440004 1 shared_informer.go:318] Caches are synced for certificate-csrsigning-kubelet-client I0425 00:47:53.440056 1 shared_informer.go:318] Caches are synced for certificate-csrsigning-kubelet-serving I0425 00:47:53.442376 1 shared_informer.go:318] Caches are synced for certificate-csrsigning-legacy-unknown I0425 00:47:53.442452 1 shared_informer.go:318] Caches are synced for certificate-csrsigning-kube-apiserver-client I0425 00:47:53.460965 1 shared_informer.go:318] Caches are synced for TTL I0425 00:47:53.464306 1 shared_informer.go:318] Caches are synced for PV protection I0425 00:47:53.465591 1 shared_informer.go:318] Caches are synced for expand I0425 00:47:53.466934 1 shared_informer.go:318] Caches are synced for certificate-csrapproving I0425 00:47:53.473671 1 shared_informer.go:318] Caches are synced for ClusterRoleAggregator I0425 00:47:53.528265 1 shared_informer.go:318] Caches are synced for attach detach E0425 00:47:53.528792 1 attach_detach_controller.go:449] "Error creating spec for volume of pod" err="error processing PVC \"default\"/\"test-pvc\": PVC default/test-pvc has non-bound phase (\"Pending\") or empty pvc.Spec.VolumeName (\"\")" pod="default/test-pvc-pod" volumeName="test-pvc" I0425 00:47:53.537143 1 shared_informer.go:318] Caches are synced for cronjob I0425 00:47:53.551710 1 shared_informer.go:318] Caches are synced for endpoint_slice I0425 00:47:53.554098 1 shared_informer.go:318] Caches are synced for ReplicationController I0425 00:47:53.558623 1 shared_informer.go:318] Caches are synced for TTL after finished I0425 00:47:53.565438 1 shared_informer.go:318] Caches are synced for deployment I0425 00:47:53.565612 1 shared_informer.go:318] Caches are synced for HPA I0425 00:47:53.566626 1 shared_informer.go:318] Caches are synced for daemon sets I0425 00:47:53.566764 1 shared_informer.go:318] Caches are synced for GC I0425 00:47:53.572267 1 shared_informer.go:318] Caches are synced for resource quota I0425 00:47:53.577270 1 shared_informer.go:318] Caches are synced for resource quota I0425 00:47:53.586862 1 shared_informer.go:318] Caches are synced for job I0425 00:47:53.595703 1 shared_informer.go:318] Caches are synced for persistent volume I0425 00:47:53.597314 1 shared_informer.go:318] Caches are synced for PVC protection I0425 00:47:53.599575 1 shared_informer.go:318] Caches are synced for endpoint I0425 00:47:53.616165 1 shared_informer.go:318] Caches are synced for taint I0425 00:47:53.616302 1 node_lifecycle_controller.go:1225] "Initializing eviction metric for zone" zone="" I0425 00:47:53.616485 1 node_lifecycle_controller.go:877] "Missing timestamp for Node. Assuming now as a timestamp" node="instance" I0425 00:47:53.616504 1 shared_informer.go:318] Caches are synced for ReplicaSet I0425 00:47:53.616543 1 shared_informer.go:318] Caches are synced for ephemeral I0425 00:47:53.616649 1 node_lifecycle_controller.go:1071] "Controller detected that zone is now in new state" zone="" newState="Normal" I0425 00:47:53.616699 1 taint_manager.go:205] "Starting NoExecuteTaintManager" I0425 00:47:53.616790 1 taint_manager.go:210] "Sending events to api server" I0425 00:47:53.616711 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" I0425 00:47:53.616948 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/ceph-csi-rbd-provisioner-69cb97fb6" duration="242.336µs" I0425 00:47:53.617036 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-67659f764b" duration="56.922µs" I0425 00:47:53.617070 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="69.732µs" I0425 00:47:53.617257 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/cilium-operator-869df985b8" duration="133.433µs" I0425 00:47:53.622055 1 shared_informer.go:318] Caches are synced for disruption I0425 00:47:53.624227 1 shared_informer.go:318] Caches are synced for stateful set I0425 00:47:53.648126 1 shared_informer.go:318] Caches are synced for crt configmap I0425 00:47:53.668820 1 shared_informer.go:318] Caches are synced for bootstrap_signer I0425 00:47:53.982156 1 shared_informer.go:318] Caches are synced for garbage collector I0425 00:47:53.982188 1 garbagecollector.go:166] "All resource monitors have synced. Proceeding to collect garbage" I0425 00:47:53.996765 1 shared_informer.go:318] Caches are synced for garbage collector I0425 00:47:54.025280 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-78446f96c9" duration="408.681982ms" I0425 00:47:54.025662 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-78446f96c9" duration="168.134µs" I0425 00:47:55.163114 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/ceph-csi-rbd-provisioner-69cb97fb6" duration="13.979989ms" I0425 00:47:55.163334 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/ceph-csi-rbd-provisioner-69cb97fb6" duration="159.044µs" I0425 00:47:57.051483 1 reconciler.go:352] "attacherDetacher.AttachVolume started" volumeName="kubernetes.io/csi/rbd.csi.ceph.com^0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-3770a474-e14f-4ef6-89de-c256e6c68ebb" nodeName="instance" scheduledPods=["default/test-pvc-pod"] I0425 00:47:57.598682 1 operation_generator.go:400] AttachVolume.Attach succeeded for volume "pvc-c2589112-1f79-45b7-97c8-d88d85babedf" (UniqueName: "kubernetes.io/csi/rbd.csi.ceph.com^0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-3770a474-e14f-4ef6-89de-c256e6c68ebb") from node "instance" I0425 00:47:57.598814 1 event.go:307] "Event occurred" object="default/test-pvc-pod" fieldPath="" kind="Pod" apiVersion="v1" type="Normal" reason="SuccessfulAttachVolume" message="AttachVolume.Attach succeeded for volume \"pvc-c2589112-1f79-45b7-97c8-d88d85babedf\" " I0425 00:48:23.323493 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-78446f96c9" duration="158.574µs" I0425 00:48:36.601250 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-78446f96c9" duration="550.532153ms" I0425 00:48:36.601404 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-78446f96c9" duration="104.743µs" I0425 00:48:38.892920 1 reconciler.go:267] "attacherDetacher.DetachVolume started" node="instance" volumeName="kubernetes.io/csi/rbd.csi.ceph.com^0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-3770a474-e14f-4ef6-89de-c256e6c68ebb" I0425 00:48:38.897299 1 operation_generator.go:1636] Verified volume is safe to detach for volume "pvc-c2589112-1f79-45b7-97c8-d88d85babedf" (UniqueName: "kubernetes.io/csi/rbd.csi.ceph.com^0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-3770a474-e14f-4ef6-89de-c256e6c68ebb") on node "instance" I0425 00:48:39.458697 1 operation_generator.go:520] DetachVolume.Detach succeeded for volume "pvc-c2589112-1f79-45b7-97c8-d88d85babedf" (UniqueName: "kubernetes.io/csi/rbd.csi.ceph.com^0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-3770a474-e14f-4ef6-89de-c256e6c68ebb") on node "instance"