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

No Test Failures!


Show 6 Passed Tests

Show 28 Skipped Tests

Error lines from build-log.txt

... skipping 625 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 239 lines ...
Sep  5 04:59:21.943: INFO: PersistentVolumeClaim pvc-r5hv5 found but phase is Pending instead of Bound.
Sep  5 04:59:24.047: INFO: PersistentVolumeClaim pvc-r5hv5 found and phase=Bound (21.156629853s)
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  5 04:59:24.358: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-2x2xr" in namespace "azurefile-2540" to be "Succeeded or Failed"
Sep  5 04:59:24.461: INFO: Pod "azurefile-volume-tester-2x2xr": Phase="Pending", Reason="", readiness=false. Elapsed: 102.439647ms
Sep  5 04:59:26.565: INFO: Pod "azurefile-volume-tester-2x2xr": Phase="Pending", Reason="", readiness=false. Elapsed: 2.206482213s
Sep  5 04:59:28.675: INFO: Pod "azurefile-volume-tester-2x2xr": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.316815064s
STEP: Saw pod success
Sep  5 04:59:28.675: INFO: Pod "azurefile-volume-tester-2x2xr" satisfied condition "Succeeded or Failed"
Sep  5 04:59:28.675: INFO: deleting Pod "azurefile-2540"/"azurefile-volume-tester-2x2xr"
Sep  5 04:59:28.793: INFO: Pod azurefile-volume-tester-2x2xr has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-2x2xr in namespace azurefile-2540
Sep  5 04:59:28.911: INFO: deleting PVC "azurefile-2540"/"pvc-r5hv5"
Sep  5 04:59:28.911: INFO: Deleting PersistentVolumeClaim "pvc-r5hv5"
... skipping 158 lines ...
Sep  5 05:01:35.704: INFO: PersistentVolumeClaim pvc-dglcf found but phase is Pending instead of Bound.
Sep  5 05:01:37.809: INFO: PersistentVolumeClaim pvc-dglcf found and phase=Bound (23.252396581s)
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  5 05:01:38.128: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-j4964" in namespace "azurefile-2790" to be "Error status code"
Sep  5 05:01:38.230: INFO: Pod "azurefile-volume-tester-j4964": Phase="Pending", Reason="", readiness=false. Elapsed: 102.015436ms
Sep  5 05:01:40.339: INFO: Pod "azurefile-volume-tester-j4964": Phase="Failed", Reason="", readiness=false. Elapsed: 2.211455627s
STEP: Saw pod failure
Sep  5 05:01:40.339: INFO: Pod "azurefile-volume-tester-j4964" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Sep  5 05:01:40.445: INFO: deleting Pod "azurefile-2790"/"azurefile-volume-tester-j4964"
Sep  5 05:01:40.551: INFO: Pod azurefile-volume-tester-j4964 has the following logs: touch: /mnt/test-1/data: Read-only file system

STEP: Deleting pod azurefile-volume-tester-j4964 in namespace azurefile-2790
Sep  5 05:01:40.666: INFO: deleting PVC "azurefile-2790"/"pvc-dglcf"
... skipping 181 lines ...
Sep  5 05:03:57.532: INFO: PersistentVolumeClaim pvc-wtl8z found but phase is Pending instead of Bound.
Sep  5 05:03:59.642: INFO: PersistentVolumeClaim pvc-wtl8z found and phase=Bound (2.212337891s)
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  5 05:03:59.953: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-cl4qt" in namespace "azurefile-4538" to be "Succeeded or Failed"
Sep  5 05:04:00.056: INFO: Pod "azurefile-volume-tester-cl4qt": Phase="Pending", Reason="", readiness=false. Elapsed: 102.81137ms
Sep  5 05:04:02.165: INFO: Pod "azurefile-volume-tester-cl4qt": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.212284991s
STEP: Saw pod success
Sep  5 05:04:02.165: INFO: Pod "azurefile-volume-tester-cl4qt" 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  5 05:04:33.282: INFO: deleting Pod "azurefile-4538"/"azurefile-volume-tester-cl4qt"
... skipping 869 lines ...
I0905 04:54:17.602362       1 named_certificates.go:53] loaded SNI cert [0/"self-signed loopback"]: "apiserver-loopback-client@1662353657" [serving] validServingFor=[apiserver-loopback-client] issuer="apiserver-loopback-client-ca@1662353657" (2022-09-05 03:54:16 +0000 UTC to 2023-09-05 03:54:16 +0000 UTC (now=2022-09-05 04:54:17.602335301 +0000 UTC))
I0905 04:54:17.602462       1 secure_serving.go:202] Serving securely on 127.0.0.1:10257
I0905 04:54:17.603197       1 leaderelection.go:243] attempting to acquire leader lease kube-system/kube-controller-manager...
I0905 04:54:17.604255       1 dynamic_cafile_content.go:167] Starting request-header::/etc/kubernetes/pki/front-proxy-ca.crt
I0905 04:54:17.604418       1 tlsconfig.go:240] Starting DynamicServingCertificateController
I0905 04:54:17.604614       1 dynamic_cafile_content.go:167] Starting client-ca-bundle::/etc/kubernetes/pki/ca.crt
E0905 04:54:19.885368       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"
I0905 04:54:19.885405       1 leaderelection.go:248] failed to acquire lease kube-system/kube-controller-manager
I0905 04:54:22.946832       1 leaderelection.go:253] successfully acquired lease kube-system/kube-controller-manager
I0905 04:54:22.947632       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-ky5ra6-control-plane-7ks5g_b1f458d7-b264-4775-b09d-8ca7551d08ac became leader"
I0905 04:54:23.059352       1 request.go:600] Waited for 94.948367ms due to client-side throttling, not priority and fairness, request: GET:https://10.0.0.4:6443/apis/admissionregistration.k8s.io/v1beta1?timeout=32s
I0905 04:54:23.109325       1 request.go:600] Waited for 144.910244ms due to client-side throttling, not priority and fairness, request: GET:https://10.0.0.4:6443/apis/apiextensions.k8s.io/v1?timeout=32s
I0905 04:54:23.159039       1 request.go:600] Waited for 194.603823ms due to client-side throttling, not priority and fairness, request: GET:https://10.0.0.4:6443/apis/apiextensions.k8s.io/v1beta1?timeout=32s
I0905 04:54:23.209466       1 request.go:600] Waited for 244.976496ms due to client-side throttling, not priority and fairness, request: GET:https://10.0.0.4:6443/apis/scheduling.k8s.io/v1?timeout=32s
... skipping 39 lines ...
I0905 04:54:23.514309       1 reflector.go:219] Starting reflector *v1.Node (19h12m53.956419433s) from k8s.io/client-go/informers/factory.go:134
I0905 04:54:23.514348       1 reflector.go:255] Listing and watching *v1.Node from k8s.io/client-go/informers/factory.go:134
I0905 04:54:23.514779       1 reflector.go:219] Starting reflector *v1.ServiceAccount (19h12m53.956419433s) from k8s.io/client-go/informers/factory.go:134
I0905 04:54:23.514797       1 reflector.go:255] Listing and watching *v1.ServiceAccount from k8s.io/client-go/informers/factory.go:134
I0905 04:54:23.514926       1 reflector.go:219] Starting reflector *v1.Secret (19h12m53.956419433s) from k8s.io/client-go/informers/factory.go:134
I0905 04:54:23.514960       1 reflector.go:255] Listing and watching *v1.Secret from k8s.io/client-go/informers/factory.go:134
W0905 04:54:23.571276       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
I0905 04:54:23.571310       1 controllermanager.go:559] Starting "endpoint"
I0905 04:54:23.591140       1 controllermanager.go:574] Started "endpoint"
I0905 04:54:23.591188       1 controllermanager.go:559] Starting "endpointslice"
I0905 04:54:23.591349       1 endpoints_controller.go:189] Starting endpoint controller
I0905 04:54:23.591359       1 shared_informer.go:240] Waiting for caches to sync for endpoint
I0905 04:54:23.608152       1 controllermanager.go:574] Started "endpointslice"
... skipping 22 lines ...
I0905 04:54:23.732370       1 plugins.go:639] Loaded volume plugin "kubernetes.io/azure-file"
I0905 04:54:23.732383       1 plugins.go:639] Loaded volume plugin "kubernetes.io/flocker"
I0905 04:54:23.732457       1 plugins.go:639] Loaded volume plugin "kubernetes.io/portworx-volume"
I0905 04:54:23.732586       1 plugins.go:639] Loaded volume plugin "kubernetes.io/scaleio"
I0905 04:54:23.732764       1 plugins.go:639] Loaded volume plugin "kubernetes.io/local-volume"
I0905 04:54:23.732826       1 plugins.go:639] Loaded volume plugin "kubernetes.io/storageos"
I0905 04:54:23.732926       1 csi_plugin.go:256] Cast from VolumeHost to KubeletVolumeHost failed. Skipping CSINode initialization, not running on kubelet
I0905 04:54:23.733013       1 plugins.go:639] Loaded volume plugin "kubernetes.io/csi"
I0905 04:54:23.733208       1 controllermanager.go:574] Started "persistentvolume-binder"
I0905 04:54:23.733392       1 controllermanager.go:559] Starting "ephemeral-volume"
I0905 04:54:23.733354       1 pv_controller_base.go:308] Starting persistent volume controller
I0905 04:54:23.733786       1 shared_informer.go:240] Waiting for caches to sync for persistent volume
I0905 04:54:23.751655       1 controllermanager.go:574] Started "ephemeral-volume"
... skipping 12 lines ...
I0905 04:54:23.786261       1 plugins.go:639] Loaded volume plugin "kubernetes.io/portworx-volume"
I0905 04:54:23.786300       1 plugins.go:639] Loaded volume plugin "kubernetes.io/scaleio"
I0905 04:54:23.786312       1 plugins.go:639] Loaded volume plugin "kubernetes.io/storageos"
I0905 04:54:23.786326       1 plugins.go:639] Loaded volume plugin "kubernetes.io/fc"
I0905 04:54:23.786337       1 plugins.go:639] Loaded volume plugin "kubernetes.io/iscsi"
I0905 04:54:23.786346       1 plugins.go:639] Loaded volume plugin "kubernetes.io/rbd"
I0905 04:54:23.786367       1 csi_plugin.go:256] Cast from VolumeHost to KubeletVolumeHost failed. Skipping CSINode initialization, not running on kubelet
I0905 04:54:23.786376       1 plugins.go:639] Loaded volume plugin "kubernetes.io/csi"
I0905 04:54:23.786480       1 controllermanager.go:574] Started "attachdetach"
I0905 04:54:23.786500       1 controllermanager.go:559] Starting "ttl-after-finished"
I0905 04:54:23.785156       1 azure_vmss.go:369] Can not extract scale set name from providerID (azure:///subscriptions/0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e/resourceGroups/capz-ky5ra6/providers/Microsoft.Compute/virtualMachines/capz-ky5ra6-control-plane-7ks5g), assuming it is managed by availability set: not a vmss instance
I0905 04:54:23.788270       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-ky5ra6-control-plane-7ks5g"
W0905 04:54:23.788327       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-ky5ra6-control-plane-7ks5g" does not exist
I0905 04:54:23.788355       1 attach_detach_controller.go:328] Starting attach detach controller
I0905 04:54:23.788363       1 shared_informer.go:240] Waiting for caches to sync for attach detach
I0905 04:54:23.886389       1 controllermanager.go:574] Started "ttl-after-finished"
I0905 04:54:23.886440       1 controllermanager.go:559] Starting "root-ca-cert-publisher"
I0905 04:54:23.886925       1 ttlafterfinished_controller.go:109] Starting TTL after finished controller
I0905 04:54:23.886937       1 shared_informer.go:240] Waiting for caches to sync for TTL after finished
... skipping 699 lines ...
I0905 04:54:28.848059       1 endpointslicemirroring_controller.go:308] kube-system/metrics-server Service now has selector, cleaning up any mirrored EndpointSlices
I0905 04:54:28.848084       1 endpointslicemirroring_controller.go:270] Finished syncing EndpointSlices for "kube-system/metrics-server" Endpoints. (96.199µs)
I0905 04:54:28.848288       1 endpointslicemirroring_controller.go:273] syncEndpoints("kube-system/kube-dns")
I0905 04:54:28.848336       1 endpointslicemirroring_controller.go:308] kube-system/kube-dns Service now has selector, cleaning up any mirrored EndpointSlices
I0905 04:54:28.848370       1 endpointslicemirroring_controller.go:270] Finished syncing EndpointSlices for "kube-system/kube-dns" Endpoints. (100.999µs)
I0905 04:54:28.848762       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/calico-kube-controllers" duration="67.963346ms"
I0905 04:54:28.848849       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"
I0905 04:54:28.848893       1 deployment_controller.go:576] "Started syncing deployment" deployment="kube-system/calico-kube-controllers" startTime="2022-09-05 04:54:28.848871886 +0000 UTC m=+13.998050842"
I0905 04:54:28.849877       1 deployment_util.go:808] Deployment "calico-kube-controllers" timed out (false) [last progress check: 2022-09-05 04:54:28 +0000 UTC - now: 2022-09-05 04:54:28.849834681 +0000 UTC m=+13.999013637]
I0905 04:54:28.850233       1 endpoints_controller.go:381] Finished syncing service "kube-system/metrics-server" endpoints. (652.343005ms)
I0905 04:54:28.850506       1 endpoints_controller.go:381] Finished syncing service "kube-system/kube-dns" endpoints. (652.812003ms)
I0905 04:54:28.857339       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/coredns" duration="673.589994ms"
I0905 04:54:28.858548       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"
I0905 04:54:28.858602       1 deployment_controller.go:576] "Started syncing deployment" deployment="kube-system/coredns" startTime="2022-09-05 04:54:28.858582236 +0000 UTC m=+14.007761092"
I0905 04:54:28.859356       1 deployment_util.go:808] Deployment "coredns" timed out (false) [last progress check: 2022-09-05 04:54:28 +0000 UTC - now: 2022-09-05 04:54:28.859348432 +0000 UTC m=+14.008527388]
I0905 04:54:28.857565       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/metrics-server" duration="666.71993ms"
I0905 04:54:28.859630       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"
I0905 04:54:28.859667       1 deployment_controller.go:576] "Started syncing deployment" deployment="kube-system/metrics-server" startTime="2022-09-05 04:54:28.85964863 +0000 UTC m=+14.008827486"
I0905 04:54:28.860293       1 deployment_util.go:808] Deployment "metrics-server" timed out (false) [last progress check: 2022-09-05 04:54:28 +0000 UTC - now: 2022-09-05 04:54:28.860286727 +0000 UTC m=+14.009465583]
I0905 04:54:28.869032       1 request.go:600] Waited for 301.080434ms 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
I0905 04:54:28.870565       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/calico-kube-controllers" duration="21.652888ms"
I0905 04:54:28.870869       1 deployment_controller.go:576] "Started syncing deployment" deployment="kube-system/calico-kube-controllers" startTime="2022-09-05 04:54:28.870845072 +0000 UTC m=+14.020023928"
I0905 04:54:28.871503       1 deployment_util.go:808] Deployment "calico-kube-controllers" timed out (false) [last progress check: 2022-09-05 04:54:28 +0000 UTC - now: 2022-09-05 04:54:28.871496269 +0000 UTC m=+14.020675125]
... skipping 10 lines ...
I0905 04:54:28.890474       1 deployment_util.go:808] Deployment "metrics-server" timed out (false) [last progress check: 2022-09-05 04:54:28 +0000 UTC - now: 2022-09-05 04:54:28.89046997 +0000 UTC m=+14.039648826]
I0905 04:54:28.890525       1 progress.go:195] Queueing up deployment "metrics-server" for a progress check after 599s
I0905 04:54:28.890549       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/metrics-server" duration="817.696µs"
I0905 04:54:28.907195       1 disruption.go:384] add DB "calico-kube-controllers"
I0905 04:54:28.909988       1 endpointslice_controller.go:318] Finished syncing service "kube-system/kube-dns" endpoint slices. (699.988957ms)
I0905 04:54:28.908229       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/calico-kube-controllers" duration="37.371806ms"
I0905 04:54:28.910048       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"
I0905 04:54:28.910091       1 deployment_controller.go:576] "Started syncing deployment" deployment="kube-system/calico-kube-controllers" startTime="2022-09-05 04:54:28.910070768 +0000 UTC m=+14.059249724"
I0905 04:54:28.910484       1 deployment_util.go:808] Deployment "calico-kube-controllers" timed out (false) [last progress check: 2022-09-05 04:54:28 +0000 UTC - now: 2022-09-05 04:54:28.910480066 +0000 UTC m=+14.059658922]
I0905 04:54:28.910537       1 progress.go:195] Queueing up deployment "calico-kube-controllers" for a progress check after 599s
I0905 04:54:28.910560       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/calico-kube-controllers" duration="477.697µs"
I0905 04:54:28.908294       1 endpointslice_controller.go:318] Finished syncing service "kube-system/metrics-server" endpoint slices. (697.908868ms)
I0905 04:54:28.914907       1 request.go:600] Waited for 132.039113ms due to client-side throttling, not priority and fairness, request: GET:https://10.0.0.4:6443/api/v1/namespaces/kube-system/serviceaccounts/replicaset-controller
... skipping 126 lines ...
I0905 04:54:29.231865       1 deployment_controller.go:576] "Started syncing deployment" deployment="kube-system/coredns" startTime="2022-09-05 04:54:29.231823928 +0000 UTC m=+14.381002784"
I0905 04:54:29.233498       1 deployment_util.go:808] Deployment "coredns" timed out (false) [last progress check: 2022-09-05 04:54:28 +0000 UTC - now: 2022-09-05 04:54:29.23349032 +0000 UTC m=+14.382669176]
I0905 04:54:29.237604       1 progress.go:195] Queueing up deployment "coredns" for a progress check after 598s
I0905 04:54:29.238137       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/coredns" duration="6.299971ms"
I0905 04:54:29.238064       1 endpointslice_controller.go:318] Finished syncing service "kube-system/kube-dns" endpoint slices. (89.745986ms)
I0905 04:54:29.237550       1 disruption.go:558] Finished syncing PodDisruptionBudget "kube-system/calico-kube-controllers" (23.528591ms)
E0905 04:54:29.238843       1 disruption.go:534] Error syncing PodDisruptionBudget kube-system/calico-kube-controllers, requeuing: Operation cannot be fulfilled on poddisruptionbudgets.policy "calico-kube-controllers": the object has been modified; please apply your changes to the latest version and try again
I0905 04:54:29.261919       1 disruption.go:391] update DB "calico-kube-controllers"
I0905 04:54:29.262307       1 deployment_controller.go:281] "ReplicaSet updated" replicaSet="kube-system/calico-kube-controllers-969cf87c4"
I0905 04:54:29.262675       1 deployment_controller.go:576] "Started syncing deployment" deployment="kube-system/calico-kube-controllers" startTime="2022-09-05 04:54:29.262643486 +0000 UTC m=+14.411822442"
I0905 04:54:29.263253       1 replica_set.go:649] Finished syncing ReplicaSet "kube-system/calico-kube-controllers-969cf87c4" (160.728559ms)
I0905 04:54:29.263343       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:0xc0bd7e212f86919e, ext:13946527210, loc:(*time.Location)(0x731ea80)}}
I0905 04:54:29.263464       1 replica_set.go:649] Finished syncing ReplicaSet "kube-system/calico-kube-controllers-969cf87c4" (147.8µs)
... skipping 288 lines ...
I0905 04:54:58.430966       1 reflector.go:255] Listing and watching *v1.PartialObjectMetadata from k8s.io/client-go/metadata/metadatainformer/informer.go:90
I0905 04:54:58.431354       1 resource_quota_monitor.go:294] quota monitor not synced: crd.projectcalico.org/v1, Resource=networkpolicies
I0905 04:54:58.533018       1 resource_quota_monitor.go:294] quota monitor not synced: crd.projectcalico.org/v1, Resource=networkpolicies
I0905 04:54:58.631742       1 shared_informer.go:270] caches populated
I0905 04:54:58.631783       1 shared_informer.go:247] Caches are synced for resource quota 
I0905 04:54:58.631795       1 resource_quota_controller.go:454] synced quota controller
W0905 04:54:58.820675       1 garbagecollector.go:703] failed to discover some groups: map[metrics.k8s.io/v1beta1:the server is currently unable to handle the request]
I0905 04:54:58.820901       1 garbagecollector.go:213] syncing garbage collector with updated resources from discovery (attempt 1): added: [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: []
I0905 04:54:58.820918       1 garbagecollector.go:219] reset restmapper
E0905 04:54:58.834607       1 memcache.go:196] couldn't get resource list for metrics.k8s.io/v1beta1: the server is currently unable to handle the request
E0905 04:54:58.855307       1 memcache.go:101] couldn't get resource list for metrics.k8s.io/v1beta1: the server is currently unable to handle the request
I0905 04:54:58.856119       1 graph_builder.go:174] using a shared informer for resource "crd.projectcalico.org/v1, Resource=ipreservations", kind "crd.projectcalico.org/v1, Kind=IPReservation"
I0905 04:54:58.856191       1 graph_builder.go:174] using a shared informer for resource "crd.projectcalico.org/v1, Resource=hostendpoints", kind "crd.projectcalico.org/v1, Kind=HostEndpoint"
... skipping 69 lines ...
I0905 04:55:00.460833       1 controller_utils.go:223] Setting expectations &controller.ControlleeExpectations{add:0, del:0, key:"kube-system/calico-node", timestamp:time.Time{wall:0xc0bd7e291b77a6d9, ext:45610005385, loc:(*time.Location)(0x731ea80)}}
I0905 04:55:00.460858       1 daemon_controller.go:968] Nodes needing daemon pods for daemon set calico-node: [], creating 0
I0905 04:55:00.461961       1 daemon_controller.go:1030] Pods to delete for daemon set calico-node: [], deleting 0
I0905 04:55:00.462002       1 daemon_controller.go:1103] Updating daemon set status
I0905 04:55:00.462084       1 daemon_controller.go:1163] Finished syncing daemon set "kube-system/calico-node" (3.411023ms)
I0905 04:55:02.748710       1 httplog.go:94] "HTTP" verb="GET" URI="/healthz" latency="304.386µs" userAgent="kube-probe/1.21+" srcIP="127.0.0.1:40696" resp=200
I0905 04:55:03.116160       1 node_lifecycle_controller.go:1039] ReadyCondition for Node capz-ky5ra6-control-plane-7ks5g transitioned from &NodeCondition{Type:Ready,Status:False,LastHeartbeatTime:2022-09-05 04:54:40 +0000 UTC,LastTransitionTime:2022-09-05 04:54:03 +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-05 04:55:00 +0000 UTC,LastTransitionTime:2022-09-05 04:55:00 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status. AppArmor enabled,}
I0905 04:55:03.116262       1 node_lifecycle_controller.go:1047] Node capz-ky5ra6-control-plane-7ks5g ReadyCondition updated. Updating timestamp.
I0905 04:55:03.116558       1 node_lifecycle_controller.go:893] Node capz-ky5ra6-control-plane-7ks5g is healthy again, removing all taints
I0905 04:55:03.116747       1 node_lifecycle_controller.go:1191] Controller detected that some Nodes are Ready. Exiting master disruption mode.
I0905 04:55:08.071554       1 gc_controller.go:161] GC'ing orphaned
I0905 04:55:08.071591       1 gc_controller.go:224] GC'ing unscheduled pods which are terminating.
I0905 04:55:08.216928       1 taint_manager.go:400] "Noticed pod update" pod="kube-system/calico-kube-controllers-969cf87c4-h6zz7"
... skipping 211 lines ...
I0905 04:55:28.073907       1 gc_controller.go:161] GC'ing orphaned
I0905 04:55:28.073943       1 gc_controller.go:224] GC'ing unscheduled pods which are terminating.
I0905 04:55:28.074256       1 reflector.go:381] k8s.io/client-go/informers/factory.go:134: forcing resync
I0905 04:55:28.192045       1 pv_controller_base.go:528] resyncing PV controller
E0905 04:55:28.656406       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
I0905 04:55:28.656651       1 resource_quota_controller.go:424] no resource updates from discovery, skipping resource quota sync
W0905 04:55:29.598567       1 garbagecollector.go:703] failed to discover some groups: map[metrics.k8s.io/v1beta1:the server is currently unable to handle the request]
I0905 04:55:30.219958       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-ky5ra6-control-plane-7ks5g"
I0905 04:55:32.747686       1 httplog.go:94] "HTTP" verb="GET" URI="/healthz" latency="84.601µs" userAgent="kube-probe/1.21+" srcIP="127.0.0.1:40000" resp=200
I0905 04:55:33.124419       1 node_lifecycle_controller.go:1047] Node capz-ky5ra6-control-plane-7ks5g ReadyCondition updated. Updating timestamp.
I0905 04:55:38.330094       1 endpoints_controller.go:555] Update endpoints for kube-system/metrics-server, ready: 1 not ready: 0
I0905 04:55:38.332179       1 replica_set.go:439] Pod metrics-server-8c95fb79b-tp554 updated, objectMeta {Name:metrics-server-8c95fb79b-tp554 GenerateName:metrics-server-8c95fb79b- Namespace:kube-system SelfLink: UID:30661062-e8f7-4184-b326-d83974f0f15d ResourceVersion:744 Generation:0 CreationTimestamp:2022-09-05 04:54:29 +0000 UTC DeletionTimestamp:<nil> DeletionGracePeriodSeconds:<nil> Labels:map[k8s-app:metrics-server pod-template-hash:8c95fb79b] Annotations:map[cni.projectcalico.org/containerID:6bd8339d3464833fc9b08e6f761abffcebda40cebfcf8c34cd66f429c92e9e0a cni.projectcalico.org/podIP:192.168.41.1/32 cni.projectcalico.org/podIPs:192.168.41.1/32] OwnerReferences:[{APIVersion:apps/v1 Kind:ReplicaSet Name:metrics-server-8c95fb79b UID:6bdaf0e2-879f-4ad2-a55e-44ca2029ff20 Controller:0xc001240520 BlockOwnerDeletion:0xc001240521}] Finalizers:[] ClusterName: ManagedFields:[{Manager:kube-controller-manager Operation:Update APIVersion:v1 Time:2022-09-05 04:54:29 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:metadata":{"f:generateName":{},"f:labels":{".":{},"f:k8s-app":{},"f:pod-template-hash":{}},"f:ownerReferences":{".":{},"k:{\"uid\":\"6bdaf0e2-879f-4ad2-a55e-44ca2029ff20\"}":{".":{},"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-05 04:54:29 +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-05 04:55:09 +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-05 04:55:15 +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.41.1\"}":{".":{},"f:ip":{}}},"f:startTime":{}}}}]} -> {Name:metrics-server-8c95fb79b-tp554 GenerateName:metrics-server-8c95fb79b- Namespace:kube-system SelfLink: UID:30661062-e8f7-4184-b326-d83974f0f15d ResourceVersion:784 Generation:0 CreationTimestamp:2022-09-05 04:54:29 +0000 UTC DeletionTimestamp:<nil> DeletionGracePeriodSeconds:<nil> Labels:map[k8s-app:metrics-server pod-template-hash:8c95fb79b] Annotations:map[cni.projectcalico.org/containerID:6bd8339d3464833fc9b08e6f761abffcebda40cebfcf8c34cd66f429c92e9e0a cni.projectcalico.org/podIP:192.168.41.1/32 cni.projectcalico.org/podIPs:192.168.41.1/32] OwnerReferences:[{APIVersion:apps/v1 Kind:ReplicaSet Name:metrics-server-8c95fb79b UID:6bdaf0e2-879f-4ad2-a55e-44ca2029ff20 Controller:0xc0011e2710 BlockOwnerDeletion:0xc0011e2711}] Finalizers:[] ClusterName: ManagedFields:[{Manager:kube-controller-manager Operation:Update APIVersion:v1 Time:2022-09-05 04:54:29 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:metadata":{"f:generateName":{},"f:labels":{".":{},"f:k8s-app":{},"f:pod-template-hash":{}},"f:ownerReferences":{".":{},"k:{\"uid\":\"6bdaf0e2-879f-4ad2-a55e-44ca2029ff20\"}":{".":{},"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-05 04:54:29 +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-05 04:55:09 +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-05 04:55:38 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:status":{"f:conditions":{"k:{\"type\":\"ContainersReady\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Initialized\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:status":{},"f:type":{}},"k:{\"type\":\"Ready\"}":{".":{},"f:lastProbeTime":{},"f:lastTransitionTime":{},"f:status":{},"f:type":{}}},"f:containerStatuses":{},"f:hostIP":{},"f:phase":{},"f:podIP":{},"f:podIPs":{".":{},"k:{\"ip\":\"192.168.41.1\"}":{".":{},"f:ip":{}}},"f:startTime":{}}}}]}.
I0905 04:55:38.332515       1 disruption.go:427] updatePod called on pod "metrics-server-8c95fb79b-tp554"
... skipping 78 lines ...
I0905 04:56:12.494814       1 controller.go:272] Triggering nodeSync
I0905 04:56:12.494824       1 controller.go:291] nodeSync has been triggered
I0905 04:56:12.494831       1 controller.go:776] Running updateLoadBalancerHosts(len(services)==0, workers==1)
I0905 04:56:12.494838       1 controller.go:790] Finished updateLoadBalancerHosts
I0905 04:56:12.494844       1 controller.go:731] It took 1.4388e-05 seconds to finish nodeSyncInternal
I0905 04:56:12.492917       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-ky5ra6-md-0-vxfmx"
W0905 04:56:12.495458       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-ky5ra6-md-0-vxfmx" does not exist
I0905 04:56:12.532345       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-ky5ra6-md-0-vxfmx"
I0905 04:56:12.535189       1 ttl_controller.go:276] "Changed ttl annotation" node="capz-ky5ra6-md-0-vxfmx" new_ttl="0s"
I0905 04:56:12.544056       1 controller_utils.go:591] Controller calico-node created pod calico-node-nvk8z
I0905 04:56:12.544879       1 daemon_controller.go:1030] Pods to delete for daemon set calico-node: [], deleting 0
I0905 04:56:12.545080       1 controller_utils.go:195] Controller still waiting on expectations &controller.ControlleeExpectations{add:1, del:0, key:"kube-system/calico-node", timestamp:time.Time{wall:0xc0bd7e3b1d79a5f8, ext:117643690564, loc:(*time.Location)(0x731ea80)}}
I0905 04:56:12.545367       1 daemon_controller.go:1103] Updating daemon set status
... skipping 190 lines ...
I0905 04:56:17.956519       1 controller.go:272] Triggering nodeSync
I0905 04:56:17.956797       1 controller.go:291] nodeSync has been triggered
I0905 04:56:17.957043       1 controller.go:776] Running updateLoadBalancerHosts(len(services)==0, workers==1)
I0905 04:56:17.957285       1 controller.go:790] Finished updateLoadBalancerHosts
I0905 04:56:17.957435       1 controller.go:731] It took 0.000393204 seconds to finish nodeSyncInternal
I0905 04:56:17.951379       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-ky5ra6-md-0-lz9m9"
W0905 04:56:17.959073       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-ky5ra6-md-0-lz9m9" does not exist
I0905 04:56:17.953231       1 controller_utils.go:189] Controller expectations fulfilled &controller.ControlleeExpectations{add:0, del:0, key:"kube-system/calico-node", timestamp:time.Time{wall:0xc0bd7e3bc30e841d, ext:120200461929, loc:(*time.Location)(0x731ea80)}}
I0905 04:56:17.960052       1 controller_utils.go:223] Setting expectations &controller.ControlleeExpectations{add:1, del:0, key:"kube-system/calico-node", timestamp:time.Time{wall:0xc0bd7e3c7939251d, ext:123109225321, loc:(*time.Location)(0x731ea80)}}
I0905 04:56:17.960534       1 daemon_controller.go:968] Nodes needing daemon pods for daemon set calico-node: [capz-ky5ra6-md-0-lz9m9], creating 1
I0905 04:56:17.965043       1 controller_utils.go:223] Setting expectations &controller.ControlleeExpectations{add:1, del:0, key:"kube-system/kube-proxy", timestamp:time.Time{wall:0xc0bd7e3c798547f8, ext:123114214980, loc:(*time.Location)(0x731ea80)}}
I0905 04:56:17.965121       1 daemon_controller.go:968] Nodes needing daemon pods for daemon set kube-proxy: [capz-ky5ra6-md-0-lz9m9], creating 1
I0905 04:56:17.983958       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-ky5ra6-md-0-lz9m9"
... skipping 318 lines ...
I0905 04:56:38.079580       1 controller.go:776] Running updateLoadBalancerHosts(len(services)==0, workers==1)
I0905 04:56:38.079626       1 controller.go:790] Finished updateLoadBalancerHosts
I0905 04:56:38.079638       1 controller.go:748] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0905 04:56:38.079645       1 controller.go:731] It took 9.72e-05 seconds to finish nodeSyncInternal
I0905 04:56:38.099529       1 controller_utils.go:221] Made sure that Node capz-ky5ra6-md-0-lz9m9 has no [&Taint{Key:node.kubernetes.io/not-ready,Value:,Effect:NoSchedule,TimeAdded:<nil>,}] Taint
I0905 04:56:38.100202       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-ky5ra6-md-0-lz9m9"
I0905 04:56:38.137057       1 node_lifecycle_controller.go:1039] ReadyCondition for Node capz-ky5ra6-md-0-lz9m9 transitioned from &NodeCondition{Type:Ready,Status:False,LastHeartbeatTime:2022-09-05 04:56:28 +0000 UTC,LastTransitionTime:2022-09-05 04:56:17 +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-05 04:56:38 +0000 UTC,LastTransitionTime:2022-09-05 04:56:38 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status. AppArmor enabled,}
I0905 04:56:38.137136       1 node_lifecycle_controller.go:1047] Node capz-ky5ra6-md-0-lz9m9 ReadyCondition updated. Updating timestamp.
I0905 04:56:38.156005       1 taint_manager.go:435] "Noticed node update" node={nodeName:capz-ky5ra6-md-0-lz9m9}
I0905 04:56:38.156390       1 taint_manager.go:440] "Updating known taints on node" node="capz-ky5ra6-md-0-lz9m9" taints=[]
I0905 04:56:38.156664       1 taint_manager.go:461] "All taints were removed from the node. Cancelling all evictions..." node="capz-ky5ra6-md-0-lz9m9"
I0905 04:56:38.157005       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-ky5ra6-md-0-lz9m9"
I0905 04:56:38.157012       1 node_lifecycle_controller.go:893] Node capz-ky5ra6-md-0-lz9m9 is healthy again, removing all taints
... skipping 55 lines ...
I0905 04:56:42.716639       1 daemon_controller.go:968] Nodes needing daemon pods for daemon set calico-node: [], creating 0
I0905 04:56:42.716791       1 daemon_controller.go:1030] Pods to delete for daemon set calico-node: [], deleting 0
I0905 04:56:42.716883       1 daemon_controller.go:1103] Updating daemon set status
I0905 04:56:42.717009       1 daemon_controller.go:1163] Finished syncing daemon set "kube-system/calico-node" (2.568003ms)
I0905 04:56:42.748223       1 httplog.go:94] "HTTP" verb="GET" URI="/healthz" latency="95.3µs" userAgent="kube-probe/1.21+" srcIP="127.0.0.1:59424" resp=200
I0905 04:56:43.067213       1 reflector.go:381] k8s.io/client-go/informers/factory.go:134: forcing resync
I0905 04:56:43.157966       1 node_lifecycle_controller.go:1039] ReadyCondition for Node capz-ky5ra6-md-0-vxfmx transitioned from &NodeCondition{Type:Ready,Status:False,LastHeartbeatTime:2022-09-05 04:56:22 +0000 UTC,LastTransitionTime:2022-09-05 04:56:12 +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-05 04:56:42 +0000 UTC,LastTransitionTime:2022-09-05 04:56:42 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status. AppArmor enabled,}
I0905 04:56:43.158050       1 node_lifecycle_controller.go:1047] Node capz-ky5ra6-md-0-vxfmx ReadyCondition updated. Updating timestamp.
I0905 04:56:43.170330       1 taint_manager.go:435] "Noticed node update" node={nodeName:capz-ky5ra6-md-0-vxfmx}
I0905 04:56:43.170366       1 taint_manager.go:440] "Updating known taints on node" node="capz-ky5ra6-md-0-vxfmx" taints=[]
I0905 04:56:43.170385       1 taint_manager.go:461] "All taints were removed from the node. Cancelling all evictions..." node="capz-ky5ra6-md-0-vxfmx"
I0905 04:56:43.171281       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-ky5ra6-md-0-vxfmx"
I0905 04:56:43.173905       1 node_lifecycle_controller.go:893] Node capz-ky5ra6-md-0-vxfmx is healthy again, removing all taints
... skipping 126 lines ...
I0905 04:59:02.865788       1 pv_controller_base.go:640] storeObjectUpdate updating claim "azurefile-2540/pvc-r5hv5" with version 1229
I0905 04:59:02.917657       1 azure_storageaccount.go:184] azure - no matching account found, begin to create a new account f2ea92711f47c4e3889128b in resource group capz-ky5ra6, location: uksouth, accountType: Standard_LRS, accountKind: StorageV2, tags: map[created-by:azure]
I0905 04:59:06.382965       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-8081
I0905 04:59:06.456328       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-8081, name kube-root-ca.crt, uid 3b9b4197-c99f-4ad6-a0a9-c86efc358f1e, event type delete
I0905 04:59:06.458567       1 publisher.go:181] Finished syncing namespace "azurefile-8081" (2.593597ms)
I0905 04:59:06.478920       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-8081, name default-token-bsmfb, uid 7945c7f5-16f0-4683-ba3d-68e248724ea4, event type delete
E0905 04:59:06.494251       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-8081/default: secrets "default-token-xcdfz" is forbidden: unable to create new content in namespace azurefile-8081 because it is being terminated
I0905 04:59:06.511075       1 tokens_controller.go:252] syncServiceAccount(azurefile-8081/default), service account deleted, removing tokens
I0905 04:59:06.511298       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-8081, name default, uid 4cb77f09-54ce-4942-84fb-f8a87abd76a0, event type delete
I0905 04:59:06.511525       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-8081" (2.2µs)
I0905 04:59:06.578096       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-8081, estimate: 0, errors: <nil>
I0905 04:59:06.580088       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-8081" (2.1µs)
I0905 04:59:06.588141       1 namespace_controller.go:180] Finished syncing namespace "azurefile-8081" (217.900956ms)
... skipping 292 lines ...
I0905 04:59:38.010539       1 pv_controller.go:1038] volume "pvc-e8463d9c-dc63-4c5e-8382-687810929f59" bound to claim "azurefile-5466/pvc-ctvjz"
I0905 04:59:38.010583       1 pv_controller.go:1039] volume "pvc-e8463d9c-dc63-4c5e-8382-687810929f59" status after binding: phase: Bound, bound to: "azurefile-5466/pvc-ctvjz (uid: e8463d9c-dc63-4c5e-8382-687810929f59)", boundByController: true
I0905 04:59:38.010642       1 pv_controller.go:1040] claim "azurefile-5466/pvc-ctvjz" status after binding: phase: Bound, bound to: "pvc-e8463d9c-dc63-4c5e-8382-687810929f59", bindCompleted: true, boundByController: true
I0905 04:59:38.009458       1 pvc_protection_controller.go:353] "Got event on PVC" pvc="azurefile-5466/pvc-ctvjz"
I0905 04:59:39.655886       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-2540
I0905 04:59:39.738937       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-2540, name default-token-6kgzb, uid bc5559de-c8a0-44dd-8891-79ed700085d4, event type delete
E0905 04:59:39.769839       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-2540/default: secrets "default-token-6dxj8" is forbidden: unable to create new content in namespace azurefile-2540 because it is being terminated
I0905 04:59:39.813798       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-2540, name kube-root-ca.crt, uid 778f09c9-9386-4eaf-bb6d-b971f4a2959e, event type delete
I0905 04:59:39.817539       1 publisher.go:181] Finished syncing namespace "azurefile-2540" (4.046503ms)
I0905 04:59:39.843267       1 tokens_controller.go:252] syncServiceAccount(azurefile-2540/default), service account deleted, removing tokens
I0905 04:59:39.843419       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-2540, name default, uid d1afe4cd-13f8-4401-8b83-03cededdd6cb, event type delete
I0905 04:59:39.843550       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-2540" (2µs)
I0905 04:59:39.875660       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-2540, name azurefile-volume-tester-2x2xr.1711de1f7e3ed78d, uid b2ce7b21-0edf-45ea-a017-071f402a1d2b, event type delete
... skipping 21 lines ...
I0905 04:59:40.200079       1 disruption.go:430] No matching pdb for pod "azurefile-volume-tester-zj94z"
I0905 04:59:40.663882       1 disruption.go:427] updatePod called on pod "azurefile-volume-tester-zj94z"
I0905 04:59:40.664134       1 disruption.go:490] No PodDisruptionBudgets found for pod azurefile-volume-tester-zj94z, PodDisruptionBudget controller will avoid syncing.
I0905 04:59:40.664224       1 disruption.go:430] No matching pdb for pod "azurefile-volume-tester-zj94z"
I0905 04:59:41.162993       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-4728
I0905 04:59:41.322275       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-4728, name default-token-5kq5r, uid 2efe8d21-2c57-4c26-aa17-58dba3ce69e2, event type delete
E0905 04:59:41.346717       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-4728/default: secrets "default-token-tf8t8" is forbidden: unable to create new content in namespace azurefile-4728 because it is being terminated
I0905 04:59:41.350442       1 tokens_controller.go:252] syncServiceAccount(azurefile-4728/default), service account deleted, removing tokens
I0905 04:59:41.350489       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-4728, name default, uid 7ca8ce9a-647e-4a87-ba39-ca5b31bdc6fc, event type delete
I0905 04:59:41.350642       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-4728" (2.4µs)
I0905 04:59:41.373902       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-4728, name kube-root-ca.crt, uid 1dfa6393-d70b-46b8-95f6-54b24fcada2f, event type delete
I0905 04:59:41.378517       1 publisher.go:181] Finished syncing namespace "azurefile-4728" (4.614303ms)
I0905 04:59:41.420462       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-4728" (2.3µs)
... skipping 638 lines ...
I0905 05:01:17.978748       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-5466, name azurefile-volume-tester-zj94z.1711de2359ceedb0, uid 64e1b072-9ab5-4f94-9095-9e7a95c40b17, event type delete
I0905 05:01:17.983771       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-5466, name azurefile-volume-tester-zj94z.1711de2360394b95, uid 389a2a5c-7ed0-426f-94cd-81307f07a1d5, event type delete
I0905 05:01:17.991366       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-5466, name azurefile-volume-tester-zj94z.1711de304b1173ab, uid 2d19c874-d478-4bba-a847-242b53f3ec55, event type delete
I0905 05:01:17.995993       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-5466, name pvc-ctvjz.1711de22ad39483a, uid e4bb008d-50b1-43da-a425-d90ee4742c49, event type delete
I0905 05:01:18.000546       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-5466, name pvc-hf25v.1711de23d12d3ddf, uid 2e94c784-bb4e-49cb-bde4-287809ac7753, event type delete
I0905 05:01:18.026514       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-5466, name default-token-8kw77, uid a93dffd1-2b64-4766-98fb-5c1388e193b9, event type delete
E0905 05:01:18.064844       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-5466/default: secrets "default-token-k6gz9" is forbidden: unable to create new content in namespace azurefile-5466 because it is being terminated
I0905 05:01:18.121659       1 tokens_controller.go:252] syncServiceAccount(azurefile-5466/default), service account deleted, removing tokens
I0905 05:01:18.122302       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-5466" (2.6µs)
I0905 05:01:18.122528       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-5466, name default, uid 6a867d8b-143d-4fef-b449-048d9f47fe8c, event type delete
I0905 05:01:18.165116       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-5466, name kube-root-ca.crt, uid 04f4ddca-6089-4f8a-baeb-dda59e3db4dd, event type delete
I0905 05:01:18.169084       1 publisher.go:181] Finished syncing namespace "azurefile-5466" (4.056798ms)
I0905 05:01:18.205268       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-5466" (3.4µs)
... skipping 292 lines ...
I0905 05:01:50.402882       1 taint_manager.go:400] "Noticed pod update" pod="azurefile-5356/azurefile-volume-tester-6f99w-78cb654999-qfxn5"
I0905 05:01:50.403444       1 replica_set.go:439] Pod azurefile-volume-tester-6f99w-78cb654999-qfxn5 updated, objectMeta {Name:azurefile-volume-tester-6f99w-78cb654999-qfxn5 GenerateName:azurefile-volume-tester-6f99w-78cb654999- Namespace:azurefile-5356 SelfLink: UID:0db256a2-b09d-448b-86ee-ffa63417a29f ResourceVersion:1678 Generation:0 CreationTimestamp:2022-09-05 05:01:50 +0000 UTC DeletionTimestamp:<nil> DeletionGracePeriodSeconds:<nil> Labels:map[app:azurefile-volume-tester-5018949295715050020 pod-template-hash:78cb654999] Annotations:map[] OwnerReferences:[{APIVersion:apps/v1 Kind:ReplicaSet Name:azurefile-volume-tester-6f99w-78cb654999 UID:032daf54-8ac6-4994-8f91-eba53c0c0f22 Controller:0xc0027a0ec7 BlockOwnerDeletion:0xc0027a0ec8}] Finalizers:[] ClusterName: ManagedFields:[{Manager:kube-controller-manager Operation:Update APIVersion:v1 Time:2022-09-05 05:01:50 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:metadata":{"f:generateName":{},"f:labels":{".":{},"f:app":{},"f:pod-template-hash":{}},"f:ownerReferences":{".":{},"k:{\"uid\":\"032daf54-8ac6-4994-8f91-eba53c0c0f22\"}":{".":{},"f:apiVersion":{},"f:blockOwnerDeletion":{},"f:controller":{},"f:kind":{},"f:name":{},"f:uid":{}}}},"f:spec":{"f:containers":{"k:{\"name\":\"volume-tester\"}":{".":{},"f:args":{},"f:command":{},"f:image":{},"f:imagePullPolicy":{},"f:name":{},"f:resources":{},"f:terminationMessagePath":{},"f:terminationMessagePolicy":{},"f:volumeMounts":{".":{},"k:{\"mountPath\":\"/mnt/test-1\"}":{".":{},"f:mountPath":{},"f:name":{}}}}},"f:dnsPolicy":{},"f:enableServiceLinks":{},"f:restartPolicy":{},"f:schedulerName":{},"f:securityContext":{},"f:terminationGracePeriodSeconds":{},"f:volumes":{".":{},"k:{\"name\":\"test-volume-1\"}":{".":{},"f:name":{},"f:persistentVolumeClaim":{".":{},"f:claimName":{}}}}}}}]} -> {Name:azurefile-volume-tester-6f99w-78cb654999-qfxn5 GenerateName:azurefile-volume-tester-6f99w-78cb654999- Namespace:azurefile-5356 SelfLink: UID:0db256a2-b09d-448b-86ee-ffa63417a29f ResourceVersion:1679 Generation:0 CreationTimestamp:2022-09-05 05:01:50 +0000 UTC DeletionTimestamp:<nil> DeletionGracePeriodSeconds:<nil> Labels:map[app:azurefile-volume-tester-5018949295715050020 pod-template-hash:78cb654999] Annotations:map[] OwnerReferences:[{APIVersion:apps/v1 Kind:ReplicaSet Name:azurefile-volume-tester-6f99w-78cb654999 UID:032daf54-8ac6-4994-8f91-eba53c0c0f22 Controller:0xc002900fce BlockOwnerDeletion:0xc002900fcf}] Finalizers:[] ClusterName: ManagedFields:[{Manager:kube-controller-manager Operation:Update APIVersion:v1 Time:2022-09-05 05:01:50 +0000 UTC FieldsType:FieldsV1 FieldsV1:{"f:metadata":{"f:generateName":{},"f:labels":{".":{},"f:app":{},"f:pod-template-hash":{}},"f:ownerReferences":{".":{},"k:{\"uid\":\"032daf54-8ac6-4994-8f91-eba53c0c0f22\"}":{".":{},"f:apiVersion":{},"f:blockOwnerDeletion":{},"f:controller":{},"f:kind":{},"f:name":{},"f:uid":{}}}},"f:spec":{"f:containers":{"k:{\"name\":\"volume-tester\"}":{".":{},"f:args":{},"f:command":{},"f:image":{},"f:imagePullPolicy":{},"f:name":{},"f:resources":{},"f:terminationMessagePath":{},"f:terminationMessagePolicy":{},"f:volumeMounts":{".":{},"k:{\"mountPath\":\"/mnt/test-1\"}":{".":{},"f:mountPath":{},"f:name":{}}}}},"f:dnsPolicy":{},"f:enableServiceLinks":{},"f:restartPolicy":{},"f:schedulerName":{},"f:securityContext":{},"f:terminationGracePeriodSeconds":{},"f:volumes":{".":{},"k:{\"name\":\"test-volume-1\"}":{".":{},"f:name":{},"f:persistentVolumeClaim":{".":{},"f:claimName":{}}}}}}}]}.
I0905 05:01:50.404307       1 disruption.go:427] updatePod called on pod "azurefile-volume-tester-6f99w-78cb654999-qfxn5"
I0905 05:01:50.405196       1 disruption.go:490] No PodDisruptionBudgets found for pod azurefile-volume-tester-6f99w-78cb654999-qfxn5, PodDisruptionBudget controller will avoid syncing.
I0905 05:01:50.405996       1 deployment_controller.go:578] "Finished syncing deployment" deployment="azurefile-5356/azurefile-volume-tester-6f99w" duration="35.586188ms"
I0905 05:01:50.406404       1 disruption.go:430] No matching pdb for pod "azurefile-volume-tester-6f99w-78cb654999-qfxn5"
I0905 05:01:50.406603       1 deployment_controller.go:490] "Error syncing deployment" deployment="azurefile-5356/azurefile-volume-tester-6f99w" err="Operation cannot be fulfilled on deployments.apps \"azurefile-volume-tester-6f99w\": the object has been modified; please apply your changes to the latest version and try again"
I0905 05:01:50.406899       1 deployment_controller.go:576] "Started syncing deployment" deployment="azurefile-5356/azurefile-volume-tester-6f99w" startTime="2022-09-05 05:01:50.406809958 +0000 UTC m=+455.555988814"
I0905 05:01:50.408016       1 deployment_util.go:808] Deployment "azurefile-volume-tester-6f99w" timed out (false) [last progress check: 2022-09-05 05:01:50 +0000 UTC - now: 2022-09-05 05:01:50.408008958 +0000 UTC m=+455.557187814]
I0905 05:01:50.413324       1 deployment_controller.go:281] "ReplicaSet updated" replicaSet="azurefile-5356/azurefile-volume-tester-6f99w-78cb654999"
I0905 05:01:50.415587       1 replica_set.go:649] Finished syncing ReplicaSet "azurefile-5356/azurefile-volume-tester-6f99w-78cb654999" (35.654388ms)
I0905 05:01:50.415840       1 controller_utils.go:189] Controller expectations fulfilled &controller.ControlleeExpectations{add:0, del:0, key:"azurefile-5356/azurefile-volume-tester-6f99w-78cb654999", timestamp:time.Time{wall:0xc0bd7e8f96ac64de, ext:455529575722, loc:(*time.Location)(0x731ea80)}}
I0905 05:01:50.416226       1 replica_set_utils.go:59] Updating status for : azurefile-5356/azurefile-volume-tester-6f99w-78cb654999, replicas 0->1 (need 1), fullyLabeledReplicas 0->1, readyReplicas 0->0, availableReplicas 0->0, sequence No: 1->1
... skipping 32 lines ...
I0905 05:01:50.945274       1 replica_set.go:649] Finished syncing ReplicaSet "azurefile-5356/azurefile-volume-tester-6f99w-78cb654999" (561.5µs)
I0905 05:01:51.394636       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-2790
I0905 05:01:51.455030       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-2790, name kube-root-ca.crt, uid c844fb4d-5635-4fa2-bfb4-988405bd86b5, event type delete
I0905 05:01:51.459936       1 publisher.go:181] Finished syncing namespace "azurefile-2790" (5.187899ms)
I0905 05:01:51.483737       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-2790, name azure-storage-account-f1ed95e97876d4cc4a3bb86-secret, uid 299b7c49-1674-43e5-a8c0-a1abf8806a22, event type delete
I0905 05:01:51.489100       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-2790, name default-token-wmgf6, uid 5db08a0d-249f-471c-8549-bc87f076cbfa, event type delete
E0905 05:01:51.504497       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-2790/default: secrets "default-token-dqkt4" is forbidden: unable to create new content in namespace azurefile-2790 because it is being terminated
I0905 05:01:51.521569       1 tokens_controller.go:252] syncServiceAccount(azurefile-2790/default), service account deleted, removing tokens
I0905 05:01:51.521617       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-2790, name default, uid c9962883-4ffa-41fc-8190-39a6966a7c65, event type delete
I0905 05:01:51.521645       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-2790" (1.7µs)
I0905 05:01:51.534696       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-2790, name azurefile-volume-tester-j4964.1711de3ea340c0c0, uid bcf39cea-689b-46cf-948e-8eb531157b42, event type delete
I0905 05:01:51.542220       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-2790, name azurefile-volume-tester-j4964.1711de3ecd2bafa6, uid 57af401e-d6cb-40c3-b8f8-06a19df7cca6, event type delete
I0905 05:01:51.565644       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-2790, name azurefile-volume-tester-j4964.1711de3ecf286d90, uid 2a0a3aa3-6a4a-42a1-a87d-6b955d7295eb, event type delete
... skipping 502 lines ...
I0905 05:03:29.079541       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-5356, name azurefile-volume-tester-6f99w-78cb654999.1711de42adf3280a, uid 7cedd6fa-bda1-4030-9da7-359f7e66915f, event type delete
I0905 05:03:29.087586       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-5356, name azurefile-volume-tester-6f99w.1711de4180227e2e, uid c9d78de2-d078-4c50-9ebe-ee8f31968e14, event type delete
I0905 05:03:29.091530       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-5356, name pvc-fbkv5.1711de40fb8d7521, uid f120a788-a6ca-4355-a759-cf037ee85e41, event type delete
I0905 05:03:29.200866       1 resource_quota_controller.go:424] no resource updates from discovery, skipping resource quota sync
I0905 05:03:29.234768       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-5356, name azure-storage-account-f1ed95e97876d4cc4a3bb86-secret, uid d5fea2a0-7107-47c9-9b98-49f899ac3f63, event type delete
I0905 05:03:29.239082       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-5356, name default-token-w99t2, uid af05df8b-7f94-46de-ba6b-e1a4da4e8de6, event type delete
E0905 05:03:29.259180       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-5356/default: secrets "default-token-vxjr7" is forbidden: unable to create new content in namespace azurefile-5356 because it is being terminated
I0905 05:03:29.327987       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-5356, name kube-root-ca.crt, uid 39e0e412-1098-4404-984f-65583fc12186, event type delete
I0905 05:03:29.331121       1 publisher.go:181] Finished syncing namespace "azurefile-5356" (3.184325ms)
I0905 05:03:29.338129       1 tokens_controller.go:252] syncServiceAccount(azurefile-5356/default), service account deleted, removing tokens
I0905 05:03:29.338365       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-5356" (2.5µs)
I0905 05:03:29.338351       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-5356, name default, uid b2e47b65-c896-4401-8f8b-3fab64db0193, event type delete
I0905 05:03:29.353813       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-5356" (1.8µs)
... skipping 276 lines ...
I0905 05:03:59.932101       1 disruption.go:430] No matching pdb for pod "azurefile-volume-tester-cl4qt"
I0905 05:04:00.704244       1 disruption.go:427] updatePod called on pod "azurefile-volume-tester-cl4qt"
I0905 05:04:00.704565       1 disruption.go:490] No PodDisruptionBudgets found for pod azurefile-volume-tester-cl4qt, PodDisruptionBudget controller will avoid syncing.
I0905 05:04:00.704685       1 disruption.go:430] No matching pdb for pod "azurefile-volume-tester-cl4qt"
I0905 05:04:00.974062       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-1957
I0905 05:04:01.035128       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-1957, name default-token-kjjcr, uid ea3f5c22-4372-4d97-8331-fdfd82a88ed9, event type delete
E0905 05:04:01.052929       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-1957/default: secrets "default-token-f7z9t" is forbidden: unable to create new content in namespace azurefile-1957 because it is being terminated
I0905 05:04:01.093463       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-1957, name kube-root-ca.crt, uid 1b01b454-bdb6-42e9-9e30-58fa6b52b950, event type delete
I0905 05:04:01.095725       1 publisher.go:181] Finished syncing namespace "azurefile-1957" (2.826171ms)
I0905 05:04:01.133048       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-1957, name default, uid 33bc061d-3233-4e5a-b295-84caed7600d2, event type delete
I0905 05:04:01.133646       1 tokens_controller.go:252] syncServiceAccount(azurefile-1957/default), service account deleted, removing tokens
I0905 05:04:01.133703       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1957" (2.1µs)
I0905 05:04:01.201109       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1957" (66.202µs)
... skipping 273 lines ...
I0905 05:04:44.488065       1 namespace_controller.go:180] Finished syncing namespace "azurefile-4538" (255.380357ms)
I0905 05:04:45.309224       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-59" (3.3µs)
I0905 05:04:45.423206       1 publisher.go:181] Finished syncing namespace "azurefile-2546" (4.875026ms)
I0905 05:04:45.433376       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-2546" (14.695778ms)
I0905 05:04:45.676200       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-8266
I0905 05:04:45.708881       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-8266, name default-token-8dt65, uid 52dd7550-fe98-4bb3-90d1-72f88d8105a9, event type delete
E0905 05:04:45.728091       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-8266/default: secrets "default-token-wsr7n" is forbidden: unable to create new content in namespace azurefile-8266 because it is being terminated
I0905 05:04:45.758237       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-8266, name kube-root-ca.crt, uid c10a0308-c245-49f6-b157-100a0a8c278d, event type delete
I0905 05:04:45.760571       1 publisher.go:181] Finished syncing namespace "azurefile-8266" (2.404912ms)
I0905 05:04:45.841243       1 tokens_controller.go:252] syncServiceAccount(azurefile-8266/default), service account deleted, removing tokens
I0905 05:04:45.841247       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-8266, name default, uid eb717764-7e18-4631-9566-3a30413160b2, event type delete
I0905 05:04:45.841273       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-8266" (2.3µs)
I0905 05:04:45.852198       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-8266" (1.6µs)
... skipping 66 lines ...
I0905 05:04:52.746960       1 httplog.go:94] "HTTP" verb="GET" URI="/healthz" latency="105.4µs" userAgent="kube-probe/1.21+" srcIP="127.0.0.1:47836" resp=200
I0905 05:04:53.213112       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-1598
I0905 05:04:53.237174       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-1598, name kube-root-ca.crt, uid 96a37629-c692-4fa3-a8d8-4805e692159d, event type delete
I0905 05:04:53.239567       1 publisher.go:181] Finished syncing namespace "azurefile-1598" (2.475608ms)
I0905 05:04:53.253096       1 node_lifecycle_controller.go:1047] Node capz-ky5ra6-md-0-lz9m9 ReadyCondition updated. Updating timestamp.
I0905 05:04:53.327156       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-1598, name default-token-dgzxl, uid ba966595-da15-4df4-899f-f9054e1d6308, event type delete
E0905 05:04:53.345211       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-1598/default: secrets "default-token-6nnpd" is forbidden: unable to create new content in namespace azurefile-1598 because it is being terminated
I0905 05:04:53.349962       1 tokens_controller.go:252] syncServiceAccount(azurefile-1598/default), service account deleted, removing tokens
I0905 05:04:53.350010       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-1598, name default, uid 28a06ec4-f094-427c-9010-862abd3daf5f, event type delete
I0905 05:04:53.350189       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1598" (2.1µs)
I0905 05:04:53.407903       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1598" (1.6µs)
I0905 05:04:53.408216       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-1598, estimate: 0, errors: <nil>
I0905 05:04:53.416746       1 namespace_controller.go:180] Finished syncing namespace "azurefile-1598" (207.244027ms)
... skipping 50 lines ...
I0905 05:04:58.315164       1 publisher.go:181] Finished syncing namespace "azurefile-9183" (16.349784ms)
I0905 05:04:58.322540       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-9183" (23.442419ms)
I0905 05:04:58.408279       1 namespace_controller.go:185] Namespace has been deleted azurefile-1598
I0905 05:04:58.408307       1 namespace_controller.go:180] Finished syncing namespace "azurefile-1598" (67.6µs)
I0905 05:04:58.926116       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-3086
I0905 05:04:58.995040       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-3086, name default-token-kpw56, uid f135d15c-6536-4039-b924-9d1d6b8ba0c6, event type delete
E0905 05:04:59.027870       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-3086/default: secrets "default-token-7r4lx" is forbidden: unable to create new content in namespace azurefile-3086 because it is being terminated
I0905 05:04:59.070851       1 tokens_controller.go:252] syncServiceAccount(azurefile-3086/default), service account deleted, removing tokens
I0905 05:04:59.072037       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-3086, name default, uid a39c443a-9b9b-4738-8c3c-8ed0af6d09f9, event type delete
I0905 05:04:59.072078       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-3086" (2.601µs)
I0905 05:04:59.089829       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-3086, name kube-root-ca.crt, uid e397378d-eed5-4b8f-8da6-a0e6249a7acb, event type delete
I0905 05:04:59.091533       1 publisher.go:181] Finished syncing namespace "azurefile-3086" (1.86571ms)
I0905 05:04:59.107147       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-3086" (1.5µs)
... skipping 19 lines ...
I0905 05:05:01.075784       1 publisher.go:181] Finished syncing namespace "azurefile-1968" (17.62939ms)
I0905 05:05:01.077800       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1968" (19.193897ms)
I0905 05:05:01.274346       1 namespace_controller.go:185] Namespace has been deleted azurefile-8582
I0905 05:05:01.274371       1 namespace_controller.go:180] Finished syncing namespace "azurefile-8582" (51µs)
I0905 05:05:01.763794       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-4547
I0905 05:05:01.800398       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-4547, name default-token-tgvz9, uid 004aa279-448e-454b-984c-d729f4521465, event type delete
E0905 05:05:01.820221       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-4547/default: secrets "default-token-xzn89" is forbidden: unable to create new content in namespace azurefile-4547 because it is being terminated
I0905 05:05:01.900169       1 tokens_controller.go:252] syncServiceAccount(azurefile-4547/default), service account deleted, removing tokens
I0905 05:05:01.900221       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-4547, name default, uid 550ffb75-a32c-405e-bd2d-3a70ac92979b, event type delete
I0905 05:05:01.900255       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-4547" (2µs)
I0905 05:05:01.920145       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-4547, name kube-root-ca.crt, uid 5b280ffc-9308-4555-8c6c-b31914070e46, event type delete
I0905 05:05:01.923823       1 publisher.go:181] Finished syncing namespace "azurefile-4547" (3.91152ms)
I0905 05:05:02.023284       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-4547" (3.5µs)
... skipping 36 lines ...
I0905 05:05:05.568055       1 namespace_controller.go:185] Namespace has been deleted azurefile-1387
I0905 05:05:05.568083       1 namespace_controller.go:180] Finished syncing namespace "azurefile-1387" (48.301µs)
I0905 05:05:05.958152       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-7578
I0905 05:05:06.030471       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-7578, name kube-root-ca.crt, uid f59c51f9-465f-476c-b7f1-522dacaff6e6, event type delete
I0905 05:05:06.034043       1 publisher.go:181] Finished syncing namespace "azurefile-7578" (3.70362ms)
I0905 05:05:06.113563       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-7578, name default-token-m2b94, uid 9421f020-219f-4604-a85b-82240ecc3cfe, event type delete
E0905 05:05:06.127249       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-7578/default: secrets "default-token-4r6jf" is forbidden: unable to create new content in namespace azurefile-7578 because it is being terminated
I0905 05:05:06.129398       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-7578, name default, uid 7380b878-6724-4bed-84f5-6e4c4d1dcc7d, event type delete
I0905 05:05:06.130553       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-7578" (1.107806ms)
I0905 05:05:06.131046       1 tokens_controller.go:252] syncServiceAccount(azurefile-7578/default), service account deleted, removing tokens
I0905 05:05:06.144743       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-7578" (1.6µs)
I0905 05:05:06.145006       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-7578, estimate: 0, errors: <nil>
I0905 05:05:06.158285       1 namespace_controller.go:180] Finished syncing namespace "azurefile-7578" (205.425774ms)
I0905 05:05:06.608649       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-565" (2.901µs)
I0905 05:05:06.721639       1 publisher.go:181] Finished syncing namespace "azurefile-8666" (4.894026ms)
I0905 05:05:06.725669       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-8666" (8.913847ms)
I0905 05:05:07.023860       1 namespace_controller.go:185] Namespace has been deleted azurefile-4547
I0905 05:05:07.023897       1 namespace_controller.go:180] Finished syncing namespace "azurefile-4547" (67.201µs)
I0905 05:05:07.326127       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-1968
I0905 05:05:07.420875       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-1968, name default-token-pddhh, uid 995b53ac-be04-48e4-94f8-af22a3f83b13, event type delete
E0905 05:05:07.438544       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-1968/default: secrets "default-token-kp76l" is forbidden: unable to create new content in namespace azurefile-1968 because it is being terminated
I0905 05:05:07.473960       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-1968, name kube-root-ca.crt, uid 0e77963b-2559-4bd1-96be-4b6d6c239218, event type delete
I0905 05:05:07.476098       1 publisher.go:181] Finished syncing namespace "azurefile-1968" (2.370313ms)
I0905 05:05:07.485182       1 tokens_controller.go:252] syncServiceAccount(azurefile-1968/default), service account deleted, removing tokens
I0905 05:05:07.485235       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-1968, name default, uid 21a5f481-9710-4596-8d09-5a7d15310b83, event type delete
I0905 05:05:07.485357       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1968" (3.3µs)
I0905 05:05:07.512544       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1968" (1.8µs)
... skipping 22 lines ...
I0905 05:05:09.788731       1 namespace_controller.go:185] Namespace has been deleted azurefile-9183
I0905 05:05:09.788778       1 namespace_controller.go:180] Finished syncing namespace "azurefile-9183" (96.6µs)
I0905 05:05:10.188546       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-1359
I0905 05:05:10.213251       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-1359, name kube-root-ca.crt, uid c6be9730-e80e-4f79-8248-572f6b89b225, event type delete
I0905 05:05:10.217491       1 publisher.go:181] Finished syncing namespace "azurefile-1359" (4.516824ms)
I0905 05:05:10.306336       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-1359, name default-token-dsj8n, uid c254f418-c99c-404b-a30e-e14008f2031b, event type delete
E0905 05:05:10.322777       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-1359/default: secrets "default-token-qvltp" is forbidden: unable to create new content in namespace azurefile-1359 because it is being terminated
I0905 05:05:10.359595       1 tokens_controller.go:252] syncServiceAccount(azurefile-1359/default), service account deleted, removing tokens
I0905 05:05:10.360683       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1359" (2.5µs)
I0905 05:05:10.360833       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-1359, name default, uid d142424c-969c-4579-9665-f8585a781f8b, event type delete
I0905 05:05:10.379432       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1359" (2µs)
I0905 05:05:10.381307       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-1359, estimate: 0, errors: <nil>
I0905 05:05:10.400484       1 namespace_controller.go:180] Finished syncing namespace "azurefile-1359" (216.019429ms)
... skipping 19 lines ...
I0905 05:05:12.513447       1 namespace_controller.go:180] Finished syncing namespace "azurefile-1968" (92.3µs)
I0905 05:05:12.746963       1 httplog.go:94] "HTTP" verb="GET" URI="/healthz" latency="162.901µs" userAgent="kube-probe/1.21+" srcIP="127.0.0.1:53220" resp=200
I0905 05:05:12.971804       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-ky5ra6-md-0-vxfmx"
I0905 05:05:13.035032       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-8666
I0905 05:05:13.090450       1 reflector.go:381] k8s.io/client-go/informers/factory.go:134: forcing resync
I0905 05:05:13.172560       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-8666, name default-token-rt5pz, uid 6ecf0814-d573-48a5-914a-6fdf6b6f5da7, event type delete
E0905 05:05:13.211647       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-8666/default: secrets "default-token-4bt8g" is forbidden: unable to create new content in namespace azurefile-8666 because it is being terminated
I0905 05:05:13.223417       1 tokens_controller.go:252] syncServiceAccount(azurefile-8666/default), service account deleted, removing tokens
I0905 05:05:13.223717       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-8666, name default, uid 06a51045-bb3c-4427-bf77-6402da689418, event type delete
I0905 05:05:13.224182       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-8666" (2µs)
I0905 05:05:13.235686       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-8666, name kube-root-ca.crt, uid db815b3e-1d03-4934-9357-9a09c94e208d, event type delete
I0905 05:05:13.238126       1 publisher.go:181] Finished syncing namespace "azurefile-8666" (2.653517ms)
I0905 05:05:13.239491       1 pv_controller_base.go:528] resyncing PV controller
... skipping 21 lines ...
I0905 05:05:15.379834       1 namespace_controller.go:185] Namespace has been deleted azurefile-1359
I0905 05:05:15.379858       1 namespace_controller.go:180] Finished syncing namespace "azurefile-1359" (39µs)
I0905 05:05:15.829671       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-6611
I0905 05:05:15.858250       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-6611, name kube-root-ca.crt, uid 824b2ec1-e60c-4ede-9ec4-2d6e716fe4b0, event type delete
I0905 05:05:15.861542       1 publisher.go:181] Finished syncing namespace "azurefile-6611" (3.630824ms)
I0905 05:05:15.866957       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-6611, name default-token-tfgth, uid a2cb5ca7-e755-4fbb-bf41-85c21b8009eb, event type delete
E0905 05:05:15.893617       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-6611/default: secrets "default-token-k9767" is forbidden: unable to create new content in namespace azurefile-6611 because it is being terminated
I0905 05:05:15.933794       1 tokens_controller.go:252] syncServiceAccount(azurefile-6611/default), service account deleted, removing tokens
I0905 05:05:15.934398       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-6611" (2.9µs)
I0905 05:05:15.934355       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-6611, name default, uid d5a59c1c-858b-4e97-8b5d-50f5b4e2f22e, event type delete
I0905 05:05:16.027582       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-6611" (2.5µs)
I0905 05:05:16.027839       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-6611, estimate: 0, errors: <nil>
I0905 05:05:16.037696       1 namespace_controller.go:180] Finished syncing namespace "azurefile-6611" (214.018714ms)
... skipping 11 lines ...
I0905 05:05:17.433927       1 namespace_controller.go:180] Finished syncing namespace "azurefile-6157" (218.599044ms)
2022/09/05 05:05:18 ===================================================

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

Ran 6 of 34 Specs in 379.321 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-jx79d, container manager
STEP: Dumping workload cluster default/capz-ky5ra6 logs
Sep  5 05:06:51.989: INFO: Collecting logs for Linux node capz-ky5ra6-control-plane-7ks5g in cluster capz-ky5ra6 in namespace default

Sep  5 05:07:51.991: INFO: Collecting boot logs for AzureMachine capz-ky5ra6-control-plane-7ks5g

Failed to get logs for machine capz-ky5ra6-control-plane-znvgv, cluster default/capz-ky5ra6: open /etc/azure-ssh/azure-ssh: no such file or directory
Sep  5 05:07:53.497: INFO: Collecting logs for Linux node capz-ky5ra6-md-0-vxfmx in cluster capz-ky5ra6 in namespace default

Sep  5 05:08:53.499: INFO: Collecting boot logs for AzureMachine capz-ky5ra6-md-0-vxfmx

Failed to get logs for machine capz-ky5ra6-md-0-dcfd7bb9d-62srs, cluster default/capz-ky5ra6: open /etc/azure-ssh/azure-ssh: no such file or directory
Sep  5 05:08:54.050: INFO: Collecting logs for Linux node capz-ky5ra6-md-0-lz9m9 in cluster capz-ky5ra6 in namespace default

Sep  5 05:09:54.052: INFO: Collecting boot logs for AzureMachine capz-ky5ra6-md-0-lz9m9

Failed to get logs for machine capz-ky5ra6-md-0-dcfd7bb9d-r7qrn, cluster default/capz-ky5ra6: open /etc/azure-ssh/azure-ssh: no such file or directory
STEP: Dumping workload cluster default/capz-ky5ra6 kube-system pod logs
STEP: Collecting events for Pod kube-system/calico-node-mgxkb
STEP: Collecting events for Pod kube-system/calico-node-nvk8z
STEP: Creating log watcher for controller kube-system/calico-kube-controllers-969cf87c4-h6zz7, container calico-kube-controllers
STEP: Creating log watcher for controller kube-system/kube-controller-manager-capz-ky5ra6-control-plane-7ks5g, container kube-controller-manager
STEP: Collecting events for Pod kube-system/calico-node-qvq4z
STEP: Collecting events for Pod kube-system/kube-controller-manager-capz-ky5ra6-control-plane-7ks5g
STEP: Creating log watcher for controller kube-system/calico-node-mgxkb, container calico-node
STEP: failed to find events of Pod "kube-controller-manager-capz-ky5ra6-control-plane-7ks5g"
STEP: Creating log watcher for controller kube-system/kube-proxy-7cqk5, container kube-proxy
STEP: Creating log watcher for controller kube-system/calico-node-qvq4z, container calico-node
STEP: Collecting events for Pod kube-system/etcd-capz-ky5ra6-control-plane-7ks5g
STEP: Collecting events for Pod kube-system/coredns-558bd4d5db-ssg4p
STEP: failed to find events of Pod "etcd-capz-ky5ra6-control-plane-7ks5g"
STEP: Collecting events for Pod kube-system/calico-kube-controllers-969cf87c4-h6zz7
STEP: Creating log watcher for controller kube-system/coredns-558bd4d5db-ssg4p, container coredns
STEP: Creating log watcher for controller kube-system/coredns-558bd4d5db-bxgxf, container coredns
STEP: Creating log watcher for controller kube-system/calico-node-nvk8z, container calico-node
STEP: Fetching kube-system pod logs took 1.217060949s
STEP: Dumping workload cluster default/capz-ky5ra6 Azure activity log
STEP: Creating log watcher for controller kube-system/kube-apiserver-capz-ky5ra6-control-plane-7ks5g, container kube-apiserver
STEP: Collecting events for Pod kube-system/metrics-server-8c95fb79b-tp554
STEP: Collecting events for Pod kube-system/kube-proxy-7cqk5
STEP: Collecting events for Pod kube-system/kube-apiserver-capz-ky5ra6-control-plane-7ks5g
STEP: Creating log watcher for controller kube-system/etcd-capz-ky5ra6-control-plane-7ks5g, container etcd
STEP: failed to find events of Pod "kube-apiserver-capz-ky5ra6-control-plane-7ks5g"
STEP: Collecting events for Pod kube-system/coredns-558bd4d5db-bxgxf
STEP: Creating log watcher for controller kube-system/kube-proxy-lkt88, container kube-proxy
STEP: Creating log watcher for controller kube-system/kube-scheduler-capz-ky5ra6-control-plane-7ks5g, container kube-scheduler
STEP: Collecting events for Pod kube-system/kube-proxy-w7hv9
STEP: Collecting events for Pod kube-system/kube-proxy-lkt88
STEP: Creating log watcher for controller kube-system/metrics-server-8c95fb79b-tp554, container metrics-server
STEP: Creating log watcher for controller kube-system/kube-proxy-w7hv9, container kube-proxy
STEP: Collecting events for Pod kube-system/kube-scheduler-capz-ky5ra6-control-plane-7ks5g
STEP: failed to find events of Pod "kube-scheduler-capz-ky5ra6-control-plane-7ks5g"
STEP: Fetching activity logs took 5.068829225s
================ REDACTING LOGS ================
All sensitive variables are redacted
cluster.cluster.x-k8s.io "capz-ky5ra6" deleted
/home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure/hack/tools/bin/kind-v0.14.0 delete cluster --name=capz || true
Deleting cluster "capz" ...
... skipping 12 lines ...