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

No Test Failures!


Show 6 Passed Tests

Show 28 Skipped Tests

Error lines from build-log.txt

... skipping 637 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-00pr9t-kubeconfig; do sleep 1; done"
capz-00pr9t-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-00pr9t-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-00pr9t-control-plane-82gfg   NotReady   control-plane,master   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-00pr9t-control-plane-82gfg condition met
node/capz-00pr9t-md-0-64h8r condition met
... skipping 93 lines ...
Sep  7 05:01:24.032: INFO: PersistentVolumeClaim pvc-pk9dd found but phase is Pending instead of Bound.
Sep  7 05:01:26.091: INFO: PersistentVolumeClaim pvc-pk9dd found and phase=Bound (28.906495053s)
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  7 05:01:26.270: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-v47ch" in namespace "azurefile-2540" to be "Succeeded or Failed"
Sep  7 05:01:26.328: INFO: Pod "azurefile-volume-tester-v47ch": Phase="Pending", Reason="", readiness=false. Elapsed: 58.057652ms
Sep  7 05:01:28.388: INFO: Pod "azurefile-volume-tester-v47ch": Phase="Pending", Reason="", readiness=false. Elapsed: 2.118096192s
Sep  7 05:01:30.452: INFO: Pod "azurefile-volume-tester-v47ch": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.181559959s
STEP: Saw pod success
Sep  7 05:01:30.452: INFO: Pod "azurefile-volume-tester-v47ch" satisfied condition "Succeeded or Failed"
Sep  7 05:01:30.452: INFO: deleting Pod "azurefile-2540"/"azurefile-volume-tester-v47ch"
Sep  7 05:01:30.524: INFO: Pod azurefile-volume-tester-v47ch has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-v47ch in namespace azurefile-2540
Sep  7 05:01:30.593: INFO: deleting PVC "azurefile-2540"/"pvc-pk9dd"
Sep  7 05:01:30.593: INFO: Deleting PersistentVolumeClaim "pvc-pk9dd"
... skipping 158 lines ...
Sep  7 05:03:26.368: INFO: PersistentVolumeClaim pvc-jgffb found but phase is Pending instead of Bound.
Sep  7 05:03:28.428: INFO: PersistentVolumeClaim pvc-jgffb found and phase=Bound (26.830485473s)
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  7 05:03:28.606: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-8hgd5" in namespace "azurefile-2790" to be "Error status code"
Sep  7 05:03:28.664: INFO: Pod "azurefile-volume-tester-8hgd5": Phase="Pending", Reason="", readiness=false. Elapsed: 58.279493ms
Sep  7 05:03:30.724: INFO: Pod "azurefile-volume-tester-8hgd5": Phase="Pending", Reason="", readiness=false. Elapsed: 2.118159066s
Sep  7 05:03:32.783: INFO: Pod "azurefile-volume-tester-8hgd5": Phase="Pending", Reason="", readiness=false. Elapsed: 4.177030572s
Sep  7 05:03:34.845: INFO: Pod "azurefile-volume-tester-8hgd5": Phase="Pending", Reason="", readiness=false. Elapsed: 6.239451756s
Sep  7 05:03:36.905: INFO: Pod "azurefile-volume-tester-8hgd5": Phase="Pending", Reason="", readiness=false. Elapsed: 8.299188277s
Sep  7 05:03:38.965: INFO: Pod "azurefile-volume-tester-8hgd5": Phase="Pending", Reason="", readiness=false. Elapsed: 10.359105438s
... skipping 12 lines ...
Sep  7 05:04:05.741: INFO: Pod "azurefile-volume-tester-8hgd5": Phase="Pending", Reason="", readiness=false. Elapsed: 37.135401819s
Sep  7 05:04:07.801: INFO: Pod "azurefile-volume-tester-8hgd5": Phase="Pending", Reason="", readiness=false. Elapsed: 39.195386222s
Sep  7 05:04:09.861: INFO: Pod "azurefile-volume-tester-8hgd5": Phase="Pending", Reason="", readiness=false. Elapsed: 41.255686316s
Sep  7 05:04:11.921: INFO: Pod "azurefile-volume-tester-8hgd5": Phase="Pending", Reason="", readiness=false. Elapsed: 43.31564568s
Sep  7 05:04:13.981: INFO: Pod "azurefile-volume-tester-8hgd5": Phase="Pending", Reason="", readiness=false. Elapsed: 45.375499903s
Sep  7 05:04:16.040: INFO: Pod "azurefile-volume-tester-8hgd5": Phase="Pending", Reason="", readiness=false. Elapsed: 47.43452954s
Sep  7 05:04:18.102: INFO: Pod "azurefile-volume-tester-8hgd5": Phase="Failed", Reason="", readiness=false. Elapsed: 49.496372448s
STEP: Saw pod failure
Sep  7 05:04:18.102: INFO: Pod "azurefile-volume-tester-8hgd5" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Sep  7 05:04:18.170: INFO: deleting Pod "azurefile-2790"/"azurefile-volume-tester-8hgd5"
Sep  7 05:04:18.231: INFO: Pod azurefile-volume-tester-8hgd5 has the following logs: touch: /mnt/test-1/data: Read-only file system

STEP: Deleting pod azurefile-volume-tester-8hgd5 in namespace azurefile-2790
Sep  7 05:04:18.298: INFO: deleting PVC "azurefile-2790"/"pvc-jgffb"
... skipping 182 lines ...
Sep  7 05:06:19.744: INFO: PersistentVolumeClaim pvc-8rpr6 found but phase is Pending instead of Bound.
Sep  7 05:06:21.804: INFO: PersistentVolumeClaim pvc-8rpr6 found and phase=Bound (2.118094597s)
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  7 05:06:21.981: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-lcj9s" in namespace "azurefile-4538" to be "Succeeded or Failed"
Sep  7 05:06:22.039: INFO: Pod "azurefile-volume-tester-lcj9s": Phase="Pending", Reason="", readiness=false. Elapsed: 58.091515ms
Sep  7 05:06:24.101: INFO: Pod "azurefile-volume-tester-lcj9s": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.120058768s
STEP: Saw pod success
Sep  7 05:06:24.101: INFO: Pod "azurefile-volume-tester-lcj9s" 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  7 05:06:55.145: INFO: deleting Pod "azurefile-4538"/"azurefile-volume-tester-lcj9s"
... skipping 869 lines ...
I0907 04:56:03.149041       1 tlsconfig.go:178] loaded client CA [1/"client-ca-bundle::/etc/kubernetes/pki/ca.crt,request-header::/etc/kubernetes/pki/front-proxy-ca.crt"]: "kubernetes" [] issuer="<self>" (2022-09-07 04:49:02 +0000 UTC to 2032-09-04 04:54:02 +0000 UTC (now=2022-09-07 04:56:03.148995588 +0000 UTC))
I0907 04:56:03.149385       1 tlsconfig.go:200] loaded serving cert ["Generated self signed cert"]: "localhost@1662526562" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer="localhost-ca@1662526561" (2022-09-07 03:56:01 +0000 UTC to 2023-09-07 03:56:01 +0000 UTC (now=2022-09-07 04:56:03.149372389 +0000 UTC))
I0907 04:56:03.149684       1 named_certificates.go:53] loaded SNI cert [0/"self-signed loopback"]: "apiserver-loopback-client@1662526563" [serving] validServingFor=[apiserver-loopback-client] issuer="apiserver-loopback-client-ca@1662526562" (2022-09-07 03:56:02 +0000 UTC to 2023-09-07 03:56:02 +0000 UTC (now=2022-09-07 04:56:03.149671689 +0000 UTC))
I0907 04:56:03.149827       1 tlsconfig.go:240] Starting DynamicServingCertificateController
I0907 04:56:03.149961       1 secure_serving.go:202] Serving securely on 127.0.0.1:10257
I0907 04:56:03.150457       1 leaderelection.go:243] attempting to acquire leader lease kube-system/kube-controller-manager...
E0907 04:56:05.194517       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"
I0907 04:56:05.194551       1 leaderelection.go:248] failed to acquire lease kube-system/kube-controller-manager
I0907 04:56:08.808391       1 leaderelection.go:253] successfully acquired lease kube-system/kube-controller-manager
I0907 04:56:08.808862       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-00pr9t-control-plane-82gfg_366bedf4-613a-436f-83c1-7a49817a51f7 became leader"
I0907 04:56:09.091895       1 request.go:600] Waited for 93.925696ms due to client-side throttling, not priority and fairness, request: GET:https://10.0.0.4:6443/apis/admissionregistration.k8s.io/v1beta1?timeout=32s
I0907 04:56:09.142102       1 request.go:600] Waited for 144.121903ms due to client-side throttling, not priority and fairness, request: GET:https://10.0.0.4:6443/apis/apiextensions.k8s.io/v1?timeout=32s
I0907 04:56:09.192638       1 request.go:600] Waited for 194.603451ms due to client-side throttling, not priority and fairness, request: GET:https://10.0.0.4:6443/apis/apiextensions.k8s.io/v1beta1?timeout=32s
I0907 04:56:09.242247       1 request.go:600] Waited for 244.211671ms 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 ...
I0907 04:56:09.546202       1 reflector.go:255] Listing and watching *v1.Node from k8s.io/client-go/informers/factory.go:134
I0907 04:56:09.546276       1 reflector.go:219] Starting reflector *v1.Secret (14h24m7.885795686s) from k8s.io/client-go/informers/factory.go:134
I0907 04:56:09.546351       1 reflector.go:255] Listing and watching *v1.Secret from k8s.io/client-go/informers/factory.go:134
I0907 04:56:09.546302       1 shared_informer.go:240] Waiting for caches to sync for tokens
I0907 04:56:09.546091       1 reflector.go:219] Starting reflector *v1.ServiceAccount (14h24m7.885795686s) from k8s.io/client-go/informers/factory.go:134
I0907 04:56:09.546554       1 reflector.go:255] Listing and watching *v1.ServiceAccount from k8s.io/client-go/informers/factory.go:134
W0907 04:56:09.571830       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
I0907 04:56:09.571850       1 controllermanager.go:559] Starting "deployment"
I0907 04:56:09.577156       1 controllermanager.go:574] Started "deployment"
I0907 04:56:09.577175       1 controllermanager.go:559] Starting "horizontalpodautoscaling"
I0907 04:56:09.577300       1 deployment_controller.go:153] "Starting controller" controller="deployment"
I0907 04:56:09.577428       1 shared_informer.go:240] Waiting for caches to sync for deployment
I0907 04:56:09.592971       1 controllermanager.go:574] Started "horizontalpodautoscaling"
... skipping 86 lines ...
I0907 04:56:09.899198       1 plugins.go:639] Loaded volume plugin "kubernetes.io/portworx-volume"
I0907 04:56:09.899216       1 plugins.go:639] Loaded volume plugin "kubernetes.io/scaleio"
I0907 04:56:09.899244       1 plugins.go:639] Loaded volume plugin "kubernetes.io/storageos"
I0907 04:56:09.899276       1 plugins.go:639] Loaded volume plugin "kubernetes.io/fc"
I0907 04:56:09.899287       1 plugins.go:639] Loaded volume plugin "kubernetes.io/iscsi"
I0907 04:56:09.899296       1 plugins.go:639] Loaded volume plugin "kubernetes.io/rbd"
I0907 04:56:09.899370       1 csi_plugin.go:256] Cast from VolumeHost to KubeletVolumeHost failed. Skipping CSINode initialization, not running on kubelet
I0907 04:56:09.899384       1 plugins.go:639] Loaded volume plugin "kubernetes.io/csi"
I0907 04:56:09.899571       1 controllermanager.go:574] Started "attachdetach"
I0907 04:56:09.899588       1 controllermanager.go:559] Starting "endpoint"
I0907 04:56:09.899627       1 attach_detach_controller.go:328] Starting attach detach controller
I0907 04:56:09.899647       1 shared_informer.go:240] Waiting for caches to sync for attach detach
I0907 04:56:09.899801       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-00pr9t-control-plane-82gfg"
W0907 04:56:09.899833       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-00pr9t-control-plane-82gfg" does not exist
I0907 04:56:10.050672       1 controllermanager.go:574] Started "endpoint"
I0907 04:56:10.051130       1 controllermanager.go:559] Starting "statefulset"
I0907 04:56:10.051085       1 endpoints_controller.go:189] Starting endpoint controller
I0907 04:56:10.051395       1 shared_informer.go:240] Waiting for caches to sync for endpoint
I0907 04:56:10.199835       1 controllermanager.go:574] Started "statefulset"
I0907 04:56:10.200133       1 controllermanager.go:559] Starting "bootstrapsigner"
... skipping 71 lines ...
I0907 04:56:11.808130       1 plugins.go:639] Loaded volume plugin "kubernetes.io/azure-file"
I0907 04:56:11.808139       1 plugins.go:639] Loaded volume plugin "kubernetes.io/flocker"
I0907 04:56:11.808155       1 plugins.go:639] Loaded volume plugin "kubernetes.io/portworx-volume"
I0907 04:56:11.808167       1 plugins.go:639] Loaded volume plugin "kubernetes.io/scaleio"
I0907 04:56:11.808177       1 plugins.go:639] Loaded volume plugin "kubernetes.io/local-volume"
I0907 04:56:11.808186       1 plugins.go:639] Loaded volume plugin "kubernetes.io/storageos"
I0907 04:56:11.808213       1 csi_plugin.go:256] Cast from VolumeHost to KubeletVolumeHost failed. Skipping CSINode initialization, not running on kubelet
I0907 04:56:11.808220       1 plugins.go:639] Loaded volume plugin "kubernetes.io/csi"
I0907 04:56:11.808272       1 controllermanager.go:574] Started "persistentvolume-binder"
I0907 04:56:11.808281       1 controllermanager.go:559] Starting "pv-protection"
I0907 04:56:11.808319       1 pv_controller_base.go:308] Starting persistent volume controller
I0907 04:56:11.808326       1 shared_informer.go:240] Waiting for caches to sync for persistent volume
I0907 04:56:11.958389       1 controllermanager.go:574] Started "pv-protection"
... skipping 594 lines ...
I0907 04:56:14.701830       1 request.go:600] Waited for 603.820781ms due to client-side throttling, not priority and fairness, request: GET:https://10.0.0.4:6443/apis/node.k8s.io/v1/runtimeclasses?limit=500&resourceVersion=0
I0907 04:56:14.701844       1 request.go:600] Waited for 413.255753ms due to client-side throttling, not priority and fairness, request: GET:https://10.0.0.4:6443/api/v1/namespaces/kube-system/serviceaccounts/disruption-controller
I0907 04:56:14.702563       1 graph_builder.go:279] garbage controller monitor not yet synced: node.k8s.io/v1, Resource=runtimeclasses
I0907 04:56:14.704848       1 daemon_controller.go:248] Updating daemon set calico-node
I0907 04:56:14.725908       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/metrics-server" duration="647.276871ms"
I0907 04:56:14.726061       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/coredns" duration="648.23366ms"
I0907 04:56:14.726150       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"
I0907 04:56:14.726224       1 deployment_controller.go:576] "Started syncing deployment" deployment="kube-system/coredns" startTime="2022-09-07 04:56:14.726204252 +0000 UTC m=+13.915980625"
I0907 04:56:14.727076       1 deployment_util.go:808] Deployment "coredns" timed out (false) [last progress check: 2022-09-07 04:56:14 +0000 UTC - now: 2022-09-07 04:56:14.727071403 +0000 UTC m=+13.916847776]
I0907 04:56:14.726078       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"
I0907 04:56:14.729860       1 deployment_controller.go:576] "Started syncing deployment" deployment="kube-system/metrics-server" startTime="2022-09-07 04:56:14.729835648 +0000 UTC m=+13.919612021"
I0907 04:56:14.730943       1 deployment_util.go:808] Deployment "metrics-server" timed out (false) [last progress check: 2022-09-07 04:56:14 +0000 UTC - now: 2022-09-07 04:56:14.730937186 +0000 UTC m=+13.920713559]
I0907 04:56:14.725992       1 deployment_controller.go:578] "Finished syncing deployment" deployment="kube-system/calico-kube-controllers" duration="514.853758ms"
I0907 04:56:14.731420       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"
I0907 04:56:14.731560       1 deployment_controller.go:576] "Started syncing deployment" deployment="kube-system/calico-kube-controllers" startTime="2022-09-07 04:56:14.731539352 +0000 UTC m=+13.921315725"
I0907 04:56:14.732051       1 deployment_util.go:808] Deployment "calico-kube-controllers" timed out (false) [last progress check: 2022-09-07 04:56:14 +0000 UTC - now: 2022-09-07 04:56:14.732045723 +0000 UTC m=+13.921822196]
I0907 04:56:14.745874       1 request.go:600] Waited for 441.254655ms 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
I0907 04:56:14.746310       1 deployment_controller.go:176] "Updating deployment" deployment="kube-system/coredns"
I0907 04:56:14.749851       1 shared_informer.go:270] caches populated
I0907 04:56:14.749886       1 shared_informer.go:247] Caches are synced for garbage collector 
... skipping 408 lines ...
I0907 04:56:37.916267       1 controller.go:776] Running updateLoadBalancerHosts(len(services)==0, workers==1)
I0907 04:56:37.916275       1 controller.go:790] Finished updateLoadBalancerHosts
I0907 04:56:37.916281       1 controller.go:731] It took 1.4701e-05 seconds to finish nodeSyncInternal
I0907 04:56:37.930884       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-00pr9t-control-plane-82gfg"
I0907 04:56:37.932215       1 controller_utils.go:221] Made sure that Node capz-00pr9t-control-plane-82gfg has no [&Taint{Key:node.kubernetes.io/not-ready,Value:,Effect:NoSchedule,TimeAdded:<nil>,}] Taint
I0907 04:56:38.613037       1 httplog.go:94] "HTTP" verb="GET" URI="/healthz" latency="71.704µs" userAgent="kube-probe/1.21+" srcIP="127.0.0.1:35002" resp=200
I0907 04:56:39.111610       1 node_lifecycle_controller.go:1039] ReadyCondition for Node capz-00pr9t-control-plane-82gfg transitioned from &NodeCondition{Type:Ready,Status:False,LastHeartbeatTime:2022-09-07 04:56:27 +0000 UTC,LastTransitionTime:2022-09-07 04:55:52 +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-07 04:56:37 +0000 UTC,LastTransitionTime:2022-09-07 04:56:37 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status. AppArmor enabled,}
I0907 04:56:39.111719       1 node_lifecycle_controller.go:1047] Node capz-00pr9t-control-plane-82gfg ReadyCondition updated. Updating timestamp.
I0907 04:56:39.111743       1 node_lifecycle_controller.go:893] Node capz-00pr9t-control-plane-82gfg is healthy again, removing all taints
I0907 04:56:39.111760       1 node_lifecycle_controller.go:1191] Controller detected that some Nodes are Ready. Exiting master disruption mode.
I0907 04:56:40.223149       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-00pr9t-control-plane-82gfg"
I0907 04:56:40.818918       1 tokencleaner.go:166] Finished syncing secret "kube-system/bootstrap-token-ufuutv" (8µs)
I0907 04:56:41.274359       1 tokencleaner.go:166] Finished syncing secret "kube-system/bootstrap-token-rr0nkq" (9.701µs)
... skipping 34 lines ...
I0907 04:56:44.315863       1 reflector.go:219] Starting reflector *v1.PartialObjectMetadata (13h58m52.217612597s) from k8s.io/client-go/metadata/metadatainformer/informer.go:90
I0907 04:56:44.315932       1 reflector.go:255] Listing and watching *v1.PartialObjectMetadata from k8s.io/client-go/metadata/metadatainformer/informer.go:90
I0907 04:56:44.416754       1 shared_informer.go:270] caches populated
I0907 04:56:44.416772       1 shared_informer.go:247] Caches are synced for resource quota 
I0907 04:56:44.416781       1 resource_quota_controller.go:454] synced quota controller
I0907 04:56:44.793906       1 tokencleaner.go:166] Finished syncing secret "kube-system/bootstrap-token-ufuutv" (12.201µs)
W0907 04:56:44.862792       1 garbagecollector.go:703] failed to discover some groups: map[metrics.k8s.io/v1beta1:the server is currently unable to handle the request]
I0907 04:56:44.863309       1 garbagecollector.go:213] syncing garbage collector with updated resources from discovery (attempt 1): added: [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: []
I0907 04:56:44.863690       1 garbagecollector.go:219] reset restmapper
E0907 04:56:44.871619       1 memcache.go:196] couldn't get resource list for metrics.k8s.io/v1beta1: the server is currently unable to handle the request
E0907 04:56:44.895557       1 memcache.go:101] couldn't get resource list for metrics.k8s.io/v1beta1: the server is currently unable to handle the request
I0907 04:56:44.896996       1 graph_builder.go:174] using a shared informer for resource "crd.projectcalico.org/v1, Resource=ipamblocks", kind "crd.projectcalico.org/v1, Kind=IPAMBlock"
I0907 04:56:44.897131       1 graph_builder.go:174] using a shared informer for resource "crd.projectcalico.org/v1, Resource=ippools", kind "crd.projectcalico.org/v1, Kind=IPPool"
... skipping 255 lines ...
I0907 04:57:14.053420       1 reflector.go:381] k8s.io/client-go/informers/factory.go:134: forcing resync
I0907 04:57:14.116371       1 gc_controller.go:161] GC'ing orphaned
I0907 04:57:14.116393       1 gc_controller.go:224] GC'ing unscheduled pods which are terminating.
I0907 04:57:14.213064       1 pv_controller_base.go:528] resyncing PV controller
E0907 04:57:14.450640       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
I0907 04:57:14.450700       1 resource_quota_controller.go:424] no resource updates from discovery, skipping resource quota sync
W0907 04:57:15.242993       1 garbagecollector.go:703] failed to discover some groups: map[metrics.k8s.io/v1beta1:the server is currently unable to handle the request]
I0907 04:57:17.958100       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-00pr9t-control-plane-82gfg"
I0907 04:57:18.611342       1 httplog.go:94] "HTTP" verb="GET" URI="/healthz" latency="51.098µs" userAgent="kube-probe/1.21+" srcIP="127.0.0.1:41716" resp=200
I0907 04:57:19.127498       1 node_lifecycle_controller.go:1047] Node capz-00pr9t-control-plane-82gfg ReadyCondition updated. Updating timestamp.
I0907 04:57:28.403034       1 endpoints_controller.go:555] Update endpoints for kube-system/metrics-server, ready: 1 not ready: 0
I0907 04:57:28.403376       1 disruption.go:427] updatePod called on pod "metrics-server-8c95fb79b-rdjwm"
I0907 04:57:28.403436       1 disruption.go:490] No PodDisruptionBudgets found for pod metrics-server-8c95fb79b-rdjwm, PodDisruptionBudget controller will avoid syncing.
... skipping 58 lines ...
I0907 04:57:46.359798       1 taint_manager.go:435] "Noticed node update" node={nodeName:capz-00pr9t-md-0-pw99v}
I0907 04:57:46.359830       1 taint_manager.go:440] "Updating known taints on node" node="capz-00pr9t-md-0-pw99v" taints=[]
I0907 04:57:46.362472       1 controller_utils.go:189] Controller expectations fulfilled &controller.ControlleeExpectations{add:0, del:0, key:"kube-system/kube-proxy", timestamp:time.Time{wall:0xc0be26fddd4d67c8, ext:22681388477, loc:(*time.Location)(0x731ea80)}}
I0907 04:57:46.362780       1 controller_utils.go:223] Setting expectations &controller.ControlleeExpectations{add:1, del:0, key:"kube-system/kube-proxy", timestamp:time.Time{wall:0xc0be2712959f7dfd, ext:105552550386, loc:(*time.Location)(0x731ea80)}}
I0907 04:57:46.362994       1 daemon_controller.go:968] Nodes needing daemon pods for daemon set kube-proxy: [capz-00pr9t-md-0-pw99v], creating 1
I0907 04:57:46.363344       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-00pr9t-md-0-pw99v"
W0907 04:57:46.363365       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-00pr9t-md-0-pw99v" does not exist
I0907 04:57:46.363465       1 controller.go:693] Ignoring node capz-00pr9t-md-0-pw99v with Ready condition status False
I0907 04:57:46.363480       1 controller.go:272] Triggering nodeSync
I0907 04:57:46.363488       1 controller.go:291] nodeSync has been triggered
I0907 04:57:46.363716       1 controller.go:776] Running updateLoadBalancerHosts(len(services)==0, workers==1)
I0907 04:57:46.363730       1 controller.go:790] Finished updateLoadBalancerHosts
I0907 04:57:46.363735       1 controller.go:731] It took 0.000242 seconds to finish nodeSyncInternal
... skipping 196 lines ...
I0907 04:57:54.109683       1 controller.go:776] Running updateLoadBalancerHosts(len(services)==0, workers==1)
I0907 04:57:54.109693       1 controller.go:790] Finished updateLoadBalancerHosts
I0907 04:57:54.109698       1 controller.go:731] It took 1.62e-05 seconds to finish nodeSyncInternal
I0907 04:57:54.117966       1 gc_controller.go:161] GC'ing orphaned
I0907 04:57:54.117985       1 gc_controller.go:224] GC'ing unscheduled pods which are terminating.
I0907 04:57:55.207400       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-00pr9t-md-0-64h8r"
W0907 04:57:55.223113       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-00pr9t-md-0-64h8r" does not exist
I0907 04:57:55.210305       1 taint_manager.go:435] "Noticed node update" node={nodeName:capz-00pr9t-md-0-64h8r}
I0907 04:57:55.223573       1 taint_manager.go:440] "Updating known taints on node" node="capz-00pr9t-md-0-64h8r" taints=[]
I0907 04:57:55.216239       1 controller.go:693] Ignoring node capz-00pr9t-md-0-pw99v with Ready condition status False
I0907 04:57:55.223829       1 controller.go:693] Ignoring node capz-00pr9t-md-0-64h8r with Ready condition status False
I0907 04:57:55.223944       1 controller.go:272] Triggering nodeSync
I0907 04:57:55.224227       1 controller.go:291] nodeSync has been triggered
... skipping 352 lines ...
I0907 04:58:16.633272       1 controller_utils.go:223] Setting expectations &controller.ControlleeExpectations{add:0, del:0, key:"kube-system/calico-node", timestamp:time.Time{wall:0xc0be271a25beed41, ext:135823046042, loc:(*time.Location)(0x731ea80)}}
I0907 04:58:16.633287       1 daemon_controller.go:968] Nodes needing daemon pods for daemon set calico-node: [], creating 0
I0907 04:58:16.633457       1 daemon_controller.go:1030] Pods to delete for daemon set calico-node: [], deleting 0
I0907 04:58:16.633636       1 daemon_controller.go:1103] Updating daemon set status
I0907 04:58:16.633737       1 daemon_controller.go:1163] Finished syncing daemon set "kube-system/calico-node" (2.125201ms)
I0907 04:58:18.611367       1 httplog.go:94] "HTTP" verb="GET" URI="/healthz" latency="68.1µs" userAgent="kube-probe/1.21+" srcIP="127.0.0.1:41602" resp=200
I0907 04:58:19.137849       1 node_lifecycle_controller.go:1039] ReadyCondition for Node capz-00pr9t-md-0-pw99v transitioned from &NodeCondition{Type:Ready,Status:False,LastHeartbeatTime:2022-09-07 04:57:56 +0000 UTC,LastTransitionTime:2022-09-07 04:57:46 +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-07 04:58:16 +0000 UTC,LastTransitionTime:2022-09-07 04:58:16 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status. AppArmor enabled,}
I0907 04:58:19.137925       1 node_lifecycle_controller.go:1047] Node capz-00pr9t-md-0-pw99v ReadyCondition updated. Updating timestamp.
I0907 04:58:19.149496       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-00pr9t-md-0-pw99v"
I0907 04:58:19.150142       1 taint_manager.go:435] "Noticed node update" node={nodeName:capz-00pr9t-md-0-pw99v}
I0907 04:58:19.150477       1 taint_manager.go:440] "Updating known taints on node" node="capz-00pr9t-md-0-pw99v" taints=[]
I0907 04:58:19.150546       1 taint_manager.go:461] "All taints were removed from the node. Cancelling all evictions..." node="capz-00pr9t-md-0-pw99v"
I0907 04:58:19.150718       1 node_lifecycle_controller.go:893] Node capz-00pr9t-md-0-pw99v is healthy again, removing all taints
I0907 04:58:19.150841       1 node_lifecycle_controller.go:1039] ReadyCondition for Node capz-00pr9t-md-0-64h8r transitioned from &NodeCondition{Type:Ready,Status:False,LastHeartbeatTime:2022-09-07 04:58:05 +0000 UTC,LastTransitionTime:2022-09-07 04:57:55 +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-07 04:58:15 +0000 UTC,LastTransitionTime:2022-09-07 04:58:15 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status. AppArmor enabled,}
I0907 04:58:19.150994       1 node_lifecycle_controller.go:1047] Node capz-00pr9t-md-0-64h8r ReadyCondition updated. Updating timestamp.
I0907 04:58:19.190663       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-00pr9t-md-0-64h8r"
I0907 04:58:19.191093       1 node_lifecycle_controller.go:893] Node capz-00pr9t-md-0-64h8r is healthy again, removing all taints
I0907 04:58:19.191321       1 node_lifecycle_controller.go:1214] Controller detected that zone westus3::0 is now in state Normal.
I0907 04:58:19.191187       1 taint_manager.go:435] "Noticed node update" node={nodeName:capz-00pr9t-md-0-64h8r}
I0907 04:58:19.191549       1 taint_manager.go:440] "Updating known taints on node" node="capz-00pr9t-md-0-64h8r" taints=[]
... skipping 1545 lines ...
I0907 05:04:26.920860       1 deployment_util.go:808] Deployment "azurefile-volume-tester-k5n5m" timed out (false) [last progress check: 2022-09-07 05:04:26.910063541 +0000 UTC m=+506.099840014 - now: 2022-09-07 05:04:26.920852548 +0000 UTC m=+506.110628921]
I0907 05:04:26.921237       1 deployment_controller.go:176] "Updating deployment" deployment="azurefile-5356/azurefile-volume-tester-k5n5m"
I0907 05:04:26.922867       1 replica_set.go:649] Finished syncing ReplicaSet "azurefile-5356/azurefile-volume-tester-k5n5m-794d4bbb9c" (12.261708ms)
I0907 05:04:26.923049       1 controller_utils.go:189] Controller expectations fulfilled &controller.ControlleeExpectations{add:0, del:0, key:"azurefile-5356/azurefile-volume-tester-k5n5m-794d4bbb9c", timestamp:time.Time{wall:0xc0be2776b64748bd, ext:506100417814, loc:(*time.Location)(0x731ea80)}}
I0907 05:04:26.923235       1 replica_set_utils.go:59] Updating status for : azurefile-5356/azurefile-volume-tester-k5n5m-794d4bbb9c, replicas 0->1 (need 1), fullyLabeledReplicas 0->1, readyReplicas 0->0, availableReplicas 0->0, sequence No: 0->1
I0907 05:04:26.929568       1 deployment_controller.go:578] "Finished syncing deployment" deployment="azurefile-5356/azurefile-volume-tester-k5n5m" duration="23.534115ms"
I0907 05:04:26.929730       1 deployment_controller.go:490] "Error syncing deployment" deployment="azurefile-5356/azurefile-volume-tester-k5n5m" err="Operation cannot be fulfilled on deployments.apps \"azurefile-volume-tester-k5n5m\": the object has been modified; please apply your changes to the latest version and try again"
I0907 05:04:26.929888       1 deployment_controller.go:576] "Started syncing deployment" deployment="azurefile-5356/azurefile-volume-tester-k5n5m" startTime="2022-09-07 05:04:26.929865153 +0000 UTC m=+506.119641626"
I0907 05:04:26.930447       1 deployment_util.go:808] Deployment "azurefile-volume-tester-k5n5m" timed out (false) [last progress check: 2022-09-07 05:04:26 +0000 UTC - now: 2022-09-07 05:04:26.930440253 +0000 UTC m=+506.120216626]
I0907 05:04:26.931514       1 taint_manager.go:400] "Noticed pod update" pod="azurefile-5356/azurefile-volume-tester-k5n5m-794d4bbb9c-p56cs"
I0907 05:04:26.931681       1 deployment_controller.go:281] "ReplicaSet updated" replicaSet="azurefile-5356/azurefile-volume-tester-k5n5m-794d4bbb9c"
I0907 05:04:26.931742       1 disruption.go:427] updatePod called on pod "azurefile-volume-tester-k5n5m-794d4bbb9c-p56cs"
I0907 05:04:26.935148       1 disruption.go:490] No PodDisruptionBudgets found for pod azurefile-volume-tester-k5n5m-794d4bbb9c-p56cs, PodDisruptionBudget controller will avoid syncing.
... skipping 53 lines ...
I0907 05:04:28.791449       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-2790, name azurefile-volume-tester-8hgd5.17127b8cd496a427, uid 7a0a228d-6b59-4b5f-95c1-45a9545b998d, event type delete
I0907 05:04:28.794526       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-2790, name azurefile-volume-tester-8hgd5.17127b8cd6c69fdc, uid 18680d5c-76de-4532-a6a6-e19e5d7d6b42, event type delete
I0907 05:04:28.796929       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-2790, name azurefile-volume-tester-8hgd5.17127b8cdd4ed93c, uid 3c2d269d-c37b-421e-b372-d8111880a869, event type delete
I0907 05:04:28.803201       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-2790, name pvc-jgffb.17127b8173fad7a6, uid e11a5df7-98c4-4dc8-9965-dcdb55dd75ed, event type delete
I0907 05:04:28.821268       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-2790, name azure-storage-account-f6eb376e26cc543efbb8754-secret, uid eb62a00f-26bb-4597-a74c-c49007359318, event type delete
I0907 05:04:28.823560       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-2790, name default-token-k772g, uid 4b46d905-49b8-4a96-8368-5dd6b93bda32, event type delete
E0907 05:04:28.834209       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-2790/default: secrets "default-token-4m2hm" is forbidden: unable to create new content in namespace azurefile-2790 because it is being terminated
I0907 05:04:28.866569       1 tokens_controller.go:252] syncServiceAccount(azurefile-2790/default), service account deleted, removing tokens
I0907 05:04:28.866606       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-2790" (2.6µs)
I0907 05:04:28.866629       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-2790, name default, uid 24b83e0d-9be5-4585-a639-3fc570219c64, event type delete
I0907 05:04:28.886702       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-2790, name kube-root-ca.crt, uid 39b6a9c7-3c31-4c6e-9ed7-2a51b88cc83c, event type delete
I0907 05:04:28.889063       1 publisher.go:181] Finished syncing namespace "azurefile-2790" (2.212901ms)
I0907 05:04:28.895526       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-2790" (1.7µs)
... skipping 772 lines ...
I0907 05:06:23.361279       1 pvc_protection_controller.go:159] "Finished processing PVC" PVC="azurefile-4538/pvc-8rpr6" duration="5.4µs"
I0907 05:06:23.361306       1 disruption.go:427] updatePod called on pod "azurefile-volume-tester-lcj9s"
I0907 05:06:23.361372       1 disruption.go:490] No PodDisruptionBudgets found for pod azurefile-volume-tester-lcj9s, PodDisruptionBudget controller will avoid syncing.
I0907 05:06:23.361384       1 disruption.go:430] No matching pdb for pod "azurefile-volume-tester-lcj9s"
I0907 05:06:23.752740       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-1957
I0907 05:06:23.799195       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-1957, name default-token-jjmjp, uid acc6b0ff-c28d-43af-b06c-04732fb5d447, event type delete
E0907 05:06:23.820512       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-1957/default: secrets "default-token-cszbv" is forbidden: unable to create new content in namespace azurefile-1957 because it is being terminated
I0907 05:06:23.879265       1 tokens_controller.go:252] syncServiceAccount(azurefile-1957/default), service account deleted, removing tokens
I0907 05:06:23.879505       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1957" (2.7µs)
I0907 05:06:23.879547       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-1957, name default, uid 540ddfb7-5bf3-4f58-9188-2505d11c26f2, event type delete
I0907 05:06:23.914781       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-1957, name kube-root-ca.crt, uid f360a3a4-00e6-4bca-a25a-411551e548dc, event type delete
I0907 05:06:23.917665       1 publisher.go:181] Finished syncing namespace "azurefile-1957" (2.612601ms)
I0907 05:06:23.943784       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-1957, estimate: 0, errors: <nil>
... skipping 203 lines ...
I0907 05:06:55.453166       1 pv_controller.go:1753] scheduleOperation[delete-pvc-65f83c90-0dae-496e-92a7-dd1095833d96[870c64ac-8c02-4e61-9c77-6dc057793bfb]]
I0907 05:06:55.453194       1 pv_controller.go:1232] deleteVolumeOperation [pvc-65f83c90-0dae-496e-92a7-dd1095833d96] started
I0907 05:06:55.455433       1 pv_controller_base.go:235] volume "pvc-65f83c90-0dae-496e-92a7-dd1095833d96" deleted
I0907 05:06:55.455662       1 pv_protection_controller.go:183] Removed protection finalizer from PV pvc-65f83c90-0dae-496e-92a7-dd1095833d96
I0907 05:06:55.455807       1 pv_protection_controller.go:128] Finished processing PV pvc-65f83c90-0dae-496e-92a7-dd1095833d96 (4.503102ms)
I0907 05:06:55.455732       1 pv_controller_base.go:505] deletion of claim "azurefile-4538/pvc-8rpr6" was already processed
I0907 05:06:55.456669       1 pv_controller.go:1239] error reading persistent volume "pvc-65f83c90-0dae-496e-92a7-dd1095833d96": persistentvolumes "pvc-65f83c90-0dae-496e-92a7-dd1095833d96" not found
I0907 05:06:56.495289       1 attach_detach_controller.go:673] processVolumesInUse for node "capz-00pr9t-md-0-pw99v"
I0907 05:06:58.611088       1 httplog.go:94] "HTTP" verb="GET" URI="/healthz" latency="75.6µs" userAgent="kube-probe/1.21+" srcIP="127.0.0.1:44794" resp=200
I0907 05:06:59.075583       1 reflector.go:381] k8s.io/client-go/informers/factory.go:134: forcing resync
I0907 05:06:59.237453       1 pv_controller_base.go:528] resyncing PV controller
I0907 05:06:59.267859       1 node_lifecycle_controller.go:1047] Node capz-00pr9t-md-0-pw99v ReadyCondition updated. Updating timestamp.
I0907 05:07:00.707171       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-4538" (3.2µs)
... skipping 21 lines ...
I0907 05:07:05.760832       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-4538, name azurefile-volume-tester-lcj9s.17127baa06e5b1ac, uid 27f8a6b7-21a1-4887-9cdf-a910bcdd2b64, event type delete
I0907 05:07:05.763587       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-4538, name azurefile-volume-tester-lcj9s.17127baa0cdaa99a, uid b9daca93-e351-4177-8c30-d33452131804, event type delete
I0907 05:07:05.766872       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-4538, name pvc-8rpr6.17127ba9774d8618, uid 23ccec3a-cff5-40be-acdf-452a9fdd6f09, event type delete
I0907 05:07:05.773025       1 resource_quota_monitor.go:355] QuotaMonitor process object: events.k8s.io/v1, Resource=events, namespace azurefile-4538, name pvc-8rpr6.17127baa73621bc1, uid 8c154e4a-01ef-4240-a34b-7799805f047b, event type delete
I0907 05:07:05.790281       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-4538, name azure-storage-account-f81c9d868cd9a4ba9992815-secret, uid 0d2ff40e-82ee-40b4-a1f8-85e006287286, event type delete
I0907 05:07:05.793467       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-4538, name default-token-nm7zh, uid de835650-4669-4849-906c-2873ba1e2922, event type delete
E0907 05:07:05.806058       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-4538/default: secrets "default-token-z5f68" is forbidden: unable to create new content in namespace azurefile-4538 because it is being terminated
I0907 05:07:05.815719       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-4538, name kube-root-ca.crt, uid 79f43679-a654-4c4d-8e55-11aa3dbad8bc, event type delete
I0907 05:07:05.817182       1 publisher.go:181] Finished syncing namespace "azurefile-4538" (1.319301ms)
I0907 05:07:05.824495       1 tokens_controller.go:252] syncServiceAccount(azurefile-4538/default), service account deleted, removing tokens
I0907 05:07:05.824702       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-4538" (2.2µs)
I0907 05:07:05.824867       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-4538, name default, uid 0a281f88-91a3-4b18-b235-2f37c7f4d592, event type delete
I0907 05:07:05.851900       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-4538" (2µs)
... skipping 29 lines ...
I0907 05:07:07.755059       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-7726" (3.894202ms)
I0907 05:07:07.756008       1 publisher.go:181] Finished syncing namespace "azurefile-7726" (4.351003ms)
I0907 05:07:08.324798       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-7996
I0907 05:07:08.338545       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-7996, name kube-root-ca.crt, uid 0083a9ab-5649-42fc-9ee2-a8ed99deb476, event type delete
I0907 05:07:08.341186       1 publisher.go:181] Finished syncing namespace "azurefile-7996" (2.597601ms)
I0907 05:07:08.370219       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-7996, name default-token-7gjcd, uid 76e1d5c6-6d3c-48af-8e1f-6ac94743d2a3, event type delete
E0907 05:07:08.381528       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-7996/default: secrets "default-token-tzltp" is forbidden: unable to create new content in namespace azurefile-7996 because it is being terminated
I0907 05:07:08.463851       1 tokens_controller.go:252] syncServiceAccount(azurefile-7996/default), service account deleted, removing tokens
I0907 05:07:08.464108       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-7996" (2.6µs)
I0907 05:07:08.464214       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-7996, name default, uid 183de2d1-bef8-40a1-9459-fe899452b597, event type delete
I0907 05:07:08.519385       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-7996" (3µs)
I0907 05:07:08.520206       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-7996, estimate: 0, errors: <nil>
I0907 05:07:08.531141       1 namespace_controller.go:180] Finished syncing namespace "azurefile-7996" (209.908232ms)
... skipping 3 lines ...
I0907 05:07:08.666453       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-3086" (19.252212ms)
I0907 05:07:09.207108       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-59
I0907 05:07:09.228991       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-59, name kube-root-ca.crt, uid 37a39fb9-bf64-4ca3-aaba-25ffe0aacfbd, event type delete
I0907 05:07:09.230982       1 publisher.go:181] Finished syncing namespace "azurefile-59" (1.909302ms)
I0907 05:07:09.269621       1 node_lifecycle_controller.go:1047] Node capz-00pr9t-md-0-64h8r ReadyCondition updated. Updating timestamp.
I0907 05:07:09.271689       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-59, name default-token-rdhs9, uid b0457b08-565b-4230-87a6-494219488a6d, event type delete
E0907 05:07:09.283290       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-59/default: secrets "default-token-xbsjp" is forbidden: unable to create new content in namespace azurefile-59 because it is being terminated
I0907 05:07:09.322152       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-59" (3µs)
I0907 05:07:09.322157       1 tokens_controller.go:252] syncServiceAccount(azurefile-59/default), service account deleted, removing tokens
I0907 05:07:09.322189       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-59, name default, uid f23cad58-7407-4872-91e0-05a2eef8472c, event type delete
I0907 05:07:09.349086       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-59" (2.2µs)
I0907 05:07:09.349292       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-59, estimate: 0, errors: <nil>
I0907 05:07:09.356646       1 namespace_controller.go:180] Finished syncing namespace "azurefile-59" (152.610495ms)
... skipping 14 lines ...
I0907 05:07:10.397639       1 publisher.go:181] Finished syncing namespace "azurefile-4547" (5.461003ms)
I0907 05:07:10.400665       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-4547" (8.738105ms)
I0907 05:07:10.852879       1 namespace_controller.go:185] Namespace has been deleted azurefile-4538
I0907 05:07:10.852898       1 namespace_controller.go:180] Finished syncing namespace "azurefile-4538" (42.6µs)
I0907 05:07:10.954782       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-1598
I0907 05:07:11.005719       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-1598, name default-token-mpqd2, uid 18a90262-c00f-44d9-b3c6-8c4254033499, event type delete
E0907 05:07:11.018050       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-1598/default: secrets "default-token-kzn29" is forbidden: unable to create new content in namespace azurefile-1598 because it is being terminated
I0907 05:07:11.023381       1 tokens_controller.go:252] syncServiceAccount(azurefile-1598/default), service account deleted, removing tokens
I0907 05:07:11.023421       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1598" (1.7µs)
I0907 05:07:11.023445       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-1598, name default, uid 9add5e73-4c9a-467a-a508-5d147084490d, event type delete
I0907 05:07:11.060652       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-1598, name kube-root-ca.crt, uid a374470f-85e8-4bfa-a010-32e29724224d, event type delete
I0907 05:07:11.063191       1 publisher.go:181] Finished syncing namespace "azurefile-1598" (2.499602ms)
I0907 05:07:11.084562       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-1598, estimate: 0, errors: <nil>
... skipping 20 lines ...
I0907 05:07:12.586141       1 namespace_controller.go:185] Namespace has been deleted azurefile-4376
I0907 05:07:12.586162       1 namespace_controller.go:180] Finished syncing namespace "azurefile-4376" (39.1µs)
I0907 05:07:12.694551       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-8582
I0907 05:07:12.744064       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-8582, name kube-root-ca.crt, uid 608dda2f-0fe8-4ac7-9c76-92d34fab8aad, event type delete
I0907 05:07:12.747292       1 publisher.go:181] Finished syncing namespace "azurefile-8582" (3.051102ms)
I0907 05:07:12.778036       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-8582, name default-token-r8x47, uid 6fc2368d-02bf-42de-8786-9e8b2a19eeaf, event type delete
E0907 05:07:12.787496       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-8582/default: secrets "default-token-x76l5" is forbidden: unable to create new content in namespace azurefile-8582 because it is being terminated
I0907 05:07:12.795870       1 tokens_controller.go:252] syncServiceAccount(azurefile-8582/default), service account deleted, removing tokens
I0907 05:07:12.796122       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-8582" (2.9µs)
I0907 05:07:12.796141       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-8582, name default, uid a03c2047-23f2-4c43-90f7-967058360301, event type delete
I0907 05:07:12.822784       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-8582, estimate: 0, errors: <nil>
I0907 05:07:12.823645       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-8582" (1.3µs)
I0907 05:07:12.831249       1 namespace_controller.go:180] Finished syncing namespace "azurefile-8582" (139.825173ms)
I0907 05:07:12.852506       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-9183" (1.5µs)
I0907 05:07:12.918132       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-7578" (3.472302ms)
I0907 05:07:12.919638       1 publisher.go:181] Finished syncing namespace "azurefile-7578" (4.803703ms)
I0907 05:07:13.519870       1 namespace_controller.go:185] Namespace has been deleted azurefile-7996
I0907 05:07:13.519888       1 namespace_controller.go:180] Finished syncing namespace "azurefile-7996" (33.8µs)
I0907 05:07:13.588622       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-7726
I0907 05:07:13.609356       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-7726, name default-token-fcwp6, uid d243b448-c8a5-4997-bc28-d1732f999d12, event type delete
E0907 05:07:13.623406       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-7726/default: secrets "default-token-7pmmc" is forbidden: unable to create new content in namespace azurefile-7726 because it is being terminated
I0907 05:07:13.687057       1 tokens_controller.go:252] syncServiceAccount(azurefile-7726/default), service account deleted, removing tokens
I0907 05:07:13.687142       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-7726" (2.4µs)
I0907 05:07:13.687252       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-7726, name default, uid 839fad13-2001-489f-8ddb-3ee596534fdc, event type delete
I0907 05:07:13.691431       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-7578" (1.5µs)
I0907 05:07:13.705452       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-7726, name kube-root-ca.crt, uid f0c9a4e8-2bfe-43c2-94e0-e3a48887f585, event type delete
I0907 05:07:13.707257       1 publisher.go:181] Finished syncing namespace "azurefile-7726" (1.6285ms)
... skipping 8 lines ...
I0907 05:07:14.134417       1 gc_controller.go:224] GC'ing unscheduled pods which are terminating.
I0907 05:07:14.238383       1 pv_controller_base.go:528] resyncing PV controller
I0907 05:07:14.349559       1 namespace_controller.go:185] Namespace has been deleted azurefile-59
I0907 05:07:14.349576       1 namespace_controller.go:180] Finished syncing namespace "azurefile-59" (34.3µs)
I0907 05:07:14.453998       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-3086
I0907 05:07:14.486098       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-3086, name default-token-4q2km, uid 78996989-aa1d-47f2-94e0-58f6f6d851f1, event type delete
E0907 05:07:14.501908       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-3086/default: secrets "default-token-k772f" is forbidden: unable to create new content in namespace azurefile-3086 because it is being terminated
I0907 05:07:14.504999       1 tokens_controller.go:252] syncServiceAccount(azurefile-3086/default), service account deleted, removing tokens
I0907 05:07:14.505145       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-3086" (2.8µs)
I0907 05:07:14.505275       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-3086, name default, uid 0a12d25b-b0e4-4941-aad2-548bca07c188, event type delete
I0907 05:07:14.530596       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-3086, name kube-root-ca.crt, uid e03ebeb7-4591-436b-a55d-162fa8c8ce0f, event type delete
I0907 05:07:14.533642       1 publisher.go:181] Finished syncing namespace "azurefile-3086" (2.986502ms)
I0907 05:07:14.577655       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1968" (2.7µs)
... skipping 22 lines ...
I0907 05:07:16.084897       1 namespace_controller.go:180] Finished syncing namespace "azurefile-1598" (36.4µs)
I0907 05:07:16.186214       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-4547
I0907 05:07:16.222023       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-4547, name kube-root-ca.crt, uid a27f9d72-dfa5-4480-93d1-c32d28355775, event type delete
I0907 05:07:16.224783       1 publisher.go:181] Finished syncing namespace "azurefile-4547" (2.648402ms)
I0907 05:07:16.293469       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-4547, name default-token-hkztl, uid 0dc0b79d-7d09-48e9-991e-8f01663175d0, event type delete
I0907 05:07:16.301753       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1359" (2µs)
E0907 05:07:16.310680       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-4547/default: secrets "default-token-vch7q" is forbidden: unable to create new content in namespace azurefile-4547 because it is being terminated
I0907 05:07:16.322870       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-4547" (1.6µs)
I0907 05:07:16.322915       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-4547, name default, uid 2cea4270-c280-497d-98bb-643abc2c63ff, event type delete
I0907 05:07:16.322964       1 tokens_controller.go:252] syncServiceAccount(azurefile-4547/default), service account deleted, removing tokens
I0907 05:07:16.334384       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-4547" (1.5µs)
I0907 05:07:16.334652       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-4547, estimate: 0, errors: <nil>
I0907 05:07:16.342272       1 namespace_controller.go:180] Finished syncing namespace "azurefile-4547" (159.321582ms)
I0907 05:07:16.364829       1 publisher.go:181] Finished syncing namespace "azurefile-565" (3.336602ms)
I0907 05:07:16.369160       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-565" (7.708704ms)
I0907 05:07:16.925610       1 namespace_controller.go:185] Namespace has been deleted azurefile-3410
I0907 05:07:16.925631       1 namespace_controller.go:180] Finished syncing namespace "azurefile-3410" (47.3µs)
I0907 05:07:17.013232       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-7051
I0907 05:07:17.056693       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-7051, name default-token-djk7s, uid 942aa453-2c40-45a2-9bbc-eb472433eb52, event type delete
E0907 05:07:17.069495       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-7051/default: secrets "default-token-dmwv2" is forbidden: unable to create new content in namespace azurefile-7051 because it is being terminated
I0907 05:07:17.104832       1 tokens_controller.go:252] syncServiceAccount(azurefile-7051/default), service account deleted, removing tokens
I0907 05:07:17.104873       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-7051" (1.7µs)
I0907 05:07:17.104896       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-7051, name default, uid 0e0bb38c-412a-455b-8d5c-3f8c9b8cdcdd, event type delete
I0907 05:07:17.116147       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-7051, name kube-root-ca.crt, uid 6768beb1-b5b6-484a-aafa-0089d3e93f04, event type delete
I0907 05:07:17.118703       1 publisher.go:181] Finished syncing namespace "azurefile-7051" (2.460301ms)
I0907 05:07:17.144673       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-565" (2.4µs)
... skipping 22 lines ...
I0907 05:07:18.694376       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-7578
I0907 05:07:18.745630       1 namespace_controller.go:185] Namespace has been deleted azurefile-7726
I0907 05:07:18.745827       1 namespace_controller.go:180] Finished syncing namespace "azurefile-7726" (211.5µs)
I0907 05:07:18.746855       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-7578, name default-token-kjdrq, uid e73dcda3-c037-4e5b-b145-0ed91d196cdd, event type delete
I0907 05:07:18.759778       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-7578, name kube-root-ca.crt, uid 828fee63-9ff3-4b9b-9d32-736ad4585d95, event type delete
I0907 05:07:18.762810       1 publisher.go:181] Finished syncing namespace "azurefile-7578" (2.859101ms)
E0907 05:07:18.763927       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-7578/default: secrets "default-token-5s665" is forbidden: unable to create new content in namespace azurefile-7578 because it is being terminated
I0907 05:07:18.793952       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-7886" (1.5µs)
I0907 05:07:18.813872       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-7578" (1.4µs)
I0907 05:07:18.814086       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-7578, name default, uid fe1baa89-f58b-4264-98ec-9bc3688ad6ad, event type delete
I0907 05:07:18.814271       1 tokens_controller.go:252] syncServiceAccount(azurefile-7578/default), service account deleted, removing tokens
I0907 05:07:18.822963       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-7578, estimate: 0, errors: <nil>
I0907 05:07:18.824211       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-7578" (1.5µs)
... skipping 3 lines ...
I0907 05:07:19.271434       1 node_lifecycle_controller.go:1047] Node capz-00pr9t-control-plane-82gfg ReadyCondition updated. Updating timestamp.
I0907 05:07:19.584035       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-1968
I0907 05:07:19.593584       1 namespace_controller.go:185] Namespace has been deleted azurefile-3086
I0907 05:07:19.593775       1 namespace_controller.go:180] Finished syncing namespace "azurefile-3086" (206µs)
I0907 05:07:19.611162       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-1968, name default-token-5s5hq, uid 85fc0722-4f20-429e-963e-bff6992702a9, event type delete
I0907 05:07:19.629713       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-6611" (1.7µs)
E0907 05:07:19.632232       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-1968/default: secrets "default-token-w9gmw" is forbidden: unable to create new content in namespace azurefile-1968 because it is being terminated
I0907 05:07:19.695784       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-6157" (3.445902ms)
I0907 05:07:19.698352       1 publisher.go:181] Finished syncing namespace "azurefile-6157" (5.762003ms)
I0907 05:07:19.722494       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-1968, name kube-root-ca.crt, uid 8c2d878f-bec7-4573-ba3d-012be77d0b2a, event type delete
I0907 05:07:19.724114       1 publisher.go:181] Finished syncing namespace "azurefile-1968" (1.4978ms)
I0907 05:07:19.743982       1 tokens_controller.go:252] syncServiceAccount(azurefile-1968/default), service account deleted, removing tokens
I0907 05:07:19.744165       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1968" (2µs)
... skipping 3 lines ...
I0907 05:07:19.777635       1 namespace_controller.go:180] Finished syncing namespace "azurefile-1968" (199.798111ms)
I0907 05:07:20.463345       1 namespace_controller.go:185] Namespace has been deleted azurefile-1387
I0907 05:07:20.463366       1 namespace_controller.go:180] Finished syncing namespace "azurefile-1387" (44.2µs)
I0907 05:07:20.481812       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-4657
I0907 05:07:20.502892       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-6157" (2.6µs)
I0907 05:07:20.526971       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-4657, name default-token-7ww6d, uid acdc0b9f-5988-48d5-bf61-bb9c718f3695, event type delete
E0907 05:07:20.537981       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-4657/default: secrets "default-token-bjcxb" is forbidden: unable to create new content in namespace azurefile-4657 because it is being terminated
I0907 05:07:20.546608       1 tokens_controller.go:252] syncServiceAccount(azurefile-4657/default), service account deleted, removing tokens
I0907 05:07:20.546858       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-4657" (2.1µs)
I0907 05:07:20.547018       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-4657, name default, uid be71d077-2414-4c6c-adeb-571da02c62c9, event type delete
I0907 05:07:20.568330       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1393" (3.690502ms)
I0907 05:07:20.572508       1 publisher.go:181] Finished syncing namespace "azurefile-1393" (7.688605ms)
I0907 05:07:20.576657       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-4657, name kube-root-ca.crt, uid c7bbcf22-23bd-44b2-afd7-e40c7c07990f, event type delete
... skipping 6 lines ...
I0907 05:07:21.336975       1 namespace_controller.go:185] Namespace has been deleted azurefile-4547
I0907 05:07:21.337804       1 namespace_controller.go:180] Finished syncing namespace "azurefile-4547" (823.3µs)
I0907 05:07:21.352020       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-1359, name kube-root-ca.crt, uid b3b19ffd-9733-45aa-ae8b-5a009805c7c8, event type delete
I0907 05:07:21.353400       1 publisher.go:181] Finished syncing namespace "azurefile-1359" (1.341801ms)
I0907 05:07:21.371678       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1393" (1.9µs)
I0907 05:07:21.412682       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-1359, name default-token-9lv9p, uid b12dd853-cc29-490c-a2f8-77c658ea94d1, event type delete
E0907 05:07:21.435834       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-1359/default: secrets "default-token-pzlrx" is forbidden: unable to create new content in namespace azurefile-1359 because it is being terminated
I0907 05:07:21.440092       1 tokens_controller.go:252] syncServiceAccount(azurefile-1359/default), service account deleted, removing tokens
I0907 05:07:21.440141       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-1359, name default, uid d842fbd3-fbd0-47a9-9d87-7e761e5caf22, event type delete
I0907 05:07:21.444880       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-3922" (7.360904ms)
I0907 05:07:21.444908       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1359" (2µs)
I0907 05:07:21.449473       1 publisher.go:181] Finished syncing namespace "azurefile-3922" (11.612806ms)
I0907 05:07:21.474593       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-1359" (1.6µs)
... skipping 13 lines ...
I0907 05:07:22.279494       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-565" (1.4µs)
I0907 05:07:22.286888       1 namespace_controller.go:180] Finished syncing namespace "azurefile-565" (141.918879ms)
I0907 05:07:22.969979       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-8666
I0907 05:07:23.004422       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-8666, name default-token-vmmx9, uid 9c955105-829e-4c39-a621-edbdb2aa8697, event type delete
I0907 05:07:23.006467       1 namespace_controller.go:185] Namespace has been deleted azurefile-9183
I0907 05:07:23.006713       1 namespace_controller.go:180] Finished syncing namespace "azurefile-9183" (256.2µs)
E0907 05:07:23.018430       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-8666/default: secrets "default-token-86xzs" is forbidden: unable to create new content in namespace azurefile-8666 because it is being terminated
I0907 05:07:23.081159       1 tokens_controller.go:252] syncServiceAccount(azurefile-8666/default), service account deleted, removing tokens
I0907 05:07:23.081385       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-8666" (2.2µs)
I0907 05:07:23.081520       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-8666, name default, uid c1df2f39-bfa3-48d5-8678-6eb9aacf1e50, event type delete
I0907 05:07:23.087585       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-8666, name kube-root-ca.crt, uid 194cf75f-d9ca-4351-95a2-ae8ce2e94aa8, event type delete
I0907 05:07:23.090304       1 publisher.go:181] Finished syncing namespace "azurefile-8666" (2.566202ms)
I0907 05:07:23.108857       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-8666" (1.5µs)
... skipping 2 lines ...
I0907 05:07:23.798927       1 namespaced_resources_deleter.go:500] namespace controller - deleteAllContent - namespace: azurefile-7886
I0907 05:07:23.825015       1 namespace_controller.go:185] Namespace has been deleted azurefile-7578
I0907 05:07:23.825222       1 namespace_controller.go:180] Finished syncing namespace "azurefile-7578" (220.3µs)
I0907 05:07:23.856564       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=configmaps, namespace azurefile-7886, name kube-root-ca.crt, uid e9d0eb2e-93ee-4476-91f2-0d9142523e8a, event type delete
I0907 05:07:23.858688       1 publisher.go:181] Finished syncing namespace "azurefile-7886" (2.078401ms)
I0907 05:07:23.873383       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=secrets, namespace azurefile-7886, name default-token-2lr4q, uid e74f7a56-4eba-4e2b-978a-a470df9ba0ce, event type delete
E0907 05:07:23.884768       1 tokens_controller.go:262] error synchronizing serviceaccount azurefile-7886/default: secrets "default-token-fq6nf" is forbidden: unable to create new content in namespace azurefile-7886 because it is being terminated
I0907 05:07:23.913641       1 tokens_controller.go:252] syncServiceAccount(azurefile-7886/default), service account deleted, removing tokens
I0907 05:07:23.914463       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-7886" (3.6µs)
I0907 05:07:23.914588       1 resource_quota_monitor.go:355] QuotaMonitor process object: /v1, Resource=serviceaccounts, namespace azurefile-7886, name default, uid 84c22b4b-46f9-414b-a525-74f5748bb055, event type delete
I0907 05:07:23.935850       1 namespaced_resources_deleter.go:554] namespace controller - deleteAllContent - namespace: azurefile-7886, estimate: 0, errors: <nil>
I0907 05:07:23.936766       1 serviceaccounts_controller.go:188] Finished syncing namespace "azurefile-7886" (1.9µs)
I0907 05:07:23.944220       1 namespace_controller.go:180] Finished syncing namespace "azurefile-7886" (149.648182ms)
2022/09/07 05:07:24 ===================================================

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

Ran 6 of 34 Specs in 389.651 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-57wvr, container manager
STEP: Dumping workload cluster default/capz-00pr9t logs
Sep  7 05:08:58.373: INFO: Collecting logs for Linux node capz-00pr9t-control-plane-82gfg in cluster capz-00pr9t in namespace default

Sep  7 05:09:58.377: INFO: Collecting boot logs for AzureMachine capz-00pr9t-control-plane-82gfg

Failed to get logs for machine capz-00pr9t-control-plane-8sjjt, cluster default/capz-00pr9t: open /etc/azure-ssh/azure-ssh: no such file or directory
Sep  7 05:09:59.486: INFO: Collecting logs for Linux node capz-00pr9t-md-0-pw99v in cluster capz-00pr9t in namespace default

Sep  7 05:10:59.489: INFO: Collecting boot logs for AzureMachine capz-00pr9t-md-0-pw99v

Failed to get logs for machine capz-00pr9t-md-0-6bbb4c7975-cmp2w, cluster default/capz-00pr9t: open /etc/azure-ssh/azure-ssh: no such file or directory
Sep  7 05:10:59.928: INFO: Collecting logs for Linux node capz-00pr9t-md-0-64h8r in cluster capz-00pr9t in namespace default

Sep  7 05:11:59.930: INFO: Collecting boot logs for AzureMachine capz-00pr9t-md-0-64h8r

Failed to get logs for machine capz-00pr9t-md-0-6bbb4c7975-qfxcw, cluster default/capz-00pr9t: open /etc/azure-ssh/azure-ssh: no such file or directory
STEP: Dumping workload cluster default/capz-00pr9t kube-system pod logs
STEP: Collecting events for Pod kube-system/calico-node-gsxns
STEP: Fetching kube-system pod logs took 728.642026ms
STEP: Dumping workload cluster default/capz-00pr9t Azure activity log
STEP: Creating log watcher for controller kube-system/calico-kube-controllers-969cf87c4-29l85, container calico-kube-controllers
STEP: Collecting events for Pod kube-system/calico-kube-controllers-969cf87c4-29l85
... skipping 2 lines ...
STEP: Collecting events for Pod kube-system/kube-scheduler-capz-00pr9t-control-plane-82gfg
STEP: Collecting events for Pod kube-system/metrics-server-8c95fb79b-rdjwm
STEP: Collecting events for Pod kube-system/coredns-558bd4d5db-7lfj9
STEP: Creating log watcher for controller kube-system/calico-node-srnth, container calico-node
STEP: Collecting events for Pod kube-system/calico-node-srnth
STEP: Collecting events for Pod kube-system/kube-apiserver-capz-00pr9t-control-plane-82gfg
STEP: failed to find events of Pod "kube-apiserver-capz-00pr9t-control-plane-82gfg"
STEP: Creating log watcher for controller kube-system/calico-node-x9x7q, container calico-node
STEP: Collecting events for Pod kube-system/calico-node-x9x7q
STEP: Creating log watcher for controller kube-system/coredns-558bd4d5db-tf9hl, container coredns
STEP: Collecting events for Pod kube-system/etcd-capz-00pr9t-control-plane-82gfg
STEP: failed to find events of Pod "etcd-capz-00pr9t-control-plane-82gfg"
STEP: Creating log watcher for controller kube-system/coredns-558bd4d5db-7lfj9, container coredns
STEP: Creating log watcher for controller kube-system/kube-apiserver-capz-00pr9t-control-plane-82gfg, container kube-apiserver
STEP: Collecting events for Pod kube-system/kube-proxy-gfr9b
STEP: Creating log watcher for controller kube-system/kube-proxy-2qwhw, container kube-proxy
STEP: Collecting events for Pod kube-system/kube-proxy-2qwhw
STEP: Creating log watcher for controller kube-system/kube-controller-manager-capz-00pr9t-control-plane-82gfg, container kube-controller-manager
STEP: Collecting events for Pod kube-system/kube-controller-manager-capz-00pr9t-control-plane-82gfg
STEP: Collecting events for Pod kube-system/coredns-558bd4d5db-tf9hl
STEP: Creating log watcher for controller kube-system/kube-scheduler-capz-00pr9t-control-plane-82gfg, container kube-scheduler
STEP: Creating log watcher for controller kube-system/kube-proxy-zg6q4, container kube-proxy
STEP: Creating log watcher for controller kube-system/etcd-capz-00pr9t-control-plane-82gfg, container etcd
STEP: Collecting events for Pod kube-system/kube-proxy-zg6q4
STEP: Creating log watcher for controller kube-system/calico-node-gsxns, container calico-node
STEP: failed to find events of Pod "kube-scheduler-capz-00pr9t-control-plane-82gfg"
STEP: failed to find events of Pod "kube-controller-manager-capz-00pr9t-control-plane-82gfg"
STEP: Fetching activity logs took 2.357194664s
================ REDACTING LOGS ================
All sensitive variables are redacted
cluster.cluster.x-k8s.io "capz-00pr9t" 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 ...