This job view page is being replaced by Spyglass soon. Check out the new job view.
Resultsuccess
Tests 0 failed / 6 succeeded
Started2022-09-06 04:39
Elapsed34m29s
Revision
uploadercrier
uploadercrier

No Test Failures!


Show 6 Passed Tests

Show 28 Skipped Tests

Error lines from build-log.txt

... skipping 627 lines ...
certificate.cert-manager.io "selfsigned-cert" deleted
# Create secret for AzureClusterIdentity
./hack/create-identity-secret.sh
make[2]: Entering directory '/home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure'
make[2]: Nothing to be done for 'kubectl'.
make[2]: Leaving directory '/home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure'
Error from server (NotFound): secrets "cluster-identity-secret" not found
secret/cluster-identity-secret created
secret/cluster-identity-secret labeled
# Create customized cloud provider configs
./hack/create-custom-cloud-provider-config.sh
make[2]: Entering directory '/home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure'
make[2]: Nothing to be done for 'kubectl'.
... skipping 137 lines ...
# Wait for the kubeconfig to become available.
timeout --foreground 300 bash -c "while ! /home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure/hack/tools/bin/kubectl-v1.22.4 get secrets | grep capz-b2pot9-kubeconfig; do sleep 1; done"
capz-b2pot9-kubeconfig                 cluster.x-k8s.io/secret   1      1s
# Get kubeconfig and store it locally.
/home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure/hack/tools/bin/kubectl-v1.22.4 get secrets capz-b2pot9-kubeconfig -o json | jq -r .data.value | base64 --decode > ./kubeconfig
timeout --foreground 600 bash -c "while ! /home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure/hack/tools/bin/kubectl-v1.22.4 --kubeconfig=./kubeconfig get nodes | grep control-plane; do sleep 1; done"
error: the server doesn't have a resource type "nodes"
capz-b2pot9-control-plane-xft6z   NotReady   <none>   3s    v1.21.15-rc.0.4+2fef630dd216dd
run "/home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure/hack/tools/bin/kubectl-v1.22.4 --kubeconfig=./kubeconfig ..." to work with the new target cluster
make[1]: Leaving directory '/home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure'
Waiting for 1 control plane machine(s), 2 worker machine(s), and  windows machine(s) to become Ready
node/capz-b2pot9-control-plane-xft6z condition met
node/capz-b2pot9-md-0-r4r48 condition met
... skipping 225 lines ...
Sep  6 04:59:23.273: INFO: PersistentVolumeClaim pvc-stn68 found but phase is Pending instead of Bound.
Sep  6 04:59:25.309: INFO: PersistentVolumeClaim pvc-stn68 found and phase=Bound (22.429720281s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep  6 04:59:25.416: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-pb9jv" in namespace "azurefile-5194" to be "Succeeded or Failed"
Sep  6 04:59:25.449: INFO: Pod "azurefile-volume-tester-pb9jv": Phase="Pending", Reason="", readiness=false. Elapsed: 33.090147ms
Sep  6 04:59:27.484: INFO: Pod "azurefile-volume-tester-pb9jv": Phase="Pending", Reason="", readiness=false. Elapsed: 2.067938995s
Sep  6 04:59:29.522: INFO: Pod "azurefile-volume-tester-pb9jv": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.105657015s
STEP: Saw pod success
Sep  6 04:59:29.522: INFO: Pod "azurefile-volume-tester-pb9jv" satisfied condition "Succeeded or Failed"
Sep  6 04:59:29.522: INFO: deleting Pod "azurefile-5194"/"azurefile-volume-tester-pb9jv"
Sep  6 04:59:29.568: INFO: Pod azurefile-volume-tester-pb9jv has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-pb9jv in namespace azurefile-5194
Sep  6 04:59:29.612: INFO: deleting PVC "azurefile-5194"/"pvc-stn68"
Sep  6 04:59:29.612: INFO: Deleting PersistentVolumeClaim "pvc-stn68"
... skipping 158 lines ...
Sep  6 05:01:28.952: INFO: PersistentVolumeClaim pvc-q2l5m found but phase is Pending instead of Bound.
Sep  6 05:01:30.987: INFO: PersistentVolumeClaim pvc-q2l5m found and phase=Bound (22.420504354s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with an error
Sep  6 05:01:31.088: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-55qmw" in namespace "azurefile-156" to be "Error status code"
Sep  6 05:01:31.121: INFO: Pod "azurefile-volume-tester-55qmw": Phase="Pending", Reason="", readiness=false. Elapsed: 32.69261ms
Sep  6 05:01:33.158: INFO: Pod "azurefile-volume-tester-55qmw": Phase="Running", Reason="", readiness=true. Elapsed: 2.070301423s
Sep  6 05:01:35.196: INFO: Pod "azurefile-volume-tester-55qmw": Phase="Failed", Reason="", readiness=false. Elapsed: 4.107563746s
STEP: Saw pod failure
Sep  6 05:01:35.196: INFO: Pod "azurefile-volume-tester-55qmw" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Sep  6 05:01:35.233: INFO: deleting Pod "azurefile-156"/"azurefile-volume-tester-55qmw"
Sep  6 05:01:35.270: INFO: Pod azurefile-volume-tester-55qmw has the following logs: touch: /mnt/test-1/data: Read-only file system

STEP: Deleting pod azurefile-volume-tester-55qmw in namespace azurefile-156
Sep  6 05:01:35.318: INFO: deleting PVC "azurefile-156"/"pvc-q2l5m"
... skipping 181 lines ...
Sep  6 05:03:41.541: INFO: PersistentVolumeClaim pvc-qwr2b found but phase is Pending instead of Bound.
Sep  6 05:03:43.575: INFO: PersistentVolumeClaim pvc-qwr2b found and phase=Bound (2.067425454s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep  6 05:03:43.683: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-hfh7g" in namespace "azurefile-2546" to be "Succeeded or Failed"
Sep  6 05:03:43.716: INFO: Pod "azurefile-volume-tester-hfh7g": Phase="Pending", Reason="", readiness=false. Elapsed: 32.903908ms
Sep  6 05:03:45.752: INFO: Pod "azurefile-volume-tester-hfh7g": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.069237093s
STEP: Saw pod success
Sep  6 05:03:45.752: INFO: Pod "azurefile-volume-tester-hfh7g" satisfied condition "Succeeded or Failed"
STEP: resizing the pvc
STEP: sleep 30s waiting for resize complete
STEP: checking the resizing result
STEP: checking the resizing PV result
STEP: checking the resizing azurefile result
Sep  6 05:04:16.685: INFO: deleting Pod "azurefile-2546"/"azurefile-volume-tester-hfh7g"
... skipping 734 lines ...
I0906 04:53:50.265669       1 tlsconfig.go:200] loaded serving cert ["Generated self signed cert"]: "localhost@1662440029" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer="localhost-ca@1662440029" (2022-09-06 03:53:48 +0000 UTC to 2023-09-06 03:53:48 +0000 UTC (now=2022-09-06 04:53:50.265657672 +0000 UTC))
I0906 04:53:50.266203       1 named_certificates.go:53] loaded SNI cert [0/"self-signed loopback"]: "apiserver-loopback-client@1662440030" [serving] validServingFor=[apiserver-loopback-client] issuer="apiserver-loopback-client-ca@1662440029" (2022-09-06 03:53:49 +0000 UTC to 2023-09-06 03:53:49 +0000 UTC (now=2022-09-06 04:53:50.266182666 +0000 UTC))
I0906 04:53:50.266381       1 secure_serving.go:202] Serving securely on 127.0.0.1:10257
I0906 04:53:50.265693       1 dynamic_cafile_content.go:167] Starting client-ca-bundle::/etc/kubernetes/pki/ca.crt
I0906 04:53:50.270958       1 tlsconfig.go:240] Starting DynamicServingCertificateController
I0906 04:53:50.271244       1 leaderelection.go:243] attempting to acquire leader lease kube-system/kube-controller-manager...
E0906 04:53:52.648348       1 leaderelection.go:325] 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"
I0906 04:53:52.648375       1 leaderelection.go:248] failed to acquire lease kube-system/kube-controller-manager
I0906 04:53:56.819177       1 leaderelection.go:253] successfully acquired lease kube-system/kube-controller-manager
I0906 04:53:56.819566       1 event.go:291] "Event occurred" object="kube-system/kube-controller-manager" kind="Lease" apiVersion="coordination.k8s.io/v1" type="Normal" reason="LeaderElection" message="capz-b2pot9-control-plane-xft6z_3e132a73-7cf0-4d5b-bdcb-8094a6a36719 became leader"
I0906 04:53:57.172267       1 request.go:600] Waited for 94.930077ms due to client-side throttling, not priority and fairness, request: GET:https://10.0.0.4:6443/apis/batch/v1beta1?timeout=32s
I0906 04:53:57.221877       1 request.go:600] Waited for 144.529099ms due to client-side throttling, not priority and fairness, request: GET:https://10.0.0.4:6443/apis/certificates.k8s.io/v1?timeout=32s
I0906 04:53:57.272052       1 request.go:600] Waited for 194.639032ms due to client-side throttling, not priority and fairness, request: GET:https://10.0.0.4:6443/apis/policy/v1beta1?timeout=32s
I0906 04:53:57.321736       1 request.go:600] Waited for 244.254755ms due to client-side throttling, not priority and fairness, request: GET:https://10.0.0.4:6443/apis/networking.k8s.io/v1?timeout=32s
... skipping 39 lines ...
I0906 04:53:57.626367       1 reflector.go:219] Starting reflector *v1.ServiceAccount (23h49m48.767952461s) from k8s.io/client-go/informers/factory.go:134
I0906 04:53:57.626399       1 reflector.go:255] Listing and watching *v1.ServiceAccount from k8s.io/client-go/informers/factory.go:134
I0906 04:53:57.626855       1 reflector.go:219] Starting reflector *v1.Node (23h49m48.767952461s) from k8s.io/client-go/informers/factory.go:134
I0906 04:53:57.626987       1 reflector.go:255] Listing and watching *v1.Node from k8s.io/client-go/informers/factory.go:134
I0906 04:53:57.630339       1 reflector.go:219] Starting reflector *v1.Secret (23h49m48.767952461s) from k8s.io/client-go/informers/factory.go:134
I0906 04:53:57.630361       1 reflector.go:255] Listing and watching *v1.Secret from k8s.io/client-go/informers/factory.go:134
W0906 04:53:57.662042       1 azure_config.go:52] Failed to get cloud-config from secret: failed to get secret azure-cloud-provider: secrets "azure-cloud-provider" is forbidden: User "system:serviceaccount:kube-system:azure-cloud-provider" cannot get resource "secrets" in API group "" in the namespace "kube-system", skip initializing from secret
I0906 04:53:57.662224       1 controllermanager.go:559] Starting "csrsigning"
I0906 04:53:57.667592       1 dynamic_serving_content.go:111] Loaded a new cert/key pair for "csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key"
I0906 04:53:57.668143       1 dynamic_serving_content.go:111] Loaded a new cert/key pair for "csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key"
I0906 04:53:57.668224       1 certificate_controller.go:118] Starting certificate controller "csrsigning-kubelet-serving"
I0906 04:53:57.668311       1 shared_informer.go:240] Waiting for caches to sync for certificate-csrsigning-kubelet-serving
I0906 04:53:57.668343       1 dynamic_serving_content.go:130] Starting csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key
... skipping 29 lines ...
I0906 04:53:57.700497       1 plugins.go:639] Loaded volume plugin "kubernetes.io/portworx-volume"
I0906 04:53:57.700544       1 plugins.go:639] Loaded volume plugin "kubernetes.io/scaleio"
I0906 04:53:57.700562       1 plugins.go:639] Loaded volume plugin "kubernetes.io/storageos"
I0906 04:53:57.700574       1 plugins.go:639] Loaded volume plugin "kubernetes.io/fc"
I0906 04:53:57.700618       1 plugins.go:639] Loaded volume plugin "kubernetes.io/iscsi"
I0906 04:53:57.700636       1 plugins.go:639] Loaded volume plugin "kubernetes.io/rbd"
I0906 04:53:57.700730       1 csi_plugin.go:256] Cast from VolumeHost to KubeletVolumeHost failed. Skipping CSINode initialization, not running on kubelet
I0906 04:53:57.700744       1 plugins.go:639] Loaded volume plugin "kubernetes.io/csi"
I0906 04:53:57.700886       1 controllermanager.go:574] Started "attachdetach"
I0906 04:53:57.700902       1 controllermanager.go:559] Starting "endpoint"
I0906 04:53:57.701068       1 attach_detach_controller.go:328] Starting attach detach controller
I0906 04:53:57.701220       1 shared_informer.go:240] Waiting for caches to sync for attach detach
I0906 04:53:57.706051       1 controllermanager.go:574] Started "endpoint"
... skipping 43 lines ...
I0906 04:53:58.332129       1 plugins.go:639] Loaded volume plugin "kubernetes.io/azure-file"
I0906 04:53:58.332139       1 plugins.go:639] Loaded volume plugin "kubernetes.io/flocker"
I0906 04:53:58.332161       1 plugins.go:639] Loaded volume plugin "kubernetes.io/portworx-volume"
I0906 04:53:58.332175       1 plugins.go:639] Loaded volume plugin "kubernetes.io/scaleio"
I0906 04:53:58.332236       1 plugins.go:639] Loaded volume plugin "kubernetes.io/local-volume"
I0906 04:53:58.332346       1 plugins.go:639] Loaded volume plugin "kubernetes.io/storageos"
I0906 04:53:58.332387       1 csi_plugin.go:256] Cast from VolumeHost to KubeletVolumeHost failed. Skipping CSINode initialization, not running on kubelet
I0906 04:53:58.332527       1 plugins.go:639] Loaded volume plugin "kubernetes.io/csi"
I0906 04:53:58.332690       1 controllermanager.go:574] Started "persistentvolume-binder"
I0906 04:53:58.332795       1 controllermanager.go:559] Starting "root-ca-cert-publisher"
I0906 04:53:58.332996       1 pv_controller_base.go:308] Starting persistent volume controller
I0906 04:53:58.333011       1 shared_informer.go:240] Waiting for caches to sync for persistent volume
I0906 04:53:58.480612       1 controllermanager.go:574] Started "root-ca-cert-publisher"
... skipping 7 lines ...
I0906 04:53:58.830331       1 controllermanager.go:574] Started "bootstrapsigner"
I0906 04:53:58.830363       1 controllermanager.go:559] Starting "nodelifecycle"
I0906 04:53:58.830561       1 shared_informer.go:240] Waiting for caches to sync for bootstrap_signer
I0906 04:53:58.867782       1 controller.go:693] Ignoring node capz-b2pot9-control-plane-xft6z with Ready condition status False
I0906 04:53:58.867824       1 controller.go:272] Triggering nodeSync
I0906 04:53:58.867874       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-b2pot9-control-plane-xft6z"
W0906 04:53:58.867900       1 actual_state_of_world.go:539] Failed to update statusUpdateNeeded field in actual state of world: Failed to set statusUpdateNeeded to needed true, because nodeName="capz-b2pot9-control-plane-xft6z" does not exist
I0906 04:53:58.887116       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-b2pot9-control-plane-xft6z"
I0906 04:53:58.981448       1 node_lifecycle_controller.go:377] Sending events to api server.
I0906 04:53:58.982530       1 taint_manager.go:163] "Sending events to api server"
I0906 04:53:58.982652       1 node_lifecycle_controller.go:505] Controller will reconcile labels.
I0906 04:53:58.982693       1 controllermanager.go:574] Started "nodelifecycle"
I0906 04:53:58.982727       1 controllermanager.go:559] Starting "endpointslicemirroring"
... skipping 487 lines ...
I0906 04:54:02.798900       1 controller_utils.go:223] Setting expectations &controller.ControlleeExpectations{add:2, del:0, key:"kube-system/coredns-558bd4d5db", timestamp:time.Time{wall:0xc0bdd27aaf9e3185, ext:14228398581, loc:(*time.Location)(0x731ea80)}}
I0906 04:54:02.798950       1 replica_set.go:559] "Too few replicas" replicaSet="kube-system/coredns-558bd4d5db" need=2 creating=2
I0906 04:54:02.799253       1 deployment_controller.go:215] "ReplicaSet added" replicaSet="kube-system/coredns-558bd4d5db"
I0906 04:54:02.805489       1 deployment_controller.go:176] "Updating deployment" deployment="kube-system/coredns"
I0906 04:54:02.806053       1 deployment_util.go:808] Deployment "coredns" timed out (false) [last progress check: 2022-09-06 04:54:02.79814172 +0000 UTC m=+14.227643784 - now: 2022-09-06 04:54:02.806044084 +0000 UTC m=+14.235546148]
I0906 04:54:02.814020       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/coredns" duration="27.429075ms"
I0906 04:54:02.814057       1 deployment_controller.go:490] "Error syncing deployment" deployment="kube-system/coredns" err="Operation cannot be fulfilled on deployments.apps \"coredns\": the object has been modified; please apply your changes to the latest version and try again"
I0906 04:54:02.814096       1 deployment_controller.go:576] "Started syncing deployment" deployment="kube-system/coredns" startTime="2022-09-06 04:54:02.814077647 +0000 UTC m=+14.243579811"
I0906 04:54:02.814578       1 deployment_util.go:808] Deployment "coredns" timed out (false) [last progress check: 2022-09-06 04:54:02 +0000 UTC - now: 2022-09-06 04:54:02.814574145 +0000 UTC m=+14.244076309]
I0906 04:54:02.814836       1 shared_informer.go:270] caches populated
I0906 04:54:02.814846       1 shared_informer.go:247] Caches are synced for garbage collector 
I0906 04:54:02.814854       1 garbagecollector.go:151] Garbage collector: all resource monitors have synced. Proceeding to collect garbage
I0906 04:54:02.819151       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/coredns" duration="5.055877ms"
... skipping 5 lines ...
I0906 04:54:02.844179       1 shared_informer.go:247] Caches are synced for garbage collector 
I0906 04:54:02.844189       1 garbagecollector.go:254] synced garbage collector
I0906 04:54:02.844352       1 controller_utils.go:591] Controller coredns-558bd4d5db created pod coredns-558bd4d5db-6hxpv
I0906 04:54:02.844429       1 replica_set.go:376] Pod coredns-558bd4d5db-6hxpv created: &v1.Pod{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"coredns-558bd4d5db-6hxpv", GenerateName:"coredns-558bd4d5db-", Namespace:"kube-system", SelfLink:"", UID:"49e51eca-2e38-41a9-a007-de856e86b955", ResourceVersion:"418", Generation:0, CreationTimestamp:v1.Time{Time:time.Time{wall:0x0, ext:63798036842, loc:(*time.Location)(0x731ea80)}}, DeletionTimestamp:(*v1.Time)(nil), DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string{"k8s-app":"kube-dns", "pod-template-hash":"558bd4d5db"}, Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference{v1.OwnerReference{APIVersion:"apps/v1", Kind:"ReplicaSet", Name:"coredns-558bd4d5db", UID:"fc037d72-8f68-4c2c-a7b6-8ddd69a881db", Controller:(*bool)(0xc0016b056f), BlockOwnerDeletion:(*bool)(0xc0016b0590)}}, Finalizers:[]string(nil), ClusterName:"", ManagedFields:[]v1.ManagedFieldsEntry{v1.ManagedFieldsEntry{Manager:"kube-controller-manager", Operation:"Update", APIVersion:"v1", Time:(*v1.Time)(0xc001544ba0), FieldsType:"FieldsV1", FieldsV1:(*v1.FieldsV1)(0xc001544bb8)}}}, Spec:v1.PodSpec{Volumes:[]v1.Volume{v1.Volume{Name:"config-volume", VolumeSource:v1.VolumeSource{HostPath:(*v1.HostPathVolumeSource)(nil), EmptyDir:(*v1.EmptyDirVolumeSource)(nil), GCEPersistentDisk:(*v1.GCEPersistentDiskVolumeSource)(nil), AWSElasticBlockStore:(*v1.AWSElasticBlockStoreVolumeSource)(nil), GitRepo:(*v1.GitRepoVolumeSource)(nil), Secret:(*v1.SecretVolumeSource)(nil), NFS:(*v1.NFSVolumeSource)(nil), ISCSI:(*v1.ISCSIVolumeSource)(nil), Glusterfs:(*v1.GlusterfsVolumeSource)(nil), PersistentVolumeClaim:(*v1.PersistentVolumeClaimVolumeSource)(nil), RBD:(*v1.RBDVolumeSource)(nil), FlexVolume:(*v1.FlexVolumeSource)(nil), Cinder:(*v1.CinderVolumeSource)(nil), CephFS:(*v1.CephFSVolumeSource)(nil), Flocker:(*v1.FlockerVolumeSource)(nil), DownwardAPI:(*v1.DownwardAPIVolumeSource)(nil), FC:(*v1.FCVolumeSource)(nil), AzureFile:(*v1.AzureFileVolumeSource)(nil), ConfigMap:(*v1.ConfigMapVolumeSource)(0xc0019ce000), VsphereVolume:(*v1.VsphereVirtualDiskVolumeSource)(nil), Quobyte:(*v1.QuobyteVolumeSource)(nil), AzureDisk:(*v1.AzureDiskVolumeSource)(nil), PhotonPersistentDisk:(*v1.PhotonPersistentDiskVolumeSource)(nil), Projected:(*v1.ProjectedVolumeSource)(nil), PortworxVolume:(*v1.PortworxVolumeSource)(nil), ScaleIO:(*v1.ScaleIOVolumeSource)(nil), StorageOS:(*v1.StorageOSVolumeSource)(nil), CSI:(*v1.CSIVolumeSource)(nil), Ephemeral:(*v1.EphemeralVolumeSource)(nil)}}, v1.Volume{Name:"kube-api-access-6b78x", VolumeSource:v1.VolumeSource{HostPath:(*v1.HostPathVolumeSource)(nil), EmptyDir:(*v1.EmptyDirVolumeSource)(nil), GCEPersistentDisk:(*v1.GCEPersistentDiskVolumeSource)(nil), AWSElasticBlockStore:(*v1.AWSElasticBlockStoreVolumeSource)(nil), GitRepo:(*v1.GitRepoVolumeSource)(nil), Secret:(*v1.SecretVolumeSource)(nil), NFS:(*v1.NFSVolumeSource)(nil), ISCSI:(*v1.ISCSIVolumeSource)(nil), Glusterfs:(*v1.GlusterfsVolumeSource)(nil), PersistentVolumeClaim:(*v1.PersistentVolumeClaimVolumeSource)(nil), RBD:(*v1.RBDVolumeSource)(nil), FlexVolume:(*v1.FlexVolumeSource)(nil), Cinder:(*v1.CinderVolumeSource)(nil), CephFS:(*v1.CephFSVolumeSource)(nil), Flocker:(*v1.FlockerVolumeSource)(nil), DownwardAPI:(*v1.DownwardAPIVolumeSource)(nil), FC:(*v1.FCVolumeSource)(nil), AzureFile:(*v1.AzureFileVolumeSource)(nil), ConfigMap:(*v1.ConfigMapVolumeSource)(nil), VsphereVolume:(*v1.VsphereVirtualDiskVolumeSource)(nil), Quobyte:(*v1.QuobyteVolumeSource)(nil), AzureDisk:(*v1.AzureDiskVolumeSource)(nil), PhotonPersistentDisk:(*v1.PhotonPersistentDiskVolumeSource)(nil), Projected:(*v1.ProjectedVolumeSource)(0xc00129f8e0), PortworxVolume:(*v1.PortworxVolumeSource)(nil), ScaleIO:(*v1.ScaleIOVolumeSource)(nil), StorageOS:(*v1.StorageOSVolumeSource)(nil), CSI:(*v1.CSIVolumeSource)(nil), Ephemeral:(*v1.EphemeralVolumeSource)(nil)}}}, InitContainers:[]v1.Container(nil), Containers:[]v1.Container{v1.Container{Name:"coredns", Image:"k8s.gcr.io/coredns/coredns:v1.8.0", Command:[]string(nil), Args:[]string{"-conf", "/etc/coredns/Corefile"}, WorkingDir:"", Ports:[]v1.ContainerPort{v1.ContainerPort{Name:"dns", HostPort:0, ContainerPort:53, Protocol:"UDP", HostIP:""}, v1.ContainerPort{Name:"dns-tcp", HostPort:0, ContainerPort:53, Protocol:"TCP", HostIP:""}, v1.ContainerPort{Name:"metrics", HostPort:0, ContainerPort:9153, Protocol:"TCP", HostIP:""}}, EnvFrom:[]v1.EnvFromSource(nil), Env:[]v1.EnvVar(nil), Resources:v1.ResourceRequirements{Limits:v1.ResourceList{"memory":resource.Quantity{i:resource.int64Amount{value:178257920, scale:0}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"170Mi", Format:"BinarySI"}}, Requests:v1.ResourceList{"cpu":resource.Quantity{i:resource.int64Amount{value:100, scale:-3}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"100m", Format:"DecimalSI"}, "memory":resource.Quantity{i:resource.int64Amount{value:73400320, scale:0}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"70Mi", Format:"BinarySI"}}}, VolumeMounts:[]v1.VolumeMount{v1.VolumeMount{Name:"config-volume", ReadOnly:true, MountPath:"/etc/coredns", SubPath:"", MountPropagation:(*v1.MountPropagationMode)(nil), SubPathExpr:""}, v1.VolumeMount{Name:"kube-api-access-6b78x", ReadOnly:true, MountPath:"/var/run/secrets/kubernetes.io/serviceaccount", SubPath:"", MountPropagation:(*v1.MountPropagationMode)(nil), SubPathExpr:""}}, VolumeDevices:[]v1.VolumeDevice(nil), LivenessProbe:(*v1.Probe)(0xc0019ce180), ReadinessProbe:(*v1.Probe)(0xc0019ce1c0), StartupProbe:(*v1.Probe)(nil), Lifecycle:(*v1.Lifecycle)(nil), TerminationMessagePath:"/dev/termination-log", TerminationMessagePolicy:"File", ImagePullPolicy:"IfNotPresent", SecurityContext:(*v1.SecurityContext)(0xc001960f60), Stdin:false, StdinOnce:false, TTY:false}}, EphemeralContainers:[]v1.EphemeralContainer(nil), RestartPolicy:"Always", TerminationGracePeriodSeconds:(*int64)(0xc0016b06f0), ActiveDeadlineSeconds:(*int64)(nil), DNSPolicy:"Default", NodeSelector:map[string]string{"kubernetes.io/os":"linux"}, ServiceAccountName:"coredns", DeprecatedServiceAccount:"coredns", AutomountServiceAccountToken:(*bool)(nil), NodeName:"", HostNetwork:false, HostPID:false, HostIPC:false, ShareProcessNamespace:(*bool)(nil), SecurityContext:(*v1.PodSecurityContext)(0xc0002559d0), ImagePullSecrets:[]v1.LocalObjectReference(nil), Hostname:"", Subdomain:"", Affinity:(*v1.Affinity)(nil), SchedulerName:"default-scheduler", Tolerations:[]v1.Toleration{v1.Toleration{Key:"CriticalAddonsOnly", Operator:"Exists", Value:"", Effect:"", TolerationSeconds:(*int64)(nil)}, v1.Toleration{Key:"node-role.kubernetes.io/master", Operator:"", Value:"", Effect:"NoSchedule", TolerationSeconds:(*int64)(nil)}, v1.Toleration{Key:"node-role.kubernetes.io/control-plane", Operator:"", Value:"", Effect:"NoSchedule", TolerationSeconds:(*int64)(nil)}, v1.Toleration{Key:"node.kubernetes.io/not-ready", Operator:"Exists", Value:"", Effect:"NoExecute", TolerationSeconds:(*int64)(0xc0016b0760)}, v1.Toleration{Key:"node.kubernetes.io/unreachable", Operator:"Exists", Value:"", Effect:"NoExecute", TolerationSeconds:(*int64)(0xc0016b0780)}}, HostAliases:[]v1.HostAlias(nil), PriorityClassName:"system-cluster-critical", Priority:(*int32)(0xc0016b0788), DNSConfig:(*v1.PodDNSConfig)(nil), ReadinessGates:[]v1.PodReadinessGate(nil), RuntimeClassName:(*string)(nil), EnableServiceLinks:(*bool)(0xc0016b078c), PreemptionPolicy:(*v1.PreemptionPolicy)(0xc0019b2ce0), Overhead:v1.ResourceList(nil), TopologySpreadConstraints:[]v1.TopologySpreadConstraint(nil), SetHostnameAsFQDN:(*bool)(nil)}, Status:v1.PodStatus{Phase:"Pending", Conditions:[]v1.PodCondition(nil), Message:"", Reason:"", NominatedNodeName:"", HostIP:"", PodIP:"", PodIPs:[]v1.PodIP(nil), StartTime:(*v1.Time)(nil), InitContainerStatuses:[]v1.ContainerStatus(nil), ContainerStatuses:[]v1.ContainerStatus(nil), QOSClass:"Burstable", EphemeralContainerStatuses:[]v1.ContainerStatus(nil)}}.
I0906 04:54:02.844731       1 controller_utils.go:240] Lowered expectations &controller.ControlleeExpectations{add:1, del:0, key:"kube-system/coredns-558bd4d5db", timestamp:time.Time{wall:0xc0bdd27aaf9e3185, ext:14228398581, loc:(*time.Location)(0x731ea80)}}
I0906 04:54:02.844713       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/coredns" duration="25.501683ms"
I0906 04:54:02.844765       1 deployment_controller.go:490] "Error syncing deployment" deployment="kube-system/coredns" err="Operation cannot be fulfilled on deployments.apps \"coredns\": the object has been modified; please apply your changes to the latest version and try again"
I0906 04:54:02.844797       1 deployment_controller.go:576] "Started syncing deployment" deployment="kube-system/coredns" startTime="2022-09-06 04:54:02.844779107 +0000 UTC m=+14.274281171"
I0906 04:54:02.844920       1 disruption.go:415] addPod called on pod "coredns-558bd4d5db-6hxpv"
I0906 04:54:02.844940       1 disruption.go:490] No PodDisruptionBudgets found for pod coredns-558bd4d5db-6hxpv, PodDisruptionBudget controller will avoid syncing.
I0906 04:54:02.844947       1 disruption.go:418] No matching pdb for pod "coredns-558bd4d5db-6hxpv"
I0906 04:54:02.844987       1 taint_manager.go:400] "Noticed pod update" pod="kube-system/coredns-558bd4d5db-6hxpv"
I0906 04:54:02.845044       1 pvc_protection_controller.go:402] "Enqueuing PVCs for Pod" pod="kube-system/coredns-558bd4d5db-6hxpv" podUID=49e51eca-2e38-41a9-a007-de856e86b955
... skipping 52 lines ...
I0906 04:54:02.956367       1 endpointslicemirroring_controller.go:273] syncEndpoints("kube-system/kube-dns")
I0906 04:54:02.956385       1 endpointslicemirroring_controller.go:308] kube-system/kube-dns Service now has selector, cleaning up any mirrored EndpointSlices
I0906 04:54:02.956403       1 endpointslicemirroring_controller.go:270] Finished syncing EndpointSlices for "kube-system/kube-dns" Endpoints. (53.3µs)
I0906 04:54:03.000033       1 request.go:600] Waited for 99.690644ms due to client-side throttling, not priority and fairness, request: POST:https://10.0.0.4:6443/api/v1/namespaces/kube-system/serviceaccounts/endpointslice-controller/token
I0906 04:54:03.026865       1 request.go:600] Waited for 110.02021ms due to client-side throttling, not priority and fairness, request: GET:https://10.0.0.4:6443/api/v1/namespaces/kube-system/serviceaccounts/daemon-set-controller
I0906 04:54:03.036700       1 endpoints_controller.go:381] Finished syncing service "kube-system/kube-dns" endpoints. (80.749949ms)
I0906 04:54:03.036759       1 endpoints_controller.go:368] "Error syncing endpoints, retrying" service="kube-system/kube-dns" err="endpoints \"kube-dns\" already exists"
I0906 04:54:03.036845       1 event.go:291] "Event occurred" object="kube-dns" kind="Endpoints" apiVersion="v1" type="Warning" reason="FailedToCreateEndpoint" message="Failed to create endpoint for service kube-system/kube-dns: endpoints \"kube-dns\" already exists"
I0906 04:54:03.037443       1 endpointslice_controller.go:318] Finished syncing service "kube-system/kube-dns" endpoint slices. (212.522747ms)
I0906 04:54:03.043830       1 endpoints_controller.go:381] Finished syncing service "kube-system/kube-dns" endpoints. (57.2µs)
I0906 04:54:03.047115       1 endpointslice_controller.go:318] Finished syncing service "kube-system/kube-dns" endpoint slices. (9.628661ms)
I0906 04:54:03.106981       1 request.go:600] Waited for 72.919106ms due to client-side throttling, not priority and fairness, request: POST:https://10.0.0.4:6443/api/v1/namespaces/kube-system/serviceaccounts/daemon-set-controller/token
I0906 04:54:03.201154       1 daemon_controller.go:395] ControllerRevision kube-proxy-76c9478db6 added.
I0906 04:54:03.210282       1 controller_utils.go:206] Controller kube-system/kube-proxy either never recorded expectations, or the ttl expired.
... skipping 241 lines ...
I0906 04:54:20.066030       1 taint_manager.go:400] "Noticed pod update" pod="kube-system/metrics-server-8c95fb79b-zcqwd"
I0906 04:54:20.066374       1 pvc_protection_controller.go:402] "Enqueuing PVCs for Pod" pod="kube-system/metrics-server-8c95fb79b-zcqwd" podUID=02b5b5b1-24b3-40ac-831c-de9f2fd48c5e
I0906 04:54:20.066449       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/metrics-server" duration="31.261728ms"
I0906 04:54:20.067372       1 event.go:291] "Event occurred" object="kube-system/metrics-server-8c95fb79b" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: metrics-server-8c95fb79b-zcqwd"
I0906 04:54:20.067905       1 disruption.go:490] No PodDisruptionBudgets found for pod metrics-server-8c95fb79b-zcqwd, PodDisruptionBudget controller will avoid syncing.
I0906 04:54:20.069457       1 disruption.go:418] No matching pdb for pod "metrics-server-8c95fb79b-zcqwd"
I0906 04:54:20.069603       1 deployment_controller.go:490] "Error syncing deployment" deployment="kube-system/metrics-server" err="Operation cannot be fulfilled on deployments.apps \"metrics-server\": the object has been modified; please apply your changes to the latest version and try again"
I0906 04:54:20.069746       1 deployment_controller.go:576] "Started syncing deployment" deployment="kube-system/metrics-server" startTime="2022-09-06 04:54:20.069722856 +0000 UTC m=+31.499224920"
I0906 04:54:20.070423       1 deployment_util.go:808] Deployment "metrics-server" timed out (false) [last progress check: 2022-09-06 04:54:20 +0000 UTC - now: 2022-09-06 04:54:20.070416008 +0000 UTC m=+31.499918072]
I0906 04:54:20.080958       1 replica_set.go:439] Pod metrics-server-8c95fb79b-zcqwd updated, objectMeta {Name:metrics-server-8c95fb79b-zcqwd GenerateName:metrics-server-8c95fb79b- Namespace:kube-system SelfLink: UID:02b5b5b1-24b3-40ac-831c-de9f2fd48c5e ResourceVersion:543 Generation:0 CreationTimestamp:2022-09-06 04:54:20 +0000 UTC DeletionTimestamp:<nil> DeletionGracePeriodSeconds:<nil> Labels:map[k8s-app:metrics-server pod-template-hash:8c95fb79b] Annotations:map[] OwnerReferences:[{APIVersion:apps/v1 Kind:ReplicaSet Name:metrics-server-8c95fb79b UID:5d5ee538-2c26-4ca2-8535-156565ac38d3 Controller:0xc00178a027 BlockOwnerDeletion:0xc00178a028}] Finalizers:[] ClusterName: ManagedFields:[{Manager:kube-controller-manager Operation:Update APIVersion:v1 Time:2022-09-06 04:54:20 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:metadata":{"f:generateName":{},"f:labels":{".":{},"f:k8s-app":{},"f:pod-template-hash":{}},"f:ownerReferences":{".":{},"k:{\"uid\":\"5d5ee538-2c26-4ca2-8535-156565ac38d3\"}":{".":{},"f:apiVersion":{},"f:blockOwnerDeletion":{},"f:controller":{},"f:kind":{},"f:name":{},"f:uid":{}}}},"f:spec":{"f:containers":{"k:{\"name\":\"metrics-server\"}":{".":{},"f:args":{},"f:image":{},"f:imagePullPolicy":{},"f:livenessProbe":{".":{},"f:failureThreshold":{},"f:httpGet":{".":{},"f:path":{},"f:port":{},"f:scheme":{}},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:name":{},"f:ports":{".":{},"k:{\"containerPort\":4443,\"protocol\":\"TCP\"}":{".":{},"f:containerPort":{},"f:name":{},"f:protocol":{}}},"f:readinessProbe":{".":{},"f:failureThreshold":{},"f:httpGet":{".":{},"f:path":{},"f:port":{},"f:scheme":{}},"f:initialDelaySeconds":{},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:resources":{".":{},"f:requests":{".":{},"f:cpu":{},"f:memory":{}}},"f:securityContext":{".":{},"f:readOnlyRootFilesystem":{},"f:runAsNonRoot":{},"f:runAsUser":{}},"f:terminationMessagePath":{},"f:terminationMessagePolicy":{},"f:volumeMounts":{".":{},"k:{\"mountPath\":\"/tmp\"}":{".":{},"f:mountPath":{},"f:name":{}}}}},"f:dnsPolicy":{},"f:enableServiceLinks":{},"f:nodeSelector":{".":{},"f:kubernetes.io/os":{}},"f:priorityClassName":{},"f:restartPolicy":{},"f:schedulerName":{},"f:securityContext":{},"f:serviceAccount":{},"f:serviceAccountName":{},"f:terminationGracePeriodSeconds":{},"f:tolerations":{},"f:volumes":{".":{},"k:{\"name\":\"tmp-dir\"}":{".":{},"f:emptyDir":{},"f:name":{}}}}}}]} -> {Name:metrics-server-8c95fb79b-zcqwd GenerateName:metrics-server-8c95fb79b- Namespace:kube-system SelfLink: UID:02b5b5b1-24b3-40ac-831c-de9f2fd48c5e ResourceVersion:548 Generation:0 CreationTimestamp:2022-09-06 04:54:20 +0000 UTC DeletionTimestamp:<nil> DeletionGracePeriodSeconds:<nil> Labels:map[k8s-app:metrics-server pod-template-hash:8c95fb79b] Annotations:map[] OwnerReferences:[{APIVersion:apps/v1 Kind:ReplicaSet Name:metrics-server-8c95fb79b UID:5d5ee538-2c26-4ca2-8535-156565ac38d3 Controller:0xc0017a4b80 BlockOwnerDeletion:0xc0017a4b81}] Finalizers:[] ClusterName: ManagedFields:[{Manager:kube-controller-manager Operation:Update APIVersion:v1 Time:2022-09-06 04:54:20 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:metadata":{"f:generateName":{},"f:labels":{".":{},"f:k8s-app":{},"f:pod-template-hash":{}},"f:ownerReferences":{".":{},"k:{\"uid\":\"5d5ee538-2c26-4ca2-8535-156565ac38d3\"}":{".":{},"f:apiVersion":{},"f:blockOwnerDeletion":{},"f:controller":{},"f:kind":{},"f:name":{},"f:uid":{}}}},"f:spec":{"f:containers":{"k:{\"name\":\"metrics-server\"}":{".":{},"f:args":{},"f:image":{},"f:imagePullPolicy":{},"f:livenessProbe":{".":{},"f:failureThreshold":{},"f:httpGet":{".":{},"f:path":{},"f:port":{},"f:scheme":{}},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:name":{},"f:ports":{".":{},"k:{\"containerPort\":4443,\"protocol\":\"TCP\"}":{".":{},"f:containerPort":{},"f:name":{},"f:protocol":{}}},"f:readinessProbe":{".":{},"f:failureThreshold":{},"f:httpGet":{".":{},"f:path":{},"f:port":{},"f:scheme":{}},"f:initialDelaySeconds":{},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:resources":{".":{},"f:requests":{".":{},"f:cpu":{},"f:memory":{}}},"f:securityContext":{".":{},"f:readOnlyRootFilesystem":{},"f:runAsNonRoot":{},"f:runAsUser":{}},"f:terminationMessagePath":{},"f:terminationMessagePolicy":{},"f:volumeMounts":{".":{},"k:{\"mountPath\":\"/tmp\"}":{".":{},"f:mountPath":{},"f:name":{}}}}},"f:dnsPolicy":{},"f:enableServiceLinks":{},"f:nodeSelector":{".":{},"f:kubernetes.io/os":{}},"f:priorityClassName":{},"f:restartPolicy":{},"f:schedulerName":{},"f:securityContext":{},"f:serviceAccount":{},"f:serviceAccountName":{},"f:terminationGracePeriodSeconds":{},"f:tolerations":{},"f:volumes":{".":{},"k:{\"name\":\"tmp-dir\"}":{".":{},"f:emptyDir":{},"f:name":{}}}}}} {Manager:kube-scheduler Operation:Update APIVersion:v1 Time:2022-09-06 04:54:20 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:status":{"f:conditions":{".":{},"k:{\"type\":\"PodScheduled\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}}}}}]}.
I0906 04:54:20.081441       1 disruption.go:427] updatePod called on pod "metrics-server-8c95fb79b-zcqwd"
I0906 04:54:20.081856       1 disruption.go:490] No PodDisruptionBudgets found for pod metrics-server-8c95fb79b-zcqwd, PodDisruptionBudget controller will avoid syncing.
I0906 04:54:20.082013       1 disruption.go:430] No matching pdb for pod "metrics-server-8c95fb79b-zcqwd"
... skipping 48 lines ...
I0906 04:54:21.661038       1 replica_set.go:439] Pod calico-kube-controllers-969cf87c4-q6s7d updated, objectMeta {Name:calico-kube-controllers-969cf87c4-q6s7d GenerateName:calico-kube-controllers-969cf87c4- Namespace:kube-system SelfLink: UID:0ca5a608-5f0e-4e96-956b-066d32c9260c ResourceVersion:613 Generation:0 CreationTimestamp:2022-09-06 04:54:21 +0000 UTC DeletionTimestamp:<nil> DeletionGracePeriodSeconds:<nil> Labels:map[k8s-app:calico-kube-controllers pod-template-hash:969cf87c4] Annotations:map[] OwnerReferences:[{APIVersion:apps/v1 Kind:ReplicaSet Name:calico-kube-controllers-969cf87c4 UID:390fcd8b-a8cd-44f1-aa53-9acc42974cd1 Controller:0xc0006d5ba7 BlockOwnerDeletion:0xc0006d5ba8}] Finalizers:[] ClusterName: ManagedFields:[{Manager:kube-controller-manager Operation:Update APIVersion:v1 Time:2022-09-06 04:54:21 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:metadata":{"f:generateName":{},"f:labels":{".":{},"f:k8s-app":{},"f:pod-template-hash":{}},"f:ownerReferences":{".":{},"k:{\"uid\":\"390fcd8b-a8cd-44f1-aa53-9acc42974cd1\"}":{".":{},"f:apiVersion":{},"f:blockOwnerDeletion":{},"f:controller":{},"f:kind":{},"f:name":{},"f:uid":{}}}},"f:spec":{"f:containers":{"k:{\"name\":\"calico-kube-controllers\"}":{".":{},"f:env":{".":{},"k:{\"name\":\"DATASTORE_TYPE\"}":{".":{},"f:name":{},"f:value":{}},"k:{\"name\":\"ENABLED_CONTROLLERS\"}":{".":{},"f:name":{},"f:value":{}}},"f:image":{},"f:imagePullPolicy":{},"f:livenessProbe":{".":{},"f:exec":{".":{},"f:command":{}},"f:failureThreshold":{},"f:initialDelaySeconds":{},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:name":{},"f:readinessProbe":{".":{},"f:exec":{".":{},"f:command":{}},"f:failureThreshold":{},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:resources":{},"f:terminationMessagePath":{},"f:terminationMessagePolicy":{}}},"f:dnsPolicy":{},"f:enableServiceLinks":{},"f:nodeSelector":{".":{},"f:kubernetes.io/os":{}},"f:priorityClassName":{},"f:restartPolicy":{},"f:schedulerName":{},"f:securityContext":{},"f:serviceAccount":{},"f:serviceAccountName":{},"f:terminationGracePeriodSeconds":{},"f:tolerations":{}}}}]} -> {Name:calico-kube-controllers-969cf87c4-q6s7d GenerateName:calico-kube-controllers-969cf87c4- Namespace:kube-system SelfLink: UID:0ca5a608-5f0e-4e96-956b-066d32c9260c ResourceVersion:616 Generation:0 CreationTimestamp:2022-09-06 04:54:21 +0000 UTC DeletionTimestamp:<nil> DeletionGracePeriodSeconds:<nil> Labels:map[k8s-app:calico-kube-controllers pod-template-hash:969cf87c4] Annotations:map[] OwnerReferences:[{APIVersion:apps/v1 Kind:ReplicaSet Name:calico-kube-controllers-969cf87c4 UID:390fcd8b-a8cd-44f1-aa53-9acc42974cd1 Controller:0xc000cf0f50 BlockOwnerDeletion:0xc000cf0f51}] Finalizers:[] ClusterName: ManagedFields:[{Manager:kube-controller-manager Operation:Update APIVersion:v1 Time:2022-09-06 04:54:21 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:metadata":{"f:generateName":{},"f:labels":{".":{},"f:k8s-app":{},"f:pod-template-hash":{}},"f:ownerReferences":{".":{},"k:{\"uid\":\"390fcd8b-a8cd-44f1-aa53-9acc42974cd1\"}":{".":{},"f:apiVersion":{},"f:blockOwnerDeletion":{},"f:controller":{},"f:kind":{},"f:name":{},"f:uid":{}}}},"f:spec":{"f:containers":{"k:{\"name\":\"calico-kube-controllers\"}":{".":{},"f:env":{".":{},"k:{\"name\":\"DATASTORE_TYPE\"}":{".":{},"f:name":{},"f:value":{}},"k:{\"name\":\"ENABLED_CONTROLLERS\"}":{".":{},"f:name":{},"f:value":{}}},"f:image":{},"f:imagePullPolicy":{},"f:livenessProbe":{".":{},"f:exec":{".":{},"f:command":{}},"f:failureThreshold":{},"f:initialDelaySeconds":{},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:name":{},"f:readinessProbe":{".":{},"f:exec":{".":{},"f:command":{}},"f:failureThreshold":{},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:resources":{},"f:terminationMessagePath":{},"f:terminationMessagePolicy":{}}},"f:dnsPolicy":{},"f:enableServiceLinks":{},"f:nodeSelector":{".":{},"f:kubernetes.io/os":{}},"f:priorityClassName":{},"f:restartPolicy":{},"f:schedulerName":{},"f:securityContext":{},"f:serviceAccount":{},"f:serviceAccountName":{},"f:terminationGracePeriodSeconds":{},"f:tolerations":{}}}} {Manager:kube-scheduler Operation:Update APIVersion:v1 Time:2022-09-06 04:54:21 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:status":{"f:conditions":{".":{},"k:{\"type\":\"PodScheduled\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}}}}}]}.
I0906 04:54:21.667071       1 deployment_controller.go:281] "ReplicaSet updated" replicaSet="kube-system/calico-kube-controllers-969cf87c4"
I0906 04:54:21.667733       1 replica_set.go:649] Finished syncing ReplicaSet "kube-system/calico-kube-controllers-969cf87c4" (27.832881ms)
I0906 04:54:21.668207       1 controller_utils.go:189] Controller expectations fulfilled &controller.ControlleeExpectations{add:0, del:0, key:"kube-system/calico-kube-controllers-969cf87c4", timestamp:time.Time{wall:0xc0bdd27f6627aae3, ext:33069635923, loc:(*time.Location)(0x731ea80)}}
I0906 04:54:21.668566       1 replica_set_utils.go:59] Updating status for : kube-system/calico-kube-controllers-969cf87c4, replicas 0->1 (need 1), fullyLabeledReplicas 0->1, readyReplicas 0->0, availableReplicas 0->0, sequence No: 1->1
I0906 04:54:21.668153       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/calico-kube-controllers" duration="32.767949ms"
I0906 04:54:21.669034       1 deployment_controller.go:490] "Error syncing deployment" deployment="kube-system/calico-kube-controllers" err="Operation cannot be fulfilled on deployments.apps \"calico-kube-controllers\": the object has been modified; please apply your changes to the latest version and try again"
I0906 04:54:21.669209       1 deployment_controller.go:576] "Started syncing deployment" deployment="kube-system/calico-kube-controllers" startTime="2022-09-06 04:54:21.669187331 +0000 UTC m=+33.098689395"
I0906 04:54:21.669745       1 deployment_util.go:808] Deployment "calico-kube-controllers" timed out (false) [last progress check: 2022-09-06 04:54:21 +0000 UTC - now: 2022-09-06 04:54:21.669738172 +0000 UTC m=+33.099240236]
I0906 04:54:21.676060       1 deployment_controller.go:281] "ReplicaSet updated" replicaSet="kube-system/calico-kube-controllers-969cf87c4"
I0906 04:54:21.676255       1 replica_set.go:649] Finished syncing ReplicaSet "kube-system/calico-kube-controllers-969cf87c4" (8.059402ms)
I0906 04:54:21.676500       1 controller_utils.go:189] Controller expectations fulfilled &controller.ControlleeExpectations{add:0, del:0, key:"kube-system/calico-kube-controllers-969cf87c4", timestamp:time.Time{wall:0xc0bdd27f6627aae3, ext:33069635923, loc:(*time.Location)(0x731ea80)}}
I0906 04:54:21.676691       1 replica_set.go:649] Finished syncing ReplicaSet "kube-system/calico-kube-controllers-969cf87c4" (224.916µs)
I0906 04:54:21.678311       1 disruption.go:384] add DB "calico-kube-controllers"
I0906 04:54:21.680742       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/calico-kube-controllers" duration="11.540262ms"
I0906 04:54:21.680786       1 deployment_controller.go:576] "Started syncing deployment" deployment="kube-system/calico-kube-controllers" startTime="2022-09-06 04:54:21.680768396 +0000 UTC m=+33.110270460"
I0906 04:54:21.681033       1 deployment_controller.go:176] "Updating deployment" deployment="kube-system/calico-kube-controllers"
I0906 04:54:21.684445       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/calico-kube-controllers" duration="3.662874ms"
I0906 04:54:21.684542       1 deployment_controller.go:490] "Error syncing deployment" deployment="kube-system/calico-kube-controllers" err="Operation cannot be fulfilled on deployments.apps \"calico-kube-controllers\": the object has been modified; please apply your changes to the latest version and try again"
I0906 04:54:21.684633       1 deployment_controller.go:576] "Started syncing deployment" deployment="kube-system/calico-kube-controllers" startTime="2022-09-06 04:54:21.684612184 +0000 UTC m=+33.114114248"
I0906 04:54:21.689873       1 deployment_controller.go:176] "Updating deployment" deployment="kube-system/calico-kube-controllers"
I0906 04:54:21.690110       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/calico-kube-controllers" duration="5.484565ms"
I0906 04:54:21.690178       1 deployment_controller.go:576] "Started syncing deployment" deployment="kube-system/calico-kube-controllers" startTime="2022-09-06 04:54:21.69015915 +0000 UTC m=+33.119661314"
I0906 04:54:21.690562       1 deployment_util.go:808] Deployment "calico-kube-controllers" timed out (false) [last progress check: 2022-09-06 04:54:21 +0000 UTC - now: 2022-09-06 04:54:21.690553854 +0000 UTC m=+33.120056018]
I0906 04:54:21.690601       1 progress.go:195] Queueing up deployment "calico-kube-controllers" for a progress check after 599s
... skipping 162 lines ...
I0906 04:54:32.501021       1 disruption.go:427] updatePod called on pod "calico-node-4sfdj"
I0906 04:54:32.507306       1 disruption.go:490] No PodDisruptionBudgets found for pod calico-node-4sfdj, PodDisruptionBudget controller will avoid syncing.
I0906 04:54:32.507320       1 disruption.go:430] No matching pdb for pod "calico-node-4sfdj"
I0906 04:54:32.558019       1 shared_informer.go:270] caches populated
I0906 04:54:32.558048       1 shared_informer.go:247] Caches are synced for resource quota 
I0906 04:54:32.558060       1 resource_quota_controller.go:454] synced quota controller
W0906 04:54:32.871803       1 garbagecollector.go:703] failed to discover some groups: map[metrics.k8s.io/v1beta1:the server is currently unable to handle the request]
I0906 04:54:32.872041       1 garbagecollector.go:213] syncing garbage collector with updated resources from discovery (attempt 1): added: [crd.projectcalico.org/v1, Resource=bgpconfigurations crd.projectcalico.org/v1, Resource=bgppeers crd.projectcalico.org/v1, Resource=blockaffinities crd.projectcalico.org/v1, Resource=caliconodestatuses crd.projectcalico.org/v1, Resource=clusterinformations crd.projectcalico.org/v1, Resource=felixconfigurations crd.projectcalico.org/v1, Resource=globalnetworkpolicies crd.projectcalico.org/v1, Resource=globalnetworksets crd.projectcalico.org/v1, Resource=hostendpoints crd.projectcalico.org/v1, Resource=ipamblocks crd.projectcalico.org/v1, Resource=ipamconfigs crd.projectcalico.org/v1, Resource=ipamhandles crd.projectcalico.org/v1, Resource=ippools crd.projectcalico.org/v1, Resource=ipreservations crd.projectcalico.org/v1, Resource=kubecontrollersconfigurations crd.projectcalico.org/v1, Resource=networkpolicies crd.projectcalico.org/v1, Resource=networksets], removed: []
I0906 04:54:32.872069       1 garbagecollector.go:219] reset restmapper
E0906 04:54:32.886633       1 memcache.go:196] couldn't get resource list for metrics.k8s.io/v1beta1: the server is currently unable to handle the request
E0906 04:54:32.888712       1 memcache.go:101] couldn't get resource list for metrics.k8s.io/v1beta1: the server is currently unable to handle the request
I0906 04:54:32.889667       1 graph_builder.go:174] using a shared informer for resource "crd.projectcalico.org/v1, Resource=bgpconfigurations", kind "crd.projectcalico.org/v1, Kind=BGPConfiguration"
I0906 04:54:32.889725       1 graph_builder.go:174] using a shared informer for resource "crd.projectcalico.org/v1, Resource=blockaffinities", kind "crd.projectcalico.org/v1, Kind=BlockAffinity"
... skipping 172 lines ...
I0906 04:54:52.166077       1 replica_set.go:439] Pod coredns-558bd4d5db-kjhhx updated, objectMeta {Name:coredns-558bd4d5db-kjhhx GenerateName:coredns-558bd4d5db- Namespace:kube-system SelfLink: UID:3e020a0b-2f16-4fb6-a945-13b1076fe965 ResourceVersion:729 Generation:0 CreationTimestamp:2022-09-06 04:54:02 +0000 UTC DeletionTimestamp:<nil> DeletionGracePeriodSeconds:<nil> Labels:map[k8s-app:kube-dns pod-template-hash:558bd4d5db] Annotations:map[] OwnerReferences:[{APIVersion:apps/v1 Kind:ReplicaSet Name:coredns-558bd4d5db UID:fc037d72-8f68-4c2c-a7b6-8ddd69a881db Controller:0xc00178bde7 BlockOwnerDeletion:0xc00178bde8}] Finalizers:[] ClusterName: ManagedFields:[{Manager:kube-controller-manager Operation:Update APIVersion:v1 Time:2022-09-06 04:54:02 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:metadata":{"f:generateName":{},"f:labels":{".":{},"f:k8s-app":{},"f:pod-template-hash":{}},"f:ownerReferences":{".":{},"k:{\"uid\":\"fc037d72-8f68-4c2c-a7b6-8ddd69a881db\"}":{".":{},"f:apiVersion":{},"f:blockOwnerDeletion":{},"f:controller":{},"f:kind":{},"f:name":{},"f:uid":{}}}},"f:spec":{"f:containers":{"k:{\"name\":\"coredns\"}":{".":{},"f:args":{},"f:image":{},"f:imagePullPolicy":{},"f:livenessProbe":{".":{},"f:failureThreshold":{},"f:httpGet":{".":{},"f:path":{},"f:port":{},"f:scheme":{}},"f:initialDelaySeconds":{},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:name":{},"f:ports":{".":{},"k:{\"containerPort\":53,\"protocol\":\"TCP\"}":{".":{},"f:containerPort":{},"f:name":{},"f:protocol":{}},"k:{\"containerPort\":53,\"protocol\":\"UDP\"}":{".":{},"f:containerPort":{},"f:name":{},"f:protocol":{}},"k:{\"containerPort\":9153,\"protocol\":\"TCP\"}":{".":{},"f:containerPort":{},"f:name":{},"f:protocol":{}}},"f:readinessProbe":{".":{},"f:failureThreshold":{},"f:httpGet":{".":{},"f:path":{},"f:port":{},"f:scheme":{}},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:resources":{".":{},"f:limits":{".":{},"f:memory":{}},"f:requests":{".":{},"f:cpu":{},"f:memory":{}}},"f:securityContext":{".":{},"f:allowPrivilegeEscalation":{},"f:capabilities":{".":{},"f:add":{},"f:drop":{}},"f:readOnlyRootFilesystem":{}},"f:terminationMessagePath":{},"f:terminationMessagePolicy":{},"f:volumeMounts":{".":{},"k:{\"mountPath\":\"/etc/coredns\"}":{".":{},"f:mountPath":{},"f:name":{},"f:readOnly":{}}}}},"f:dnsPolicy":{},"f:enableServiceLinks":{},"f:nodeSelector":{".":{},"f:kubernetes.io/os":{}},"f:priorityClassName":{},"f:restartPolicy":{},"f:schedulerName":{},"f:securityContext":{},"f:serviceAccount":{},"f:serviceAccountName":{},"f:terminationGracePeriodSeconds":{},"f:tolerations":{},"f:volumes":{".":{},"k:{\"name\":\"config-volume\"}":{".":{},"f:configMap":{".":{},"f:defaultMode":{},"f:items":{},"f:name":{}},"f:name":{}}}}}} {Manager:kube-scheduler Operation:Update APIVersion:v1 Time:2022-09-06 04:54:02 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:status":{"f:conditions":{".":{},"k:{\"type\":\"PodScheduled\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}}}}}]} -> {Name:coredns-558bd4d5db-kjhhx GenerateName:coredns-558bd4d5db- Namespace:kube-system SelfLink: UID:3e020a0b-2f16-4fb6-a945-13b1076fe965 ResourceVersion:733 Generation:0 CreationTimestamp:2022-09-06 04:54:02 +0000 UTC DeletionTimestamp:<nil> DeletionGracePeriodSeconds:<nil> Labels:map[k8s-app:kube-dns pod-template-hash:558bd4d5db] Annotations:map[] OwnerReferences:[{APIVersion:apps/v1 Kind:ReplicaSet Name:coredns-558bd4d5db UID:fc037d72-8f68-4c2c-a7b6-8ddd69a881db Controller:0xc000d9f4ff BlockOwnerDeletion:0xc000d9f630}] Finalizers:[] ClusterName: ManagedFields:[{Manager:kube-controller-manager Operation:Update APIVersion:v1 Time:2022-09-06 04:54:02 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:metadata":{"f:generateName":{},"f:labels":{".":{},"f:k8s-app":{},"f:pod-template-hash":{}},"f:ownerReferences":{".":{},"k:{\"uid\":\"fc037d72-8f68-4c2c-a7b6-8ddd69a881db\"}":{".":{},"f:apiVersion":{},"f:blockOwnerDeletion":{},"f:controller":{},"f:kind":{},"f:name":{},"f:uid":{}}}},"f:spec":{"f:containers":{"k:{\"name\":\"coredns\"}":{".":{},"f:args":{},"f:image":{},"f:imagePullPolicy":{},"f:livenessProbe":{".":{},"f:failureThreshold":{},"f:httpGet":{".":{},"f:path":{},"f:port":{},"f:scheme":{}},"f:initialDelaySeconds":{},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:name":{},"f:ports":{".":{},"k:{\"containerPort\":53,\"protocol\":\"TCP\"}":{".":{},"f:containerPort":{},"f:name":{},"f:protocol":{}},"k:{\"containerPort\":53,\"protocol\":\"UDP\"}":{".":{},"f:containerPort":{},"f:name":{},"f:protocol":{}},"k:{\"containerPort\":9153,\"protocol\":\"TCP\"}":{".":{},"f:containerPort":{},"f:name":{},"f:protocol":{}}},"f:readinessProbe":{".":{},"f:failureThreshold":{},"f:httpGet":{".":{},"f:path":{},"f:port":{},"f:scheme":{}},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:resources":{".":{},"f:limits":{".":{},"f:memory":{}},"f:requests":{".":{},"f:cpu":{},"f:memory":{}}},"f:securityContext":{".":{},"f:allowPrivilegeEscalation":{},"f:capabilities":{".":{},"f:add":{},"f:drop":{}},"f:readOnlyRootFilesystem":{}},"f:terminationMessagePath":{},"f:terminationMessagePolicy":{},"f:volumeMounts":{".":{},"k:{\"mountPath\":\"/etc/coredns\"}":{".":{},"f:mountPath":{},"f:name":{},"f:readOnly":{}}}}},"f:dnsPolicy":{},"f:enableServiceLinks":{},"f:nodeSelector":{".":{},"f:kubernetes.io/os":{}},"f:priorityClassName":{},"f:restartPolicy":{},"f:schedulerName":{},"f:securityContext":{},"f:serviceAccount":{},"f:serviceAccountName":{},"f:terminationGracePeriodSeconds":{},"f:tolerations":{},"f:volumes":{".":{},"k:{\"name\":\"config-volume\"}":{".":{},"f:configMap":{".":{},"f:defaultMode":{},"f:items":{},"f:name":{}},"f:name":{}}}}}} {Manager:kube-scheduler Operation:Update APIVersion:v1 Time:2022-09-06 04:54:02 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:status":{"f:conditions":{".":{},"k:{\"type\":\"PodScheduled\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}}}}} {Manager:kubelet Operation:Update APIVersion:v1 Time:2022-09-06 04:54:52 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:status":{"f:conditions":{"k:{\"type\":\"ContainersReady\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Initialized\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Ready\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}},"f:containerStatuses":{},"f:hostIP":{},"f:startTime":{}}}}]}.
I0906 04:54:52.166241       1 controller_utils.go:189] Controller expectations fulfilled &controller.ControlleeExpectations{add:0, del:0, key:"kube-system/coredns-558bd4d5db", timestamp:time.Time{wall:0xc0bdd27aaf9e3185, ext:14228398581, loc:(*time.Location)(0x731ea80)}}
I0906 04:54:52.166339       1 replica_set.go:649] Finished syncing ReplicaSet "kube-system/coredns-558bd4d5db" (104.101µs)
I0906 04:54:52.166359       1 disruption.go:427] updatePod called on pod "coredns-558bd4d5db-kjhhx"
I0906 04:54:52.166379       1 disruption.go:490] No PodDisruptionBudgets found for pod coredns-558bd4d5db-kjhhx, PodDisruptionBudget controller will avoid syncing.
I0906 04:54:52.166385       1 disruption.go:430] No matching pdb for pod "coredns-558bd4d5db-kjhhx"
I0906 04:54:52.195616       1 node_lifecycle_controller.go:1039] ReadyCondition for Node capz-b2pot9-control-plane-xft6z transitioned from &NodeCondition{Type:Ready,Status:False,LastHeartbeatTime:2022-09-06 04:54:38 +0000 UTC,LastTransitionTime:2022-09-06 04:53:38 +0000 UTC,Reason:KubeletNotReady,Message:container runtime network not ready: NetworkReady=false reason:NetworkPluginNotReady message:Network plugin returns error: cni plugin not initialized,} to &NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2022-09-06 04:54:48 +0000 UTC,LastTransitionTime:2022-09-06 04:54:48 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status. AppArmor enabled,}
I0906 04:54:52.195699       1 node_lifecycle_controller.go:1047] Node capz-b2pot9-control-plane-xft6z ReadyCondition updated. Updating timestamp.
I0906 04:54:52.195727       1 node_lifecycle_controller.go:893] Node capz-b2pot9-control-plane-xft6z is healthy again, removing all taints
I0906 04:54:52.195747       1 node_lifecycle_controller.go:1191] Controller detected that some Nodes are Ready. Exiting master disruption mode.
I0906 04:54:52.696035       1 replica_set.go:439] Pod coredns-558bd4d5db-6hxpv updated, objectMeta {Name:coredns-558bd4d5db-6hxpv GenerateName:coredns-558bd4d5db- Namespace:kube-system SelfLink: UID:49e51eca-2e38-41a9-a007-de856e86b955 ResourceVersion:731 Generation:0 CreationTimestamp:2022-09-06 04:54:02 +0000 UTC DeletionTimestamp:<nil> DeletionGracePeriodSeconds:<nil> Labels:map[k8s-app:kube-dns pod-template-hash:558bd4d5db] Annotations:map[] OwnerReferences:[{APIVersion:apps/v1 Kind:ReplicaSet Name:coredns-558bd4d5db UID:fc037d72-8f68-4c2c-a7b6-8ddd69a881db Controller:0xc0017a47bf BlockOwnerDeletion:0xc0017a47e0}] Finalizers:[] ClusterName: ManagedFields:[{Manager:kube-controller-manager Operation:Update APIVersion:v1 Time:2022-09-06 04:54:02 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:metadata":{"f:generateName":{},"f:labels":{".":{},"f:k8s-app":{},"f:pod-template-hash":{}},"f:ownerReferences":{".":{},"k:{\"uid\":\"fc037d72-8f68-4c2c-a7b6-8ddd69a881db\"}":{".":{},"f:apiVersion":{},"f:blockOwnerDeletion":{},"f:controller":{},"f:kind":{},"f:name":{},"f:uid":{}}}},"f:spec":{"f:containers":{"k:{\"name\":\"coredns\"}":{".":{},"f:args":{},"f:image":{},"f:imagePullPolicy":{},"f:livenessProbe":{".":{},"f:failureThreshold":{},"f:httpGet":{".":{},"f:path":{},"f:port":{},"f:scheme":{}},"f:initialDelaySeconds":{},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:name":{},"f:ports":{".":{},"k:{\"containerPort\":53,\"protocol\":\"TCP\"}":{".":{},"f:containerPort":{},"f:name":{},"f:protocol":{}},"k:{\"containerPort\":53,\"protocol\":\"UDP\"}":{".":{},"f:containerPort":{},"f:name":{},"f:protocol":{}},"k:{\"containerPort\":9153,\"protocol\":\"TCP\"}":{".":{},"f:containerPort":{},"f:name":{},"f:protocol":{}}},"f:readinessProbe":{".":{},"f:failureThreshold":{},"f:httpGet":{".":{},"f:path":{},"f:port":{},"f:scheme":{}},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:resources":{".":{},"f:limits":{".":{},"f:memory":{}},"f:requests":{".":{},"f:cpu":{},"f:memory":{}}},"f:securityContext":{".":{},"f:allowPrivilegeEscalation":{},"f:capabilities":{".":{},"f:add":{},"f:drop":{}},"f:readOnlyRootFilesystem":{}},"f:terminationMessagePath":{},"f:terminationMessagePolicy":{},"f:volumeMounts":{".":{},"k:{\"mountPath\":\"/etc/coredns\"}":{".":{},"f:mountPath":{},"f:name":{},"f:readOnly":{}}}}},"f:dnsPolicy":{},"f:enableServiceLinks":{},"f:nodeSelector":{".":{},"f:kubernetes.io/os":{}},"f:priorityClassName":{},"f:restartPolicy":{},"f:schedulerName":{},"f:securityContext":{},"f:serviceAccount":{},"f:serviceAccountName":{},"f:terminationGracePeriodSeconds":{},"f:tolerations":{},"f:volumes":{".":{},"k:{\"name\":\"config-volume\"}":{".":{},"f:configMap":{".":{},"f:defaultMode":{},"f:items":{},"f:name":{}},"f:name":{}}}}}} {Manager:kube-scheduler Operation:Update APIVersion:v1 Time:2022-09-06 04:54:02 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:status":{"f:conditions":{".":{},"k:{\"type\":\"PodScheduled\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}}}}} {Manager:kubelet Operation:Update APIVersion:v1 Time:2022-09-06 04:54:52 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:status":{"f:conditions":{"k:{\"type\":\"ContainersReady\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Initialized\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Ready\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}},"f:containerStatuses":{},"f:hostIP":{},"f:startTime":{}}}}]} -> {Name:coredns-558bd4d5db-6hxpv GenerateName:coredns-558bd4d5db- Namespace:kube-system SelfLink: UID:49e51eca-2e38-41a9-a007-de856e86b955 ResourceVersion:737 Generation:0 CreationTimestamp:2022-09-06 04:54:02 +0000 UTC DeletionTimestamp:<nil> DeletionGracePeriodSeconds:<nil> Labels:map[k8s-app:kube-dns pod-template-hash:558bd4d5db] Annotations:map[cni.projectcalico.org/containerID:04a4557b1075c4886c2c44ce39d894dff5ea40b5f0227f7e19570e67baa577c3 cni.projectcalico.org/podIP:192.168.198.129/32 cni.projectcalico.org/podIPs:192.168.198.129/32] OwnerReferences:[{APIVersion:apps/v1 Kind:ReplicaSet Name:coredns-558bd4d5db UID:fc037d72-8f68-4c2c-a7b6-8ddd69a881db Controller:0xc0005fb0b7 BlockOwnerDeletion:0xc0005fb0b8}] Finalizers:[] ClusterName: ManagedFields:[{Manager:kube-controller-manager Operation:Update APIVersion:v1 Time:2022-09-06 04:54:02 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:metadata":{"f:generateName":{},"f:labels":{".":{},"f:k8s-app":{},"f:pod-template-hash":{}},"f:ownerReferences":{".":{},"k:{\"uid\":\"fc037d72-8f68-4c2c-a7b6-8ddd69a881db\"}":{".":{},"f:apiVersion":{},"f:blockOwnerDeletion":{},"f:controller":{},"f:kind":{},"f:name":{},"f:uid":{}}}},"f:spec":{"f:containers":{"k:{\"name\":\"coredns\"}":{".":{},"f:args":{},"f:image":{},"f:imagePullPolicy":{},"f:livenessProbe":{".":{},"f:failureThreshold":{},"f:httpGet":{".":{},"f:path":{},"f:port":{},"f:scheme":{}},"f:initialDelaySeconds":{},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:name":{},"f:ports":{".":{},"k:{\"containerPort\":53,\"protocol\":\"TCP\"}":{".":{},"f:containerPort":{},"f:name":{},"f:protocol":{}},"k:{\"containerPort\":53,\"protocol\":\"UDP\"}":{".":{},"f:containerPort":{},"f:name":{},"f:protocol":{}},"k:{\"containerPort\":9153,\"protocol\":\"TCP\"}":{".":{},"f:containerPort":{},"f:name":{},"f:protocol":{}}},"f:readinessProbe":{".":{},"f:failureThreshold":{},"f:httpGet":{".":{},"f:path":{},"f:port":{},"f:scheme":{}},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:resources":{".":{},"f:limits":{".":{},"f:memory":{}},"f:requests":{".":{},"f:cpu":{},"f:memory":{}}},"f:securityContext":{".":{},"f:allowPrivilegeEscalation":{},"f:capabilities":{".":{},"f:add":{},"f:drop":{}},"f:readOnlyRootFilesystem":{}},"f:terminationMessagePath":{},"f:terminationMessagePolicy":{},"f:volumeMounts":{".":{},"k:{\"mountPath\":\"/etc/coredns\"}":{".":{},"f:mountPath":{},"f:name":{},"f:readOnly":{}}}}},"f:dnsPolicy":{},"f:enableServiceLinks":{},"f:nodeSelector":{".":{},"f:kubernetes.io/os":{}},"f:priorityClassName":{},"f:restartPolicy":{},"f:schedulerName":{},"f:securityContext":{},"f:serviceAccount":{},"f:serviceAccountName":{},"f:terminationGracePeriodSeconds":{},"f:tolerations":{},"f:volumes":{".":{},"k:{\"name\":\"config-volume\"}":{".":{},"f:configMap":{".":{},"f:defaultMode":{},"f:items":{},"f:name":{}},"f:name":{}}}}}} {Manager:kube-scheduler Operation:Update APIVersion:v1 Time:2022-09-06 04:54:02 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:status":{"f:conditions":{".":{},"k:{\"type\":\"PodScheduled\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}}}}} {Manager:Go-http-client Operation:Update APIVersion:v1 Time:2022-09-06 04:54:52 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:metadata":{"f:annotations":{".":{},"f:cni.projectcalico.org/containerID":{},"f:cni.projectcalico.org/podIP":{},"f:cni.projectcalico.org/podIPs":{}}}}} {Manager:kubelet Operation:Update APIVersion:v1 Time:2022-09-06 04:54:52 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:status":{"f:conditions":{"k:{\"type\":\"ContainersReady\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Initialized\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Ready\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}},"f:containerStatuses":{},"f:hostIP":{},"f:startTime":{}}}}]}.
I0906 04:54:52.696212       1 controller_utils.go:189] Controller expectations fulfilled &controller.ControlleeExpectations{add:0, del:0, key:"kube-system/coredns-558bd4d5db", timestamp:time.Time{wall:0xc0bdd27aaf9e3185, ext:14228398581, loc:(*time.Location)(0x731ea80)}}
I0906 04:54:52.696350       1 replica_set.go:649] Finished syncing ReplicaSet "kube-system/coredns-558bd4d5db" (145.201µs)
... skipping 120 lines ...
I0906 04:55:02.140001       1 reflector.go:381] k8s.io/client-go/informers/factory.go:134: forcing resync
I0906 04:55:02.236847       1 gc_controller.go:161] GC'ing orphaned
I0906 04:55:02.236880       1 gc_controller.go:224] GC'ing unscheduled pods which are terminating.
I0906 04:55:02.336962       1 pv_controller_base.go:528] resyncing PV controller
E0906 04:55:02.602351       1 resource_quota_controller.go:409] unable to retrieve the complete list of server APIs: metrics.k8s.io/v1beta1: the server is currently unable to handle the request
I0906 04:55:02.602592       1 resource_quota_controller.go:424] no resource updates from discovery, skipping resource quota sync
W0906 04:55:03.522520       1 garbagecollector.go:703] failed to discover some groups: map[metrics.k8s.io/v1beta1:the server is currently unable to handle the request]
I0906 04:55:03.595661       1 replica_set.go:439] Pod calico-kube-controllers-969cf87c4-q6s7d updated, objectMeta {Name:calico-kube-controllers-969cf87c4-q6s7d GenerateName:calico-kube-controllers-969cf87c4- Namespace:kube-system SelfLink: UID:0ca5a608-5f0e-4e96-956b-066d32c9260c ResourceVersion:779 Generation:0 CreationTimestamp:2022-09-06 04:54:21 +0000 UTC DeletionTimestamp:<nil> DeletionGracePeriodSeconds:<nil> Labels:map[k8s-app:calico-kube-controllers pod-template-hash:969cf87c4] Annotations:map[cni.projectcalico.org/containerID:6e4c3150923a3776f40a533a716aff9a396bf2d6cc66c387d513dc81b420a5e2 cni.projectcalico.org/podIP:192.168.198.131/32 cni.projectcalico.org/podIPs:192.168.198.131/32] OwnerReferences:[{APIVersion:apps/v1 Kind:ReplicaSet Name:calico-kube-controllers-969cf87c4 UID:390fcd8b-a8cd-44f1-aa53-9acc42974cd1 Controller:0xc001743067 BlockOwnerDeletion:0xc001743068}] Finalizers:[] ClusterName: ManagedFields:[{Manager:kube-controller-manager Operation:Update APIVersion:v1 Time:2022-09-06 04:54:21 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:metadata":{"f:generateName":{},"f:labels":{".":{},"f:k8s-app":{},"f:pod-template-hash":{}},"f:ownerReferences":{".":{},"k:{\"uid\":\"390fcd8b-a8cd-44f1-aa53-9acc42974cd1\"}":{".":{},"f:apiVersion":{},"f:blockOwnerDeletion":{},"f:controller":{},"f:kind":{},"f:name":{},"f:uid":{}}}},"f:spec":{"f:containers":{"k:{\"name\":\"calico-kube-controllers\"}":{".":{},"f:env":{".":{},"k:{\"name\":\"DATASTORE_TYPE\"}":{".":{},"f:name":{},"f:value":{}},"k:{\"name\":\"ENABLED_CONTROLLERS\"}":{".":{},"f:name":{},"f:value":{}}},"f:image":{},"f:imagePullPolicy":{},"f:livenessProbe":{".":{},"f:exec":{".":{},"f:command":{}},"f:failureThreshold":{},"f:initialDelaySeconds":{},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:name":{},"f:readinessProbe":{".":{},"f:exec":{".":{},"f:command":{}},"f:failureThreshold":{},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:resources":{},"f:terminationMessagePath":{},"f:terminationMessagePolicy":{}}},"f:dnsPolicy":{},"f:enableServiceLinks":{},"f:nodeSelector":{".":{},"f:kubernetes.io/os":{}},"f:priorityClassName":{},"f:restartPolicy":{},"f:schedulerName":{},"f:securityContext":{},"f:serviceAccount":{},"f:serviceAccountName":{},"f:terminationGracePeriodSeconds":{},"f:tolerations":{}}}} {Manager:kube-scheduler Operation:Update APIVersion:v1 Time:2022-09-06 04:54:21 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:status":{"f:conditions":{".":{},"k:{\"type\":\"PodScheduled\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}}}}} {Manager:Go-http-client Operation:Update APIVersion:v1 Time:2022-09-06 04:54:57 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:metadata":{"f:annotations":{".":{},"f:cni.projectcalico.org/containerID":{},"f:cni.projectcalico.org/podIP":{},"f:cni.projectcalico.org/podIPs":{}}}}} {Manager:kubelet Operation:Update APIVersion:v1 Time:2022-09-06 04:54:57 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:status":{"f:conditions":{"k:{\"type\":\"ContainersReady\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Initialized\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Ready\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}},"f:containerStatuses":{},"f:hostIP":{},"f:startTime":{}}}}]} -> {Name:calico-kube-controllers-969cf87c4-q6s7d GenerateName:calico-kube-controllers-969cf87c4- Namespace:kube-system SelfLink: UID:0ca5a608-5f0e-4e96-956b-066d32c9260c ResourceVersion:795 Generation:0 CreationTimestamp:2022-09-06 04:54:21 +0000 UTC DeletionTimestamp:<nil> DeletionGracePeriodSeconds:<nil> Labels:map[k8s-app:calico-kube-controllers pod-template-hash:969cf87c4] Annotations:map[cni.projectcalico.org/containerID:6e4c3150923a3776f40a533a716aff9a396bf2d6cc66c387d513dc81b420a5e2 cni.projectcalico.org/podIP:192.168.198.131/32 cni.projectcalico.org/podIPs:192.168.198.131/32] OwnerReferences:[{APIVersion:apps/v1 Kind:ReplicaSet Name:calico-kube-controllers-969cf87c4 UID:390fcd8b-a8cd-44f1-aa53-9acc42974cd1 Controller:0xc0023021f0 BlockOwnerDeletion:0xc0023021f1}] Finalizers:[] ClusterName: ManagedFields:[{Manager:kube-controller-manager Operation:Update APIVersion:v1 Time:2022-09-06 04:54:21 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:metadata":{"f:generateName":{},"f:labels":{".":{},"f:k8s-app":{},"f:pod-template-hash":{}},"f:ownerReferences":{".":{},"k:{\"uid\":\"390fcd8b-a8cd-44f1-aa53-9acc42974cd1\"}":{".":{},"f:apiVersion":{},"f:blockOwnerDeletion":{},"f:controller":{},"f:kind":{},"f:name":{},"f:uid":{}}}},"f:spec":{"f:containers":{"k:{\"name\":\"calico-kube-controllers\"}":{".":{},"f:env":{".":{},"k:{\"name\":\"DATASTORE_TYPE\"}":{".":{},"f:name":{},"f:value":{}},"k:{\"name\":\"ENABLED_CONTROLLERS\"}":{".":{},"f:name":{},"f:value":{}}},"f:image":{},"f:imagePullPolicy":{},"f:livenessProbe":{".":{},"f:exec":{".":{},"f:command":{}},"f:failureThreshold":{},"f:initialDelaySeconds":{},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:name":{},"f:readinessProbe":{".":{},"f:exec":{".":{},"f:command":{}},"f:failureThreshold":{},"f:periodSeconds":{},"f:successThreshold":{},"f:timeoutSeconds":{}},"f:resources":{},"f:terminationMessagePath":{},"f:terminationMessagePolicy":{}}},"f:dnsPolicy":{},"f:enableServiceLinks":{},"f:nodeSelector":{".":{},"f:kubernetes.io/os":{}},"f:priorityClassName":{},"f:restartPolicy":{},"f:schedulerName":{},"f:securityContext":{},"f:serviceAccount":{},"f:serviceAccountName":{},"f:terminationGracePeriodSeconds":{},"f:tolerations":{}}}} {Manager:kube-scheduler Operation:Update APIVersion:v1 Time:2022-09-06 04:54:21 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:status":{"f:conditions":{".":{},"k:{\"type\":\"PodScheduled\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}}}}} {Manager:Go-http-client Operation:Update APIVersion:v1 Time:2022-09-06 04:54:57 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:metadata":{"f:annotations":{".":{},"f:cni.projectcalico.org/containerID":{},"f:cni.projectcalico.org/podIP":{},"f:cni.projectcalico.org/podIPs":{}}}}} {Manager:kubelet Operation:Update APIVersion:v1 Time:2022-09-06 04:55:03 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:status":{"f:conditions":{"k:{\"type\":\"ContainersReady\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Initialized\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Ready\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{},"f:type":{}}},"f:containerStatuses":{},"f:hostIP":{},"f:phase":{},"f:podIP":{},"f:podIPs":{".":{},"k:{\"ip\":\"192.168.198.131\"}":{".":{},"f:ip":{}}},"f:startTime":{}}}}]}.
I0906 04:55:03.595835       1 controller_utils.go:189] Controller expectations fulfilled &controller.ControlleeExpectations{add:0, del:0, key:"kube-system/calico-kube-controllers-969cf87c4", timestamp:time.Time{wall:0xc0bdd27f6627aae3, ext:33069635923, loc:(*time.Location)(0x731ea80)}}
I0906 04:55:03.595916       1 replica_set.go:649] Finished syncing ReplicaSet "kube-system/calico-kube-controllers-969cf87c4" (87.302µs)
I0906 04:55:03.595935       1 disruption.go:427] updatePod called on pod "calico-kube-controllers-969cf87c4-q6s7d"
I0906 04:55:03.595949       1 disruption.go:433] updatePod "calico-kube-controllers-969cf87c4-q6s7d" -> PDB "calico-kube-controllers"
I0906 04:55:03.595989       1 disruption.go:558] Finished syncing PodDisruptionBudget "kube-system/calico-kube-controllers" (25.501µs)
... skipping 147 lines ...
I0906 04:56:07.291052       1 controller.go:272] Triggering nodeSync
I0906 04:56:07.291061       1 controller.go:291] nodeSync has been triggered
I0906 04:56:07.291068       1 controller.go:776] Running updateLoadBalancerHosts(len(services)==0, workers==1)
I0906 04:56:07.291101       1 controller.go:790] Finished updateLoadBalancerHosts
I0906 04:56:07.291107       1 controller.go:731] It took 4.03e-05 seconds to finish nodeSyncInternal
I0906 04:56:07.291142       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-b2pot9-md-0-r4r48"
W0906 04:56:07.291155       1 actual_state_of_world.go:539] Failed to update statusUpdateNeeded field in actual state of world: Failed to set statusUpdateNeeded to needed true, because nodeName="capz-b2pot9-md-0-r4r48" does not exist
I0906 04:56:07.302060       1 taint_manager.go:435] "Noticed node update" node={nodeName:capz-b2pot9-md-0-r4r48}
I0906 04:56:07.307544       1 taint_manager.go:440] "Updating known taints on node" node="capz-b2pot9-md-0-r4r48" taints=[]
I0906 04:56:07.311615       1 controller_utils.go:189] Controller expectations fulfilled &controller.ControlleeExpectations{add:0, del:0, key:"kube-system/kube-proxy", timestamp:time.Time{wall:0xc0bdd27d6a6c8125, ext:25141256085, loc:(*time.Location)(0x731ea80)}}
I0906 04:56:07.311922       1 controller_utils.go:223] Setting expectations &controller.ControlleeExpectations{add:1, del:0, key:"kube-system/kube-proxy", timestamp:time.Time{wall:0xc0bdd299d29774dc, ext:138741417804, loc:(*time.Location)(0x731ea80)}}
I0906 04:56:07.312066       1 daemon_controller.go:968] Nodes needing daemon pods for daemon set kube-proxy: [capz-b2pot9-md-0-r4r48], creating 1
I0906 04:56:07.315688       1 controller_utils.go:189] Controller expectations fulfilled &controller.ControlleeExpectations{add:0, del:0, key:"kube-system/calico-node", timestamp:time.Time{wall:0xc0bdd286f85d9d0e, ext:63375161214, loc:(*time.Location)(0x731ea80)}}
... skipping 99 lines ...
I0906 04:56:10.743633       1 controller.go:272] Triggering nodeSync
I0906 04:56:10.743642       1 controller.go:291] nodeSync has been triggered
I0906 04:56:10.743651       1 controller.go:776] Running updateLoadBalancerHosts(len(services)==0, workers==1)
I0906 04:56:10.743662       1 controller.go:790] Finished updateLoadBalancerHosts
I0906 04:56:10.743669       1 controller.go:731] It took 1.9601e-05 seconds to finish nodeSyncInternal
I0906 04:56:10.743731       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-b2pot9-md-0-whg8j"
W0906 04:56:10.743753       1 actual_state_of_world.go:539] Failed to update statusUpdateNeeded field in actual state of world: Failed to set statusUpdateNeeded to needed true, because nodeName="capz-b2pot9-md-0-whg8j" does not exist
I0906 04:56:10.744573       1 controller_utils.go:189] Controller expectations fulfilled &controller.ControlleeExpectations{add:0, del:0, key:"kube-system/kube-proxy", timestamp:time.Time{wall:0xc0bdd29a6feac5a4, ext:141233417236, loc:(*time.Location)(0x731ea80)}}
I0906 04:56:10.744793       1 controller_utils.go:223] Setting expectations &controller.ControlleeExpectations{add:1, del:0, key:"kube-system/kube-proxy", timestamp:time.Time{wall:0xc0bdd29aac648d65, ext:142174289365, loc:(*time.Location)(0x731ea80)}}
I0906 04:56:10.744854       1 daemon_controller.go:968] Nodes needing daemon pods for daemon set kube-proxy: [capz-b2pot9-md-0-whg8j], creating 1
I0906 04:56:10.745359       1 taint_manager.go:435] "Noticed node update" node={nodeName:capz-b2pot9-md-0-whg8j}
I0906 04:56:10.745382       1 taint_manager.go:440] "Updating known taints on node" node="capz-b2pot9-md-0-whg8j" taints=[]
I0906 04:56:10.747570       1 controller_utils.go:189] Controller expectations fulfilled &controller.ControlleeExpectations{add:0, del:0, key:"kube-system/calico-node", timestamp:time.Time{wall:0xc0bdd29a8c4a5976, ext:141635701222, loc:(*time.Location)(0x731ea80)}}
... skipping 367 lines ...
I0906 04:56:32.138611       1 reflector.go:381] k8s.io/client-go/informers/factory.go:134: forcing resync
I0906 04:56:32.143768       1 reflector.go:381] k8s.io/client-go/informers/factory.go:134: forcing resync
I0906 04:56:32.202241       1 azure_vmss.go:369] Can not extract scale set name from providerID (azure:///subscriptions/0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e/resourceGroups/capz-b2pot9/providers/Microsoft.Compute/virtualMachines/capz-b2pot9-md-0-r4r48), assuming it is managed by availability set: not a vmss instance
I0906 04:56:32.202478       1 azure_vmss.go:369] Can not extract scale set name from providerID (azure:///subscriptions/0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e/resourceGroups/capz-b2pot9/providers/Microsoft.Compute/virtualMachines/capz-b2pot9-md-0-r4r48), assuming it is managed by availability set: not a vmss instance
I0906 04:56:32.202567       1 azure_instances.go:239] InstanceShutdownByProviderID gets power status "running" for node "capz-b2pot9-md-0-r4r48"
I0906 04:56:32.202589       1 azure_instances.go:250] InstanceShutdownByProviderID gets provisioning state "Updating" for node "capz-b2pot9-md-0-r4r48"
I0906 04:56:32.208501       1 node_lifecycle_controller.go:1039] ReadyCondition for Node capz-b2pot9-md-0-whg8j transitioned from &NodeCondition{Type:Ready,Status:False,LastHeartbeatTime:2022-09-06 04:56:20 +0000 UTC,LastTransitionTime:2022-09-06 04:56:10 +0000 UTC,Reason:KubeletNotReady,Message:container runtime network not ready: NetworkReady=false reason:NetworkPluginNotReady message:Network plugin returns error: cni plugin not initialized,} to &NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2022-09-06 04:56:30 +0000 UTC,LastTransitionTime:2022-09-06 04:56:30 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status. AppArmor enabled,}
I0906 04:56:32.208570       1 node_lifecycle_controller.go:1047] Node capz-b2pot9-md-0-whg8j ReadyCondition updated. Updating timestamp.
I0906 04:56:32.221322       1 node_lifecycle_controller.go:893] Node capz-b2pot9-md-0-whg8j is healthy again, removing all taints
I0906 04:56:32.221365       1 node_lifecycle_controller.go:1214] Controller detected that zone canadacentral::0 is now in state Normal.
I0906 04:56:32.221599       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-b2pot9-md-0-whg8j"
I0906 04:56:32.221950       1 taint_manager.go:435] "Noticed node update" node={nodeName:capz-b2pot9-md-0-whg8j}
I0906 04:56:32.221979       1 taint_manager.go:440] "Updating known taints on node" node="capz-b2pot9-md-0-whg8j" taints=[]
... skipping 76 lines ...
I0906 04:56:41.886506       1 controller_utils.go:189] Controller expectations fulfilled &controller.ControlleeExpectations{add:0, del:0, key:"kube-system/calico-node", timestamp:time.Time{wall:0xc0bdd2a274c2275d, ext:173314641457, loc:(*time.Location)(0x731ea80)}}
I0906 04:56:41.886892       1 controller_utils.go:223] Setting expectations &controller.ControlleeExpectations{add:0, del:0, key:"kube-system/calico-node", timestamp:time.Time{wall:0xc0bdd2a274dccdb7, ext:173316387979, loc:(*time.Location)(0x731ea80)}}
I0906 04:56:41.887081       1 daemon_controller.go:968] Nodes needing daemon pods for daemon set calico-node: [], creating 0
I0906 04:56:41.887295       1 daemon_controller.go:1030] Pods to delete for daemon set calico-node: [], deleting 0
I0906 04:56:41.887510       1 daemon_controller.go:1103] Updating daemon set status
I0906 04:56:41.887812       1 daemon_controller.go:1163] Finished syncing daemon set "kube-system/calico-node" (4.929763ms)
I0906 04:56:42.223095       1 node_lifecycle_controller.go:1039] ReadyCondition for Node capz-b2pot9-md-0-r4r48 transitioned from &NodeCondition{Type:Ready,Status:False,LastHeartbeatTime:2022-09-06 04:56:17 +0000 UTC,LastTransitionTime:2022-09-06 04:56:07 +0000 UTC,Reason:KubeletNotReady,Message:container runtime network not ready: NetworkReady=false reason:NetworkPluginNotReady message:Network plugin returns error: cni plugin not initialized,} to &NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2022-09-06 04:56:37 +0000 UTC,LastTransitionTime:2022-09-06 04:56:37 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status. AppArmor enabled,}
I0906 04:56:42.223153       1 node_lifecycle_controller.go:1047] Node capz-b2pot9-md-0-r4r48 ReadyCondition updated. Updating timestamp.
I0906 04:56:42.231169       1 node_lifecycle_controller.go:893] Node capz-b2pot9-md-0-r4r48 is healthy again, removing all taints
I0906 04:56:42.231519       1 node_lifecycle_controller.go:1047] Node capz-b2pot9-md-0-whg8j ReadyCondition updated. Updating timestamp.
I0906 04:56:42.233510       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-b2pot9-md-0-r4r48"
I0906 04:56:42.236647       1 taint_manager.go:435] "Noticed node update" node={nodeName:capz-b2pot9-md-0-r4r48}
I0906 04:56:42.236815       1 taint_manager.go:440] "Updating known taints on node" node="capz-b2pot9-md-0-r4r48" taints=[]
... skipping 132 lines ...
I0906 04:59:02.871624       1 pv_controller.go:1764] operation "provision-azurefile-5194/pvc-stn68[d7dc3bbe-0db8-412b-a669-fc57cdc8c114]" is already running, skipping
I0906 04:59:02.871681       1 pvc_protection_controller.go:353] "Got event on PVC" pvc="azurefile-5194/pvc-stn68"
I0906 04:59:02.871835       1 pv_controller_base.go:640] storeObjectUpdate updating claim "azurefile-5194/pvc-stn68" with version 1321
I0906 04:59:02.944111       1 azure_storageaccount.go:184] azure - no matching account found, begin to create a new account f352c0a2adea24ac09ac500 in resource group capz-b2pot9, location: canadacentral, accountType: Standard_LRS, accountKind: StorageV2, tags: map[created-by:azure]
I0906 04:59:03.998279       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-8081
I0906 04:59:04.055083       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-8081, name default-token-g67st, uid 613c0538-c369-4809-993d-ef1f8ba9f719, event type delete
E0906 04:59:04.068834       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-8081/default: secrets "default-token-xh6bm" is forbidden: unable to create new content in namespace azurefile-8081 because it is being terminated
I0906 04:59:04.090060       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-8081, name kube-root-ca.crt, uid 8c3e1d13-a896-4a44-9f84-ce5d6a2bea63, event type delete
I0906 04:59:04.093334       1 publisher.go:181] Finished syncing namespace "azurefile-8081" (3.376645ms)
I0906 04:59:04.109791       1 tokens_controller.go:252] syncServiceAccount(azurefile-8081/default), service account deleted, removing tokens
I0906 04:59:04.109920       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-8081" (2.8µs)
I0906 04:59:04.109999       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-8081, name default, uid 8cd768d9-f3a0-4231-910e-b2a9a57cf8ef, event type delete
I0906 04:59:04.137868       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-8081" (3µs)
... skipping 41 lines ...
I0906 04:59:06.799924       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-2790, estimate: 0, errors: <nil>
I0906 04:59:06.807049       1 namespace_controller.go:180] Finished syncing namespace "azurefile-2790" (143.373102ms)
I0906 04:59:07.276678       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-5356
I0906 04:59:07.310068       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-5356, name default-token-8jt7k, uid 13e83701-61d0-4045-adf8-35e3ab052fcf, event type delete
I0906 04:59:07.327042       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-5356, name kube-root-ca.crt, uid 28b14d01-2485-41f0-946e-0cc89633e776, event type delete
I0906 04:59:07.328397       1 publisher.go:181] Finished syncing namespace "azurefile-5356" (2.40663ms)
E0906 04:59:07.330178       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-5356/default: secrets "default-token-blc6m" is forbidden: unable to create new content in namespace azurefile-5356 because it is being terminated
I0906 04:59:07.365568       1 tokens_controller.go:252] syncServiceAccount(azurefile-5356/default), service account deleted, removing tokens
I0906 04:59:07.365846       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-5356" (2.9µs)
I0906 04:59:07.366029       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-5356, name default, uid 64c60a37-1d92-4c3f-801f-990604412ac5, event type delete
I0906 04:59:07.430063       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-5356" (2.6µs)
I0906 04:59:07.430308       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-5356, estimate: 0, errors: <nil>
I0906 04:59:07.438966       1 namespace_controller.go:180] Finished syncing namespace "azurefile-5356" (165.778692ms)
... skipping 298 lines ...
I0906 04:59:39.896480       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-5194, name azurefile-volume-tester-pb9jv.17122cb4f0d03e2b, uid 972c1c85-47d4-4810-9911-b916dbf9cd86, event type delete
I0906 04:59:39.900274       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-5194, name azurefile-volume-tester-pb9jv.17122cb4f9ae57e5, uid 9f2fdd61-5a1c-4582-99e0-bfbbd8df389f, event type delete
I0906 04:59:39.902653       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-5194, name pvc-stn68.17122cb40b0b30e7, uid cd005274-17b0-4994-bfbc-415f0cf04832, event type delete
I0906 04:59:39.917184       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-5194, name kube-root-ca.crt, uid 7f499bdb-5537-43b6-8510-af76d03927c9, event type delete
I0906 04:59:39.920834       1 publisher.go:181] Finished syncing namespace "azurefile-5194" (3.75835ms)
I0906 04:59:39.937481       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-5194, name default-token-d46jk, uid cd8c4925-ccf2-4c59-8fbe-dde8ec960975, event type delete
E0906 04:59:39.950367       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-5194/default: secrets "default-token-bjx5p" is forbidden: unable to create new content in namespace azurefile-5194 because it is being terminated
I0906 04:59:40.019185       1 tokens_controller.go:252] syncServiceAccount(azurefile-5194/default), service account deleted, removing tokens
I0906 04:59:40.019473       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-5194" (3.701µs)
I0906 04:59:40.019526       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-5194, name default, uid 2a22f620-e655-419e-a75d-f89925b3126b, event type delete
I0906 04:59:40.027210       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-5194" (1.8µs)
I0906 04:59:40.028380       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-5194, estimate: 0, errors: <nil>
I0906 04:59:40.041679       1 namespace_controller.go:180] Finished syncing namespace "azurefile-5194" (187.526177ms)
... skipping 605 lines ...
I0906 05:01:08.551028       1 pv_controller.go:1764] operation "provision-azurefile-156/pvc-q2l5m[8f2449d5-0b73-4c25-a343-fa86201e3b07]" is already running, skipping
I0906 05:01:08.551054       1 pvc_protection_controller.go:353] "Got event on PVC" pvc="azurefile-156/pvc-q2l5m"
I0906 05:01:08.551867       1 pv_controller_base.go:640] storeObjectUpdate updating claim "azurefile-156/pvc-q2l5m" with version 1671
I0906 05:01:08.596452       1 azure_storageaccount.go:184] azure - no matching account found, begin to create a new account f66366cb431c74186ac31da in resource group capz-b2pot9, location: canadacentral, accountType: Standard_GRS, accountKind: StorageV2, tags: map[created-by:azure]
I0906 05:01:12.902322       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-2888
I0906 05:01:12.932356       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-2888, name default-token-tb52h, uid d438503e-fb19-4664-a67d-c1b2c3d548bf, event type delete
E0906 05:01:12.956084       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-2888/default: secrets "default-token-8t55d" is forbidden: unable to create new content in namespace azurefile-2888 because it is being terminated
I0906 05:01:12.971381       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-2888, name azurefile-volume-tester-2mg6z.17122cb8cf35d58c, uid d408d81b-e27d-4027-8ed0-155f7545d5e6, event type delete
I0906 05:01:12.974373       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-2888, name azurefile-volume-tester-2mg6z.17122cb8f6788e19, uid cbb8fb31-91ef-44e6-b476-dce5842b48dc, event type delete
I0906 05:01:12.980573       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-2888, name azurefile-volume-tester-2mg6z.17122cb8f9be43b8, uid 759436f0-da65-4740-9586-ab51bfa114f3, event type delete
I0906 05:01:12.983474       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-2888, name azurefile-volume-tester-2mg6z.17122cb904664737, uid 2cfeda1b-a488-4ad5-a635-087b485349b5, event type delete
I0906 05:01:12.993845       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-2888, name azurefile-volume-tester-2mg6z.17122cb9a395f778, uid 06743a59-ef11-42d8-87f7-da1913de4b54, event type delete
I0906 05:01:12.996580       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-2888, name azurefile-volume-tester-b2q9m.17122cb756fcf7b7, uid acb62136-c2ba-41d3-ab74-3976e9e64498, event type delete
... skipping 328 lines ...
I0906 05:01:43.435296       1 disruption.go:418] No matching pdb for pod "azurefile-volume-tester-j2j9r-867d9859b5-cbfpc"
I0906 05:01:43.434925       1 taint_manager.go:400] "Noticed pod update" pod="azurefile-1563/azurefile-volume-tester-j2j9r-867d9859b5-cbfpc"
I0906 05:01:43.434961       1 pvc_protection_controller.go:402] "Enqueuing PVCs for Pod" pod="azurefile-1563/azurefile-volume-tester-j2j9r-867d9859b5-cbfpc" podUID=f5de4205-2f5d-4478-8164-eb291fdab50d
I0906 05:01:43.435614       1 pvc_protection_controller.go:156] "Processing PVC" PVC="azurefile-1563/pvc-zgfpx"
I0906 05:01:43.435738       1 pvc_protection_controller.go:159] "Finished processing PVC" PVC="azurefile-1563/pvc-zgfpx" duration="6.1µs"
I0906 05:01:43.438219       1 deployment_controller.go:578] "Finished syncing deployment" deployment="azurefile-1563/azurefile-volume-tester-j2j9r" duration="23.230196ms"
I0906 05:01:43.438432       1 deployment_controller.go:490] "Error syncing deployment" deployment="azurefile-1563/azurefile-volume-tester-j2j9r" err="Operation cannot be fulfilled on deployments.apps \"azurefile-volume-tester-j2j9r\": the object has been modified; please apply your changes to the latest version and try again"
I0906 05:01:43.438617       1 deployment_controller.go:576] "Started syncing deployment" deployment="azurefile-1563/azurefile-volume-tester-j2j9r" startTime="2022-09-06 05:01:43.438592097 +0000 UTC m=+474.868094161"
I0906 05:01:43.439146       1 deployment_util.go:808] Deployment "azurefile-volume-tester-j2j9r" timed out (false) [last progress check: 2022-09-06 05:01:43 +0000 UTC - now: 2022-09-06 05:01:43.439137304 +0000 UTC m=+474.868639468]
I0906 05:01:43.440862       1 disruption.go:427] updatePod called on pod "azurefile-volume-tester-j2j9r-867d9859b5-cbfpc"
I0906 05:01:43.441015       1 disruption.go:490] No PodDisruptionBudgets found for pod azurefile-volume-tester-j2j9r-867d9859b5-cbfpc, PodDisruptionBudget controller will avoid syncing.
I0906 05:01:43.441113       1 disruption.go:430] No matching pdb for pod "azurefile-volume-tester-j2j9r-867d9859b5-cbfpc"
I0906 05:01:43.441259       1 taint_manager.go:400] "Noticed pod update" pod="azurefile-1563/azurefile-volume-tester-j2j9r-867d9859b5-cbfpc"
... skipping 799 lines ...
I0906 05:03:44.873113       1 httplog.go:94] "HTTP" verb="GET" URI="/healthz" latency="71.301µs" userAgent="kube-probe/1.21+" srcIP="127.0.0.1:52252" resp=200
I0906 05:03:45.296773       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-7996
I0906 05:03:45.328091       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-7996, name kube-root-ca.crt, uid 01905ae5-b587-408b-8158-143e273ad7d9, event type delete
I0906 05:03:45.329705       1 publisher.go:181] Finished syncing namespace "azurefile-7996" (1.800423ms)
I0906 05:03:45.340829       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-7996, name azure-storage-account-f6d9bd617e48d40259721f9-secret, uid 57e940d9-c708-49c3-95b8-0560a2556a03, event type delete
I0906 05:03:45.343788       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-7996, name default-token-w59fl, uid 8775974c-5397-4ca1-9bc3-dc3b0df0aeb8, event type delete
E0906 05:03:45.354605       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-7996/default: secrets "default-token-7b7wr" is forbidden: unable to create new content in namespace azurefile-7996 because it is being terminated
I0906 05:03:45.357514       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-7996, name pvc-bxzj2.17122cee029d893f, uid 4e8b9f39-3849-4b68-bff4-3c57dc76fcc2, event type delete
I0906 05:03:45.407013       1 tokens_controller.go:252] syncServiceAccount(azurefile-7996/default), service account deleted, removing tokens
I0906 05:03:45.407125       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-7996" (2.7µs)
I0906 05:03:45.407204       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-7996, name default, uid 1045a647-0edc-4588-9bd4-689f3ca78af0, event type delete
I0906 05:03:45.439298       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-7996, estimate: 0, errors: <nil>
I0906 05:03:45.439572       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-7996" (3.4µs)
... skipping 419 lines ...
I0906 05:04:32.262262       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-9183
I0906 05:04:32.282503       1 namespace_controller.go:185] Namespace has been deleted azurefile-2546
I0906 05:04:32.282638       1 namespace_controller.go:180] Finished syncing namespace "azurefile-2546" (155.402µs)
I0906 05:04:32.293155       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1393" (7.04909ms)
I0906 05:04:32.293507       1 publisher.go:181] Finished syncing namespace "azurefile-1393" (7.186592ms)
I0906 05:04:32.301039       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-9183, name default-token-pbns2, uid 09bb35c9-b3ee-4403-9820-64ed5bc084b6, event type delete
E0906 05:04:32.313686       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-9183/default: secrets "default-token-vmqsj" is forbidden: unable to create new content in namespace azurefile-9183 because it is being terminated
I0906 05:04:32.362237       1 pv_controller_base.go:528] resyncing PV controller
I0906 05:04:32.380750       1 tokens_controller.go:252] syncServiceAccount(azurefile-9183/default), service account deleted, removing tokens
I0906 05:04:32.381124       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-9183" (3.2µs)
I0906 05:04:32.381332       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-9183, name default, uid b4346ceb-e03a-4960-8f6e-47bcb6f57dfc, event type delete
I0906 05:04:32.423672       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-9183, name kube-root-ca.crt, uid b55d05eb-e4cd-4119-b427-4b4a2eb7ca2d, event type delete
I0906 05:04:32.425124       1 publisher.go:181] Finished syncing namespace "azurefile-9183" (1.700622ms)
I0906 05:04:32.442552       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-9183" (3.3µs)
I0906 05:04:32.442842       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-9183, estimate: 0, errors: <nil>
I0906 05:04:32.451282       1 namespace_controller.go:180] Finished syncing namespace "azurefile-9183" (191.448545ms)
I0906 05:04:32.824712       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-7578
I0906 05:04:32.834866       1 namespace_controller.go:185] Namespace has been deleted azurefile-1598
I0906 05:04:32.834891       1 namespace_controller.go:180] Finished syncing namespace "azurefile-1598" (47.401µs)
I0906 05:04:32.849795       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-7578, name default-token-rbbl9, uid 4523e165-3995-4952-994d-07337994b3f9, event type delete
E0906 05:04:32.866744       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-7578/default: secrets "default-token-6knrx" is forbidden: unable to create new content in namespace azurefile-7578 because it is being terminated
I0906 05:04:32.870391       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1393" (2.1µs)
I0906 05:04:32.896745       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-7578, name kube-root-ca.crt, uid 6580e59a-e72e-4155-bf47-54f58485fbb8, event type delete
I0906 05:04:32.899431       1 publisher.go:181] Finished syncing namespace "azurefile-7578" (2.888237ms)
I0906 05:04:32.913693       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-3922" (6.502883ms)
I0906 05:04:32.913799       1 publisher.go:181] Finished syncing namespace "azurefile-3922" (6.29498ms)
I0906 05:04:32.938101       1 tokens_controller.go:252] syncServiceAccount(azurefile-7578/default), service account deleted, removing tokens
... skipping 5 lines ...
I0906 05:04:33.041009       1 resource_quota_controller.go:424] no resource updates from discovery, skipping resource quota sync
I0906 05:04:33.380810       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-3922" (3µs)
I0906 05:04:33.411729       1 namespace_controller.go:185] Namespace has been deleted azurefile-3410
I0906 05:04:33.411756       1 namespace_controller.go:180] Finished syncing namespace "azurefile-3410" (50.601µs)
I0906 05:04:33.425489       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-1968
I0906 05:04:33.448059       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-1968, name default-token-g8hgb, uid 846a6658-d2a5-402f-84d9-300605ce8445, event type delete
E0906 05:04:33.460619       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-1968/default: secrets "default-token-vcn9l" is forbidden: unable to create new content in namespace azurefile-1968 because it is being terminated
I0906 05:04:33.528952       1 tokens_controller.go:252] syncServiceAccount(azurefile-1968/default), service account deleted, removing tokens
I0906 05:04:33.529088       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1968" (3.3µs)
I0906 05:04:33.529150       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-1968, name default, uid 627547c1-54be-4d54-84a5-47ddd2d49d81, event type delete
I0906 05:04:33.551109       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-1968, name kube-root-ca.crt, uid 6e21b721-6f71-464f-8f12-459a2f9db974, event type delete
I0906 05:04:33.556545       1 publisher.go:181] Finished syncing namespace "azurefile-1968" (5.579171ms)
I0906 05:04:33.565092       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1968" (2.2µs)
I0906 05:04:33.568645       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-1968, estimate: 0, errors: <nil>
I0906 05:04:33.588483       1 namespace_controller.go:180] Finished syncing namespace "azurefile-1968" (165.640016ms)
I0906 05:04:34.002443       1 namespace_controller.go:185] Namespace has been deleted azurefile-8582
I0906 05:04:34.002471       1 namespace_controller.go:180] Finished syncing namespace "azurefile-8582" (51.701µs)
I0906 05:04:34.039761       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-4657
I0906 05:04:34.066584       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-4657, name default-token-2w8xp, uid 02617898-a30d-4b10-9c67-2588fa7f3f79, event type delete
E0906 05:04:34.080332       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-4657/default: secrets "default-token-k278w" is forbidden: unable to create new content in namespace azurefile-4657 because it is being terminated
I0906 05:04:34.157959       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-4657, name kube-root-ca.crt, uid fdb21329-1547-45a2-b9f3-65ed5a4d5c6d, event type delete
I0906 05:04:34.160863       1 publisher.go:181] Finished syncing namespace "azurefile-4657" (3.14464ms)
I0906 05:04:34.171663       1 tokens_controller.go:252] syncServiceAccount(azurefile-4657/default), service account deleted, removing tokens
I0906 05:04:34.172015       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-4657, name default, uid 836815d2-f1f8-45ac-b91d-306bbf395121, event type delete
I0906 05:04:34.172072       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-4657" (2.3µs)
I0906 05:04:34.181689       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-4657" (3.9µs)
... skipping 14 lines ...
I0906 05:04:34.874233       1 httplog.go:94] "HTTP" verb="GET" URI="/healthz" latency="74µs" userAgent="kube-probe/1.21+" srcIP="127.0.0.1:40004" resp=200
2022/09/06 05:04:35 ===================================================

JUnit report was created: /logs/artifacts/junit_01.xml

Ran 6 of 34 Specs in 336.951 seconds
SUCCESS! -- 6 Passed | 0 Failed | 0 Pending | 28 Skipped

You're using deprecated Ginkgo functionality:
=============================================
Ginkgo 2.0 is under active development and will introduce several new features, improvements, and a small handful of breaking changes.
A release candidate for 2.0 is now available and 2.0 should GA in Fall 2021.  Please give the RC a try and send us feedback!
  - To learn more, view the migration guide at https://github.com/onsi/ginkgo/blob/ver2/docs/MIGRATING_TO_V2.md
... skipping 37 lines ...
INFO: Creating log watcher for controller capz-system/capz-controller-manager, pod capz-controller-manager-858df9cd95-nd6f9, container manager
STEP: Dumping workload cluster default/capz-b2pot9 logs
Sep  6 05:06:01.819: INFO: Collecting logs for Linux node capz-b2pot9-control-plane-xft6z in cluster capz-b2pot9 in namespace default

Sep  6 05:07:01.821: INFO: Collecting boot logs for AzureMachine capz-b2pot9-control-plane-xft6z

Failed to get logs for machine capz-b2pot9-control-plane-7g4kz, cluster default/capz-b2pot9: open /etc/azure-ssh/azure-ssh: no such file or directory
Sep  6 05:07:02.903: INFO: Collecting logs for Linux node capz-b2pot9-md-0-whg8j in cluster capz-b2pot9 in namespace default

Sep  6 05:08:02.905: INFO: Collecting boot logs for AzureMachine capz-b2pot9-md-0-whg8j

Failed to get logs for machine capz-b2pot9-md-0-7c846dd49b-5npqb, cluster default/capz-b2pot9: open /etc/azure-ssh/azure-ssh: no such file or directory
Sep  6 05:08:03.287: INFO: Collecting logs for Linux node capz-b2pot9-md-0-r4r48 in cluster capz-b2pot9 in namespace default

Sep  6 05:09:03.290: INFO: Collecting boot logs for AzureMachine capz-b2pot9-md-0-r4r48

Failed to get logs for machine capz-b2pot9-md-0-7c846dd49b-l8b7z, cluster default/capz-b2pot9: open /etc/azure-ssh/azure-ssh: no such file or directory
STEP: Dumping workload cluster default/capz-b2pot9 kube-system pod logs
STEP: Fetching kube-system pod logs took 491.581276ms
STEP: Dumping workload cluster default/capz-b2pot9 Azure activity log
STEP: Creating log watcher for controller kube-system/calico-node-dzrzw, container calico-node
STEP: Creating log watcher for controller kube-system/kube-proxy-28grc, container kube-proxy
STEP: Collecting events for Pod kube-system/coredns-558bd4d5db-kjhhx
... skipping 43 lines ...