I0608 08:44:03.825758 1 serving.go:386] Generated self-signed cert in-memory I0608 08:44:04.886200 1 controllermanager.go:185] "Starting" version="v1.32.10" I0608 08:44:04.886233 1 controllermanager.go:187] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" I0608 08:44:04.887995 1 dynamic_cafile_content.go:161] "Starting controller" name="request-header::/etc/kubernetes/pki/front-proxy-ca.crt" I0608 08:44:04.888448 1 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" I0608 08:44:04.888532 1 secure_serving.go:213] Serving securely on [::]:10257 I0608 08:44:04.888713 1 tlsconfig.go:243] "Starting DynamicServingCertificateController" I0608 08:44:04.888986 1 leaderelection.go:257] attempting to acquire leader lease kube-system/kube-controller-manager... E0608 08:44:04.897799 1 leaderelection.go:436] 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" I0608 08:44:09.219016 1 leaderelection.go:271] successfully acquired lease kube-system/kube-controller-manager I0608 08:44:09.219327 1 event.go:389] "Event occurred" object="kube-system/kube-controller-manager" fieldPath="" kind="Lease" apiVersion="coordination.k8s.io/v1" type="Normal" reason="LeaderElection" message="instance_06659665-18e1-4694-89e5-6d1eddb2664a became leader" I0608 08:44:09.222717 1 controllermanager.go:765] "Started controller" controller="serviceaccount-token-controller" I0608 08:44:09.222978 1 shared_informer.go:313] Waiting for caches to sync for tokens I0608 08:44:09.232632 1 controllermanager.go:765] "Started controller" controller="ttl-controller" I0608 08:44:09.232930 1 ttl_controller.go:127] "Starting TTL controller" logger="ttl-controller" I0608 08:44:09.232950 1 shared_informer.go:313] Waiting for caches to sync for TTL I0608 08:44:09.238863 1 controllermanager.go:765] "Started controller" controller="root-ca-certificate-publisher-controller" I0608 08:44:09.242814 1 publisher.go:107] "Starting root CA cert publisher controller" logger="root-ca-certificate-publisher-controller" I0608 08:44:09.242852 1 shared_informer.go:313] Waiting for caches to sync for crt configmap I0608 08:44:09.265521 1 controllermanager.go:765] "Started controller" controller="validatingadmissionpolicy-status-controller" I0608 08:44:09.265593 1 controllermanager.go:743] "Warning: skipping controller" controller="storage-version-migrator-controller" I0608 08:44:09.265803 1 shared_informer.go:313] Waiting for caches to sync for validatingadmissionpolicy-status I0608 08:44:09.272240 1 controllermanager.go:765] "Started controller" controller="endpoints-controller" I0608 08:44:09.272432 1 endpoints_controller.go:182] "Starting endpoint controller" logger="endpoints-controller" I0608 08:44:09.272446 1 shared_informer.go:313] Waiting for caches to sync for endpoint I0608 08:44:09.278424 1 controllermanager.go:765] "Started controller" controller="endpointslice-controller" I0608 08:44:09.278792 1 endpointslice_controller.go:281] "Starting endpoint slice controller" logger="endpointslice-controller" I0608 08:44:09.278814 1 shared_informer.go:313] Waiting for caches to sync for endpoint_slice I0608 08:44:09.285907 1 controllermanager.go:765] "Started controller" controller="certificatesigningrequest-cleaner-controller" I0608 08:44:09.285948 1 controllermanager.go:743] "Warning: skipping controller" controller="node-ipam-controller" I0608 08:44:09.286183 1 cleaner.go:83] "Starting CSR cleaner controller" logger="certificatesigningrequest-cleaner-controller" I0608 08:44:09.295849 1 controllermanager.go:765] "Started controller" controller="legacy-serviceaccount-token-cleaner-controller" I0608 08:44:09.295874 1 controllermanager.go:717] "Controller is disabled by a feature gate" controller="selinux-warning-controller" requiredFeatureGates=["SELinuxChangePolicy"] I0608 08:44:09.295955 1 legacy_serviceaccount_token_cleaner.go:103] "Starting legacy service account token cleaner controller" logger="legacy-serviceaccount-token-cleaner-controller" I0608 08:44:09.295994 1 shared_informer.go:313] Waiting for caches to sync for legacy-service-account-token-cleaner I0608 08:44:09.310606 1 garbagecollector.go:144] "Starting controller" logger="garbage-collector-controller" controller="garbagecollector" I0608 08:44:09.310619 1 controllermanager.go:765] "Started controller" controller="garbage-collector-controller" I0608 08:44:09.310648 1 shared_informer.go:313] Waiting for caches to sync for garbage collector I0608 08:44:09.310718 1 graph_builder.go:351] "Running" logger="garbage-collector-controller" component="GraphBuilder" I0608 08:44:09.323987 1 shared_informer.go:320] Caches are synced for tokens I0608 08:44:09.376804 1 controllermanager.go:765] "Started controller" controller="token-cleaner-controller" I0608 08:44:09.376881 1 tokencleaner.go:117] "Starting token cleaner controller" logger="token-cleaner-controller" I0608 08:44:09.376890 1 shared_informer.go:313] Waiting for caches to sync for token_cleaner I0608 08:44:09.376898 1 shared_informer.go:320] Caches are synced for token_cleaner I0608 08:44:09.526365 1 controllermanager.go:765] "Started controller" controller="taint-eviction-controller" I0608 08:44:09.526411 1 taint_eviction.go:281] "Starting" logger="taint-eviction-controller" controller="taint-eviction-controller" I0608 08:44:09.526597 1 taint_eviction.go:287] "Sending events to api server" logger="taint-eviction-controller" I0608 08:44:09.526670 1 shared_informer.go:313] Waiting for caches to sync for taint-eviction-controller I0608 08:44:09.676077 1 controllermanager.go:765] "Started controller" controller="job-controller" I0608 08:44:09.676181 1 job_controller.go:243] "Starting job controller" logger="job-controller" I0608 08:44:09.676346 1 shared_informer.go:313] Waiting for caches to sync for job I0608 08:44:09.830105 1 controllermanager.go:765] "Started controller" controller="bootstrap-signer-controller" I0608 08:44:09.830249 1 shared_informer.go:313] Waiting for caches to sync for bootstrap_signer I0608 08:44:09.975281 1 controllermanager.go:765] "Started controller" controller="persistentvolumeclaim-protection-controller" I0608 08:44:09.975306 1 controllermanager.go:717] "Controller is disabled by a feature gate" controller="volumeattributesclass-protection-controller" requiredFeatureGates=["VolumeAttributesClass"] I0608 08:44:09.975362 1 pvc_protection_controller.go:168] "Starting PVC protection controller" logger="persistentvolumeclaim-protection-controller" I0608 08:44:09.975373 1 shared_informer.go:313] Waiting for caches to sync for PVC protection I0608 08:44:10.231926 1 controllermanager.go:765] "Started controller" controller="namespace-controller" I0608 08:44:10.232028 1 namespace_controller.go:202] "Starting namespace controller" logger="namespace-controller" I0608 08:44:10.232064 1 shared_informer.go:313] Waiting for caches to sync for namespace I0608 08:44:10.376553 1 controllermanager.go:765] "Started controller" controller="daemonset-controller" I0608 08:44:10.376666 1 daemon_controller.go:294] "Starting daemon sets controller" logger="daemonset-controller" I0608 08:44:10.376682 1 shared_informer.go:313] Waiting for caches to sync for daemon sets I0608 08:44:10.525526 1 controllermanager.go:765] "Started controller" controller="replicaset-controller" I0608 08:44:10.525666 1 replica_set.go:217] "Starting controller" logger="replicaset-controller" name="replicaset" I0608 08:44:10.525702 1 shared_informer.go:313] Waiting for caches to sync for ReplicaSet I0608 08:44:10.723798 1 controllermanager.go:765] "Started controller" controller="disruption-controller" I0608 08:44:10.723875 1 disruption.go:452] "Sending events to api server." logger="disruption-controller" I0608 08:44:10.724735 1 disruption.go:463] "Starting disruption controller" logger="disruption-controller" I0608 08:44:10.724776 1 shared_informer.go:313] Waiting for caches to sync for disruption I0608 08:44:10.877147 1 controllermanager.go:765] "Started controller" controller="persistentvolume-binder-controller" I0608 08:44:10.877225 1 pv_controller_base.go:308] "Starting persistent volume controller" logger="persistentvolume-binder-controller" I0608 08:44:10.877244 1 shared_informer.go:313] Waiting for caches to sync for persistent volume I0608 08:44:11.025318 1 controllermanager.go:765] "Started controller" controller="persistentvolume-expander-controller" I0608 08:44:11.025367 1 controllermanager.go:717] "Controller is disabled by a feature gate" controller="kube-apiserver-serving-clustertrustbundle-publisher-controller" requiredFeatureGates=["ClusterTrustBundle"] I0608 08:44:11.025441 1 expand_controller.go:329] "Starting expand controller" logger="persistentvolume-expander-controller" I0608 08:44:11.025452 1 shared_informer.go:313] Waiting for caches to sync for expand I0608 08:44:11.196802 1 controllermanager.go:765] "Started controller" controller="ephemeral-volume-controller" I0608 08:44:11.196827 1 controllermanager.go:723] "Skipping a cloud provider controller" controller="service-lb-controller" I0608 08:44:11.196865 1 controller.go:173] "Starting ephemeral volume controller" logger="ephemeral-volume-controller" I0608 08:44:11.196881 1 shared_informer.go:313] Waiting for caches to sync for ephemeral I0608 08:44:11.324680 1 controllermanager.go:765] "Started controller" controller="replicationcontroller-controller" I0608 08:44:11.324816 1 replica_set.go:217] "Starting controller" logger="replicationcontroller-controller" name="replicationcontroller" I0608 08:44:11.324855 1 shared_informer.go:313] Waiting for caches to sync for ReplicationController I0608 08:44:11.475299 1 controllermanager.go:765] "Started controller" controller="serviceaccount-controller" I0608 08:44:11.475414 1 serviceaccounts_controller.go:114] "Starting service account controller" logger="serviceaccount-controller" I0608 08:44:11.475466 1 shared_informer.go:313] Waiting for caches to sync for service account I0608 08:44:11.773562 1 controllermanager.go:765] "Started controller" controller="horizontal-pod-autoscaler-controller" I0608 08:44:11.773722 1 horizontal.go:201] "Starting HPA controller" logger="horizontal-pod-autoscaler-controller" I0608 08:44:11.773765 1 shared_informer.go:313] Waiting for caches to sync for HPA I0608 08:44:11.924329 1 controllermanager.go:765] "Started controller" controller="statefulset-controller" I0608 08:44:11.924491 1 stateful_set.go:166] "Starting stateful set controller" logger="statefulset-controller" I0608 08:44:11.924552 1 shared_informer.go:313] Waiting for caches to sync for stateful set I0608 08:44:12.081838 1 controllermanager.go:765] "Started controller" controller="persistentvolume-protection-controller" I0608 08:44:12.081863 1 controllermanager.go:717] "Controller is disabled by a feature gate" controller="service-cidr-controller" requiredFeatureGates=["MultiCIDRServiceAllocator"] I0608 08:44:12.081927 1 pv_protection_controller.go:81] "Starting PV protection controller" logger="persistentvolume-protection-controller" I0608 08:44:12.081946 1 shared_informer.go:313] Waiting for caches to sync for PV protection I0608 08:44:12.228262 1 controllermanager.go:765] "Started controller" controller="pod-garbage-collector-controller" I0608 08:44:12.228313 1 gc_controller.go:99] "Starting GC controller" logger="pod-garbage-collector-controller" I0608 08:44:12.228524 1 shared_informer.go:313] Waiting for caches to sync for GC I0608 08:44:12.532718 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="daemonsets.apps" I0608 08:44:12.532789 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="deployments.apps" I0608 08:44:12.532825 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="poddisruptionbudgets.policy" I0608 08:44:12.532850 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="jobs.batch" I0608 08:44:12.532889 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="roles.rbac.authorization.k8s.io" I0608 08:44:12.532957 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="podtemplates" I0608 08:44:12.532996 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="endpointslices.discovery.k8s.io" I0608 08:44:12.533030 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="replicasets.apps" I0608 08:44:12.533077 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="ingresses.networking.k8s.io" I0608 08:44:12.533104 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="rolebindings.rbac.authorization.k8s.io" I0608 08:44:12.533204 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="controllerrevisions.apps" I0608 08:44:12.533255 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="cronjobs.batch" I0608 08:44:12.533391 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="serviceaccounts" I0608 08:44:12.533426 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="endpoints" I0608 08:44:12.533461 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="limitranges" I0608 08:44:12.533531 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="networkpolicies.networking.k8s.io" I0608 08:44:12.533576 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="csistoragecapacities.storage.k8s.io" I0608 08:44:12.533613 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="leases.coordination.k8s.io" I0608 08:44:12.533665 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="statefulsets.apps" I0608 08:44:12.533695 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="horizontalpodautoscalers.autoscaling" I0608 08:44:12.533734 1 controllermanager.go:765] "Started controller" controller="resourcequota-controller" I0608 08:44:12.533773 1 resource_quota_controller.go:300] "Starting resource quota controller" logger="resourcequota-controller" I0608 08:44:12.533800 1 shared_informer.go:313] Waiting for caches to sync for resource quota I0608 08:44:12.533850 1 resource_quota_monitor.go:308] "QuotaMonitor running" logger="resourcequota-controller" I0608 08:44:12.675364 1 controllermanager.go:765] "Started controller" controller="deployment-controller" I0608 08:44:12.675516 1 deployment_controller.go:173] "Starting controller" logger="deployment-controller" controller="deployment" I0608 08:44:12.675533 1 shared_informer.go:313] Waiting for caches to sync for deployment I0608 08:44:12.827801 1 controllermanager.go:765] "Started controller" controller="cronjob-controller" I0608 08:44:12.827887 1 cronjob_controllerv2.go:145] "Starting cronjob controller v2" logger="cronjob-controller" I0608 08:44:12.827895 1 shared_informer.go:313] Waiting for caches to sync for cronjob I0608 08:44:12.872937 1 controllermanager.go:765] "Started controller" controller="certificatesigningrequest-approving-controller" I0608 08:44:12.873018 1 certificate_controller.go:120] "Starting certificate controller" logger="certificatesigningrequest-approving-controller" name="csrapproving" I0608 08:44:12.873030 1 shared_informer.go:313] Waiting for caches to sync for certificate-csrapproving I0608 08:44:12.922722 1 node_lifecycle_controller.go:432] "Controller will reconcile labels" logger="node-lifecycle-controller" I0608 08:44:12.922795 1 controllermanager.go:765] "Started controller" controller="node-lifecycle-controller" I0608 08:44:12.922805 1 controllermanager.go:723] "Skipping a cloud provider controller" controller="cloud-node-lifecycle-controller" I0608 08:44:12.922900 1 node_lifecycle_controller.go:466] "Sending events to api server" logger="node-lifecycle-controller" I0608 08:44:12.922969 1 node_lifecycle_controller.go:477] "Starting node controller" logger="node-lifecycle-controller" I0608 08:44:12.922990 1 shared_informer.go:313] Waiting for caches to sync for taint I0608 08:44:13.081549 1 controllermanager.go:765] "Started controller" controller="persistentvolume-attach-detach-controller" I0608 08:44:13.081835 1 attach_detach_controller.go:338] "Starting attach detach controller" logger="persistentvolume-attach-detach-controller" I0608 08:44:13.081859 1 shared_informer.go:313] Waiting for caches to sync for attach detach I0608 08:44:13.238533 1 controllermanager.go:765] "Started controller" controller="endpointslice-mirroring-controller" I0608 08:44:13.238690 1 endpointslicemirroring_controller.go:227] "Starting EndpointSliceMirroring controller" logger="endpointslice-mirroring-controller" I0608 08:44:13.238717 1 shared_informer.go:313] Waiting for caches to sync for endpoint_slice_mirroring I0608 08:44:13.274717 1 certificate_controller.go:120] "Starting certificate controller" logger="certificatesigningrequest-signing-controller" name="csrsigning-kubelet-serving" I0608 08:44:13.274765 1 shared_informer.go:313] Waiting for caches to sync for certificate-csrsigning-kubelet-serving I0608 08:44:13.274828 1 dynamic_serving_content.go:135] "Starting controller" name="csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key" I0608 08:44:13.275856 1 certificate_controller.go:120] "Starting certificate controller" logger="certificatesigningrequest-signing-controller" name="csrsigning-kubelet-client" I0608 08:44:13.275891 1 shared_informer.go:313] Waiting for caches to sync for certificate-csrsigning-kubelet-client I0608 08:44:13.275934 1 dynamic_serving_content.go:135] "Starting controller" name="csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key" I0608 08:44:13.277532 1 certificate_controller.go:120] "Starting certificate controller" logger="certificatesigningrequest-signing-controller" name="csrsigning-kube-apiserver-client" I0608 08:44:13.277558 1 shared_informer.go:313] Waiting for caches to sync for certificate-csrsigning-kube-apiserver-client I0608 08:44:13.277579 1 dynamic_serving_content.go:135] "Starting controller" name="csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key" I0608 08:44:13.278350 1 controllermanager.go:765] "Started controller" controller="certificatesigningrequest-signing-controller" I0608 08:44:13.278370 1 controllermanager.go:723] "Skipping a cloud provider controller" controller="node-route-controller" I0608 08:44:13.278466 1 certificate_controller.go:120] "Starting certificate controller" logger="certificatesigningrequest-signing-controller" name="csrsigning-legacy-unknown" I0608 08:44:13.278483 1 shared_informer.go:313] Waiting for caches to sync for certificate-csrsigning-legacy-unknown I0608 08:44:13.278534 1 dynamic_serving_content.go:135] "Starting controller" name="csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key" I0608 08:44:13.431130 1 controllermanager.go:765] "Started controller" controller="clusterrole-aggregation-controller" I0608 08:44:13.432071 1 clusterroleaggregation_controller.go:194] "Starting ClusterRoleAggregator controller" logger="clusterrole-aggregation-controller" I0608 08:44:13.432106 1 shared_informer.go:313] Waiting for caches to sync for ClusterRoleAggregator I0608 08:44:13.575324 1 controllermanager.go:765] "Started controller" controller="ttl-after-finished-controller" I0608 08:44:13.575360 1 controllermanager.go:717] "Controller is disabled by a feature gate" controller="storageversion-garbage-collector-controller" requiredFeatureGates=["APIServerIdentity","StorageVersionAPI"] I0608 08:44:13.575378 1 controllermanager.go:717] "Controller is disabled by a feature gate" controller="resourceclaim-controller" requiredFeatureGates=["DynamicResourceAllocation"] I0608 08:44:13.578945 1 ttlafterfinished_controller.go:112] "Starting TTL after finished controller" logger="ttl-after-finished-controller" I0608 08:44:13.578987 1 shared_informer.go:313] Waiting for caches to sync for TTL after finished I0608 08:44:13.586557 1 shared_informer.go:313] Waiting for caches to sync for resource quota I0608 08:44:13.596308 1 shared_informer.go:313] Waiting for caches to sync for garbage collector I0608 08:44:13.601182 1 actual_state_of_world.go:541] "Failed to update statusUpdateNeeded field in actual state of world" logger="persistentvolume-attach-detach-controller" err="Failed to set statusUpdateNeeded to needed true, because nodeName=\"instance\" does not exist" I0608 08:44:13.623916 1 shared_informer.go:320] Caches are synced for taint I0608 08:44:13.624013 1 node_lifecycle_controller.go:1234] "Initializing eviction metric for zone" logger="node-lifecycle-controller" zone="" I0608 08:44:13.624612 1 node_lifecycle_controller.go:886] "Missing timestamp for Node. Assuming now as a timestamp" logger="node-lifecycle-controller" node="instance" I0608 08:44:13.624676 1 node_lifecycle_controller.go:1038] "Controller detected that all Nodes are not-Ready. Entering master disruption mode" logger="node-lifecycle-controller" I0608 08:44:13.625911 1 shared_informer.go:320] Caches are synced for disruption I0608 08:44:13.625905 1 shared_informer.go:320] Caches are synced for ReplicaSet I0608 08:44:13.625972 1 shared_informer.go:320] Caches are synced for stateful set I0608 08:44:13.625949 1 shared_informer.go:320] Caches are synced for expand I0608 08:44:13.625959 1 shared_informer.go:320] Caches are synced for ReplicationController I0608 08:44:13.627411 1 shared_informer.go:320] Caches are synced for taint-eviction-controller I0608 08:44:13.628037 1 shared_informer.go:320] Caches are synced for cronjob I0608 08:44:13.628897 1 shared_informer.go:320] Caches are synced for GC I0608 08:44:13.631161 1 shared_informer.go:320] Caches are synced for bootstrap_signer I0608 08:44:13.632364 1 shared_informer.go:320] Caches are synced for namespace I0608 08:44:13.632513 1 shared_informer.go:320] Caches are synced for ClusterRoleAggregator I0608 08:44:13.633084 1 shared_informer.go:320] Caches are synced for TTL I0608 08:44:13.633922 1 shared_informer.go:320] Caches are synced for resource quota I0608 08:44:13.639133 1 shared_informer.go:320] Caches are synced for endpoint_slice_mirroring I0608 08:44:13.643643 1 shared_informer.go:320] Caches are synced for crt configmap I0608 08:44:13.665959 1 shared_informer.go:320] Caches are synced for validatingadmissionpolicy-status I0608 08:44:13.672814 1 shared_informer.go:320] Caches are synced for endpoint I0608 08:44:13.673167 1 shared_informer.go:320] Caches are synced for certificate-csrapproving I0608 08:44:13.673933 1 shared_informer.go:320] Caches are synced for HPA I0608 08:44:13.675329 1 shared_informer.go:320] Caches are synced for certificate-csrsigning-kubelet-serving I0608 08:44:13.675454 1 shared_informer.go:320] Caches are synced for PVC protection I0608 08:44:13.675531 1 shared_informer.go:320] Caches are synced for service account I0608 08:44:13.675568 1 shared_informer.go:320] Caches are synced for deployment I0608 08:44:13.676488 1 shared_informer.go:320] Caches are synced for certificate-csrsigning-kubelet-client I0608 08:44:13.676639 1 shared_informer.go:320] Caches are synced for job I0608 08:44:13.676837 1 shared_informer.go:320] Caches are synced for daemon sets I0608 08:44:13.677370 1 shared_informer.go:320] Caches are synced for persistent volume I0608 08:44:13.677717 1 shared_informer.go:320] Caches are synced for certificate-csrsigning-kube-apiserver-client I0608 08:44:13.678977 1 shared_informer.go:320] Caches are synced for certificate-csrsigning-legacy-unknown I0608 08:44:13.679143 1 shared_informer.go:320] Caches are synced for TTL after finished I0608 08:44:13.679325 1 shared_informer.go:320] Caches are synced for endpoint_slice I0608 08:44:13.682854 1 shared_informer.go:320] Caches are synced for attach detach I0608 08:44:13.683094 1 shared_informer.go:320] Caches are synced for PV protection I0608 08:44:13.687161 1 shared_informer.go:320] Caches are synced for resource quota I0608 08:44:13.696399 1 shared_informer.go:320] Caches are synced for legacy-service-account-token-cleaner I0608 08:44:13.696569 1 shared_informer.go:320] Caches are synced for garbage collector I0608 08:44:13.697621 1 shared_informer.go:320] Caches are synced for ephemeral I0608 08:44:13.711111 1 shared_informer.go:320] Caches are synced for garbage collector I0608 08:44:13.711145 1 garbagecollector.go:154] "Garbage collector: all resource monitors have synced" logger="garbage-collector-controller" I0608 08:44:13.711161 1 garbagecollector.go:157] "Proceeding to collect garbage" logger="garbage-collector-controller" I0608 08:44:14.901873 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="265.981875ms" I0608 08:44:14.909435 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="7.474146ms" I0608 08:44:14.909556 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="75.432µs" I0608 08:44:14.909706 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="36.111µs" I0608 08:44:28.161668 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-5cd68b5dbf" duration="16.665453ms" I0608 08:44:28.170986 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-5cd68b5dbf" duration="9.27052ms" I0608 08:44:28.171111 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-5cd68b5dbf" duration="73.643µs" I0608 08:44:28.187582 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="24.206201ms" I0608 08:44:28.204981 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-5cd68b5dbf" duration="23.198831ms" I0608 08:44:28.205515 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="17.895971ms" I0608 08:44:28.205646 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="82.633µs" I0608 08:44:28.216283 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-5cd68b5dbf" duration="11.24663ms" I0608 08:44:28.234813 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-5cd68b5dbf" duration="18.475367ms" I0608 08:44:28.234993 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-5cd68b5dbf" duration="113.324µs" I0608 08:44:40.076271 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/cilium-operator-646c4c79cc" duration="25.041007ms" I0608 08:44:40.086176 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/cilium-operator-646c4c79cc" duration="9.862518ms" I0608 08:44:40.086261 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/cilium-operator-646c4c79cc" duration="50.341µs" I0608 08:44:40.086295 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/cilium-operator-646c4c79cc" duration="22.82µs" I0608 08:44:40.112970 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/cilium-operator-646c4c79cc" duration="57.771µs" I0608 08:44:43.692751 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="envoyextensionpolicies.gateway.envoyproxy.io" I0608 08:44:43.692799 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="httproutefilters.gateway.envoyproxy.io" I0608 08:44:43.692827 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="envoypatchpolicies.gateway.envoyproxy.io" I0608 08:44:43.692860 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="backendtlspolicies.gateway.networking.k8s.io" I0608 08:44:43.692887 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="backendtrafficpolicies.gateway.envoyproxy.io" I0608 08:44:43.692916 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="backends.gateway.envoyproxy.io" I0608 08:44:43.692940 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="clienttrafficpolicies.gateway.envoyproxy.io" I0608 08:44:43.692969 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="securitypolicies.gateway.envoyproxy.io" I0608 08:44:43.692989 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="envoyproxies.gateway.envoyproxy.io" I0608 08:44:43.693017 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="gateways.gateway.networking.k8s.io" I0608 08:44:43.693041 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="httproutes.gateway.networking.k8s.io" I0608 08:44:43.693061 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="grpcroutes.gateway.networking.k8s.io" I0608 08:44:43.693081 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="tlsroutes.gateway.networking.k8s.io" I0608 08:44:43.693111 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="xlistenersets.gateway.networking.x-k8s.io" I0608 08:44:43.693133 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="udproutes.gateway.networking.k8s.io" I0608 08:44:43.693151 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="referencegrants.gateway.networking.k8s.io" I0608 08:44:43.693170 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="tcproutes.gateway.networking.k8s.io" I0608 08:44:43.693188 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="xbackendtrafficpolicies.gateway.networking.x-k8s.io" I0608 08:44:43.693333 1 shared_informer.go:313] Waiting for caches to sync for resource quota I0608 08:44:43.707325 1 shared_informer.go:313] Waiting for caches to sync for garbage collector I0608 08:44:45.794213 1 shared_informer.go:320] Caches are synced for resource quota I0608 08:44:45.808390 1 shared_informer.go:320] Caches are synced for garbage collector I0608 08:44:49.532786 1 job_controller.go:604] "enqueueing job" logger="job-controller" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" delay="0s" I0608 08:44:49.560068 1 job_controller.go:604] "enqueueing job" logger="job-controller" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" delay="1s" I0608 08:44:49.567424 1 job_controller.go:604] "enqueueing job" logger="job-controller" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" delay="1s" I0608 08:44:49.569887 1 job_controller.go:604] "enqueueing job" logger="job-controller" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" delay="1s" I0608 08:44:51.093187 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/cilium-operator-646c4c79cc" duration="8.62179ms" I0608 08:44:51.093299 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/cilium-operator-646c4c79cc" duration="52.432µs" I0608 08:44:58.630603 1 node_lifecycle_controller.go:1057] "Controller detected that some Nodes are Ready. Exiting master disruption mode" logger="node-lifecycle-controller" I0608 08:45:00.470939 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="63.692µs" I0608 08:45:00.471089 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-5cd68b5dbf" duration="21.81µs" I0608 08:45:00.478739 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-5cd68b5dbf" duration="56.072µs" I0608 08:45:00.483742 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="77.632µs" I0608 08:45:00.486489 1 job_controller.go:604] "enqueueing job" logger="job-controller" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" delay="1s" I0608 08:45:00.499063 1 job_controller.go:604] "enqueueing job" logger="job-controller" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" delay="1s" I0608 08:45:02.129652 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="96.303µs" I0608 08:45:05.134309 1 job_controller.go:604] "enqueueing job" logger="job-controller" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" delay="1s" I0608 08:45:06.163646 1 job_controller.go:604] "enqueueing job" logger="job-controller" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" delay="1s" I0608 08:45:06.169473 1 job_controller.go:604] "enqueueing job" logger="job-controller" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" delay="1s" I0608 08:45:07.171093 1 job_controller.go:604] "enqueueing job" logger="job-controller" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" delay="1s" I0608 08:45:07.312803 1 job_controller.go:604] "enqueueing job" logger="job-controller" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" delay="1s" I0608 08:45:08.181356 1 job_controller.go:604] "enqueueing job" logger="job-controller" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" delay="1s" I0608 08:45:08.194238 1 job_controller.go:604] "enqueueing job" logger="job-controller" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" delay="1s" I0608 08:45:08.292371 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-8b6d98d9b" duration="19.326611ms" I0608 08:45:08.303241 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-8b6d98d9b" duration="10.819765ms" I0608 08:45:08.303496 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-8b6d98d9b" duration="205.006µs" I0608 08:45:08.311033 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-8b6d98d9b" duration="51.851µs" I0608 08:45:09.153695 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-8b6d98d9b" duration="64.222µs" I0608 08:45:11.639423 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-5cd68b5dbf" duration="73.272µs" I0608 08:45:11.639764 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-5cd68b5dbf" duration="27.631µs" I0608 08:45:11.654614 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-5cd68b5dbf" duration="82.473µs" I0608 08:45:11.667468 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-5cd68b5dbf" duration="69.292µs" I0608 08:45:12.139200 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="8.875957ms" I0608 08:45:12.139337 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="93.582µs" I0608 08:45:13.170849 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-5cd68b5dbf" duration="102.723µs" I0608 08:45:13.197991 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-5cd68b5dbf" duration="12.836115ms" I0608 08:45:13.208965 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-5cd68b5dbf" duration="10.932067ms" I0608 08:45:13.209215 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-5cd68b5dbf" duration="59.822µs" I0608 08:45:13.222790 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="17.034182ms" I0608 08:45:13.240401 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="17.541317ms" I0608 08:45:13.240574 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="90.943µs" I0608 08:45:15.802350 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="ciliumendpoints.cilium.io" I0608 08:45:15.802433 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="ciliumnetworkpolicies.cilium.io" I0608 08:45:15.802475 1 resource_quota_monitor.go:227] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="ciliumnodeconfigs.cilium.io" I0608 08:45:15.802614 1 shared_informer.go:313] Waiting for caches to sync for resource quota I0608 08:45:15.825770 1 shared_informer.go:313] Waiting for caches to sync for garbage collector I0608 08:45:16.903775 1 shared_informer.go:320] Caches are synced for resource quota I0608 08:45:16.926494 1 shared_informer.go:320] Caches are synced for garbage collector I0608 08:45:18.475453 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="69.622µs" I0608 08:45:19.189587 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="100.163µs" I0608 08:45:19.194732 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/coredns-67d79cf957" duration="95.793µs" I0608 08:45:20.170619 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-8b6d98d9b" duration="9.01438ms" I0608 08:45:20.170767 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="envoy-gateway-system/envoy-gateway-8b6d98d9b" duration="104.874µs" I0608 08:45:38.038284 1 job_controller.go:604] "enqueueing job" logger="job-controller" key="envoy-gateway-system/envoy-gateway-gateway-helm-certgen" delay="0s" I0608 08:46:17.793459 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/ceph-csi-rbd-provisioner-846c7587b6" duration="40.269638ms" I0608 08:46:17.870905 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/ceph-csi-rbd-provisioner-846c7587b6" duration="77.377468ms" I0608 08:46:17.871102 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/ceph-csi-rbd-provisioner-846c7587b6" duration="111.373µs" I0608 08:46:17.906676 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/ceph-csi-rbd-provisioner-846c7587b6" duration="127.634µs" I0608 08:46:45.502665 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/ceph-csi-rbd-provisioner-846c7587b6" duration="11.065794ms" I0608 08:46:45.502898 1 replica_set.go:679] "Finished syncing" logger="replicaset-controller" kind="ReplicaSet" key="kube-system/ceph-csi-rbd-provisioner-846c7587b6" duration="84.952µs" I0608 08:50:35.083578 1 reconciler.go:360] "attacherDetacher.AttachVolume started" logger="persistentvolume-attach-detach-controller" volumeName="kubernetes.io/csi/rbd.csi.ceph.com^0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-9640cdb6-f0b1-4c42-a3ca-268c98c0a03a" nodeName="instance" scheduledPods=["default/test-pvc-pod"] I0608 08:50:35.636276 1 operation_generator.go:298] AttachVolume.Attach succeeded for volume "pvc-21baf242-72fa-453b-a42e-85d314a7aba6" (UniqueName: "kubernetes.io/csi/rbd.csi.ceph.com^0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-9640cdb6-f0b1-4c42-a3ca-268c98c0a03a") from node "instance" I0608 08:51:06.008274 1 reconciler.go:275] "attacherDetacher.DetachVolume started" logger="persistentvolume-attach-detach-controller" node="instance" volumeName="kubernetes.io/csi/rbd.csi.ceph.com^0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-9640cdb6-f0b1-4c42-a3ca-268c98c0a03a" I0608 08:51:06.011886 1 operation_generator.go:1516] Verified volume is safe to detach for volume "pvc-21baf242-72fa-453b-a42e-85d314a7aba6" (UniqueName: "kubernetes.io/csi/rbd.csi.ceph.com^0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-9640cdb6-f0b1-4c42-a3ca-268c98c0a03a") on node "instance" I0608 08:51:06.540002 1 operation_generator.go:414] DetachVolume.Detach succeeded for volume "pvc-21baf242-72fa-453b-a42e-85d314a7aba6" (UniqueName: "kubernetes.io/csi/rbd.csi.ceph.com^0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-9640cdb6-f0b1-4c42-a3ca-268c98c0a03a") on node "instance"