W0919 17:53:59.367690       1 feature_gate.go:410] Setting GA feature gate MachineWaitForVolumeDetachConsiderVolumeAttachments=true. It will be removed in a future release.
I0919 17:53:59.367823       1 main.go:308] FLAG: --additional-sync-machine-annotations="[]"
I0919 17:53:59.367834       1 main.go:308] FLAG: --additional-sync-machine-labels="[]"
I0919 17:53:59.367840       1 main.go:308] FLAG: --cluster-concurrency="50"
I0919 17:53:59.367845       1 main.go:308] FLAG: --clustercache-client-burst="30"
I0919 17:53:59.367849       1 main.go:308] FLAG: --clustercache-client-qps="20"
I0919 17:53:59.367854       1 main.go:308] FLAG: --clustercache-concurrency="100"
I0919 17:53:59.367859       1 main.go:308] FLAG: --clusterclass-concurrency="10"
I0919 17:53:59.367863       1 main.go:308] FLAG: --clusterresourceset-concurrency="10"
I0919 17:53:59.367866       1 main.go:308] FLAG: --clustertopology-concurrency="50"
I0919 17:53:59.367870       1 main.go:308] FLAG: --contention-profiling="false"
I0919 17:53:59.367875       1 main.go:308] FLAG: --diagnostics-address=":8443"
I0919 17:53:59.367881       1 main.go:308] FLAG: --extensionconfig-concurrency="10"
I0919 17:53:59.367885       1 main.go:308] FLAG: --feature-gates="ClusterTopology=true,InPlaceUpdates=false,MachinePool=true,MachineSetPreflightChecks=true,MachineTaintPropagation=false,MachineWaitForVolumeDetachConsiderVolumeAttachments=true,PriorityQueue=true,ReconcilerRateLimiting=true,RuntimeSDK=false"
I0919 17:53:59.367903       1 main.go:308] FLAG: --health-addr=":9440"
I0919 17:53:59.367908       1 main.go:308] FLAG: --insecure-diagnostics="false"
I0919 17:53:59.367912       1 main.go:308] FLAG: --kube-api-burst="200"
I0919 17:53:59.367916       1 main.go:308] FLAG: --kube-api-qps="100"
I0919 17:53:59.367920       1 main.go:308] FLAG: --kubeconfig=""
I0919 17:53:59.367925       1 main.go:308] FLAG: --leader-elect="true"
I0919 17:53:59.367929       1 main.go:308] FLAG: --leader-elect-lease-duration="15s"
I0919 17:53:59.367934       1 main.go:308] FLAG: --leader-elect-renew-deadline="10s"
I0919 17:53:59.367938       1 main.go:308] FLAG: --leader-elect-retry-period="2s"
I0919 17:53:59.367942       1 main.go:308] FLAG: --log-flush-frequency="5s"
I0919 17:53:59.367946       1 main.go:308] FLAG: --log-json-info-buffer-size="0"
I0919 17:53:59.367954       1 main.go:308] FLAG: --log-json-split-stream="false"
I0919 17:53:59.367962       1 main.go:308] FLAG: --log-text-info-buffer-size="0"
I0919 17:53:59.367965       1 main.go:308] FLAG: --log-text-split-stream="false"
I0919 17:53:59.367968       1 main.go:308] FLAG: --logging-format="text"
I0919 17:53:59.367972       1 main.go:308] FLAG: --machine-concurrency="100"
I0919 17:53:59.367975       1 main.go:308] FLAG: --machinedeployment-concurrency="50"
I0919 17:53:59.367978       1 main.go:308] FLAG: --machinehealthcheck-concurrency="50"
I0919 17:53:59.367981       1 main.go:308] FLAG: --machinepool-concurrency="50"
I0919 17:53:59.367984       1 main.go:308] FLAG: --machineset-concurrency="50"
I0919 17:53:59.367987       1 main.go:308] FLAG: --machineset-preflight-checks="[All]"
I0919 17:53:59.367994       1 main.go:308] FLAG: --namespace=""
I0919 17:53:59.367997       1 main.go:308] FLAG: --profiler-address=""
I0919 17:53:59.368000       1 main.go:308] FLAG: --remote-conditions-grace-period="5m0s"
I0919 17:53:59.368004       1 main.go:308] FLAG: --remote-connection-grace-period="50s"
I0919 17:53:59.368007       1 main.go:308] FLAG: --runtime-extension-client-cert-file=""
I0919 17:53:59.368011       1 main.go:308] FLAG: --runtime-extension-client-key-file=""
I0919 17:53:59.368015       1 main.go:308] FLAG: --skip-crd-migration-phases="[]"
I0919 17:53:59.368020       1 main.go:308] FLAG: --sync-period="10m0s"
I0919 17:53:59.368024       1 main.go:308] FLAG: --tls-cipher-suites="[]"
I0919 17:53:59.368028       1 main.go:308] FLAG: --tls-min-version="VersionTLS12"
I0919 17:53:59.368032       1 main.go:308] FLAG: --v="2"
I0919 17:53:59.368046       1 main.go:308] FLAG: --vmodule=""
I0919 17:53:59.368051       1 main.go:308] FLAG: --watch-filter=""
I0919 17:53:59.368054       1 main.go:308] FLAG: --webhook-cert-dir="/tmp/k8s-webhook-server/serving-certs/"
I0919 17:53:59.368058       1 main.go:308] FLAG: --webhook-cert-name="tls.crt"
I0919 17:53:59.368061       1 main.go:308] FLAG: --webhook-key-name="tls.key"
I0919 17:53:59.368065       1 main.go:308] FLAG: --webhook-port="9443"
I0919 17:53:59.368096       1 main.go:315] "Version: v1.13.2 (git commit: ebd807c66351f3ad96ca99b68d2219305d794640)" logger="setup"
I0919 17:53:59.368399       1 envvar.go:172] "Feature gate default state" feature="ClientsPreferCBOR" enabled=false
I0919 17:53:59.368422       1 envvar.go:172] "Feature gate default state" feature="InOrderInformers" enabled=true
I0919 17:53:59.368429       1 envvar.go:172] "Feature gate default state" feature="InOrderInformersBatchProcess" enabled=true
I0919 17:53:59.368434       1 envvar.go:172] "Feature gate default state" feature="InformerResourceVersion" enabled=true
I0919 17:53:59.368439       1 envvar.go:172] "Feature gate default state" feature="WatchListClient" enabled=true
I0919 17:53:59.368444       1 envvar.go:172] "Feature gate default state" feature="ClientsAllowCBOR" enabled=false
I0919 17:53:59.395722       1 cluster_cache.go:310] "Found controller Pod metadata, the ClusterCache will try to access the cluster it is running on directly if possible" controller="clustercache"
I0919 17:53:59.398012       1 webhook.go:293] "Registering a validating webhook" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=ClusterClass" path="/validate-cluster-x-k8s-io-v1beta2-clusterclass"
I0919 17:53:59.398225       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-clusterclass"
I0919 17:53:59.398547       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/convert"
I0919 17:53:59.398567       1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=ClusterClass"
I0919 17:53:59.398727       1 webhook.go:246] "Registering a mutating webhook" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=Cluster" path="/mutate-cluster-x-k8s-io-v1beta2-cluster"
I0919 17:53:59.398862       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-cluster"
I0919 17:53:59.398949       1 webhook.go:293] "Registering a validating webhook" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=Cluster" path="/validate-cluster-x-k8s-io-v1beta2-cluster"
I0919 17:53:59.399069       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-cluster"
I0919 17:53:59.399126       1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=Cluster"
I0919 17:53:59.399174       1 webhook.go:246] "Registering a mutating webhook" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=Machine" path="/mutate-cluster-x-k8s-io-v1beta2-machine"
I0919 17:53:59.399254       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machine"
I0919 17:53:59.399296       1 webhook.go:293] "Registering a validating webhook" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=Machine" path="/validate-cluster-x-k8s-io-v1beta2-machine"
I0919 17:53:59.399394       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machine"
I0919 17:53:59.399460       1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=Machine"
I0919 17:53:59.399528       1 webhook.go:246] "Registering a mutating webhook" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineSet" path="/mutate-cluster-x-k8s-io-v1beta2-machineset"
I0919 17:53:59.399601       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machineset"
I0919 17:53:59.399644       1 webhook.go:293] "Registering a validating webhook" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineSet" path="/validate-cluster-x-k8s-io-v1beta2-machineset"
I0919 17:53:59.399716       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machineset"
I0919 17:53:59.399774       1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineSet"
I0919 17:53:59.399861       1 webhook.go:246] "Registering a mutating webhook" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineDeployment" path="/mutate-cluster-x-k8s-io-v1beta2-machinedeployment"
I0919 17:53:59.399969       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machinedeployment"
I0919 17:53:59.400014       1 webhook.go:293] "Registering a validating webhook" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineDeployment" path="/validate-cluster-x-k8s-io-v1beta2-machinedeployment"
I0919 17:53:59.400081       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinedeployment"
I0919 17:53:59.400138       1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineDeployment"
I0919 17:53:59.400180       1 webhook.go:293] "Registering a validating webhook" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineDrainRule" path="/validate-cluster-x-k8s-io-v1beta2-machinedrainrule"
I0919 17:53:59.400277       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinedrainrule"
I0919 17:53:59.400385       1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineDrainRule"
I0919 17:53:59.400506       1 webhook.go:246] "Registering a mutating webhook" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachinePool" path="/mutate-cluster-x-k8s-io-v1beta2-machinepool"
I0919 17:53:59.400650       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machinepool"
I0919 17:53:59.400725       1 webhook.go:293] "Registering a validating webhook" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachinePool" path="/validate-cluster-x-k8s-io-v1beta2-machinepool"
I0919 17:53:59.400836       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinepool"
I0919 17:53:59.400920       1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachinePool"
I0919 17:53:59.401012       1 webhook.go:246] "Registering a mutating webhook" logger="controller-runtime.builder" GVK="addons.cluster.x-k8s.io/v1beta2, Kind=ClusterResourceSet" path="/mutate-addons-cluster-x-k8s-io-v1beta2-clusterresourceset"
I0919 17:53:59.401110       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-addons-cluster-x-k8s-io-v1beta2-clusterresourceset"
I0919 17:53:59.401184       1 webhook.go:293] "Registering a validating webhook" logger="controller-runtime.builder" GVK="addons.cluster.x-k8s.io/v1beta2, Kind=ClusterResourceSet" path="/validate-addons-cluster-x-k8s-io-v1beta2-clusterresourceset"
I0919 17:53:59.401337       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-addons-cluster-x-k8s-io-v1beta2-clusterresourceset"
I0919 17:53:59.401419       1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="addons.cluster.x-k8s.io/v1beta2, Kind=ClusterResourceSet"
I0919 17:53:59.401480       1 webhook.go:293] "Registering a validating webhook" logger="controller-runtime.builder" GVK="addons.cluster.x-k8s.io/v1beta2, Kind=ClusterResourceSetBinding" path="/validate-addons-cluster-x-k8s-io-v1beta2-clusterresourcesetbinding"
I0919 17:53:59.401562       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-addons-cluster-x-k8s-io-v1beta2-clusterresourcesetbinding"
I0919 17:53:59.401620       1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="addons.cluster.x-k8s.io/v1beta2, Kind=ClusterResourceSetBinding"
I0919 17:53:59.401662       1 webhook.go:246] "Registering a mutating webhook" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineHealthCheck" path="/mutate-cluster-x-k8s-io-v1beta2-machinehealthcheck"
I0919 17:53:59.401736       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machinehealthcheck"
I0919 17:53:59.401784       1 webhook.go:293] "Registering a validating webhook" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineHealthCheck" path="/validate-cluster-x-k8s-io-v1beta2-machinehealthcheck"
I0919 17:53:59.401881       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinehealthcheck"
I0919 17:53:59.401942       1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineHealthCheck"
I0919 17:53:59.401993       1 webhook.go:246] "Registering a mutating webhook" logger="controller-runtime.builder" GVK="runtime.cluster.x-k8s.io/v1beta2, Kind=ExtensionConfig" path="/mutate-runtime-cluster-x-k8s-io-v1beta2-extensionconfig"
I0919 17:53:59.402063       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-runtime-cluster-x-k8s-io-v1beta2-extensionconfig"
I0919 17:53:59.402115       1 webhook.go:293] "Registering a validating webhook" logger="controller-runtime.builder" GVK="runtime.cluster.x-k8s.io/v1beta2, Kind=ExtensionConfig" path="/validate-runtime-cluster-x-k8s-io-v1beta2-extensionconfig"
I0919 17:53:59.402182       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-runtime-cluster-x-k8s-io-v1beta2-extensionconfig"
I0919 17:53:59.402230       1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="runtime.cluster.x-k8s.io/v1beta2, Kind=ExtensionConfig"
I0919 17:53:59.402272       1 webhook.go:293] "Registering a validating webhook" logger="controller-runtime.builder" GVK="ipam.cluster.x-k8s.io/v1beta2, Kind=IPAddress" path="/validate-ipam-cluster-x-k8s-io-v1beta2-ipaddress"
I0919 17:53:59.402351       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-ipam-cluster-x-k8s-io-v1beta2-ipaddress"
I0919 17:53:59.402405       1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="ipam.cluster.x-k8s.io/v1beta2, Kind=IPAddress"
I0919 17:53:59.402446       1 webhook.go:293] "Registering a validating webhook" logger="controller-runtime.builder" GVK="ipam.cluster.x-k8s.io/v1beta2, Kind=IPAddressClaim" path="/validate-ipam-cluster-x-k8s-io-v1beta2-ipaddressclaim"
I0919 17:53:59.402519       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-ipam-cluster-x-k8s-io-v1beta2-ipaddressclaim"
I0919 17:53:59.402584       1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="ipam.cluster.x-k8s.io/v1beta2, Kind=IPAddressClaim"
I0919 17:53:59.402634       1 main.go:400] "Starting manager" logger="setup" version="v1.13.2"
I0919 17:53:59.402722       1 server.go:208] "Starting metrics server" logger="controller-runtime.metrics"
I0919 17:53:59.403235       1 server.go:83] "starting server" name="health probe" addr="[::]:9440"
I0919 17:53:59.403423       1 server.go:191] "Starting webhook server" logger="controller-runtime.webhook"
I0919 17:53:59.404074       1 certwatcher.go:214] "Updated current TLS certificate" logger="controller-runtime.certwatcher" cert="/tmp/k8s-webhook-server/serving-certs/tls.crt" key="/tmp/k8s-webhook-server/serving-certs/tls.key"
I0919 17:53:59.404203       1 server.go:242] "Serving webhook server" logger="controller-runtime.webhook" host="" port=9443
I0919 17:53:59.404521       1 certwatcher.go:136] "Starting certificate poll+watcher" logger="controller-runtime.certwatcher" cert="/tmp/k8s-webhook-server/serving-certs/tls.crt" key="/tmp/k8s-webhook-server/serving-certs/tls.key" interval="10s"
I0919 17:53:59.411132       1 reflector.go:446] "Caches populated" logger="controller-runtime.cache" type="*v1beta2.Machine" reflector="k8s.io/client-go@v0.35.4/tools/cache/reflector.go:289"
I0919 17:53:59.413269       1 reflector.go:446] "Caches populated" logger="controller-runtime.cache" type="*v1beta2.MachinePool" reflector="k8s.io/client-go@v0.35.4/tools/cache/reflector.go:289"
I0919 17:53:59.414763       1 reflector.go:446] "Caches populated" logger="controller-runtime.cache" type="*v1beta2.Cluster" reflector="k8s.io/client-go@v0.35.4/tools/cache/reflector.go:289"
I0919 17:53:59.504600       1 leaderelection.go:258] "Attempting to acquire leader lease..." lock="capi-system/controller-leader-election-capi"
I0919 17:53:59.541896       1 server.go:247] "Serving metrics server" logger="controller-runtime.metrics" bindAddress=":8443" secure=true
I0919 17:54:16.526657       1 leaderelection.go:272] "Successfully acquired lease" lock="capi-system/controller-leader-election-capi"
I0919 17:54:16.526836       1 recorder.go:116] "capi-controller-manager-5647546676-96b44_de8ad596-0a9d-41ec-94a8-ecfd2d41c2af became leader" logger="events" type="Normal" object={"kind":"Lease","namespace":"capi-system","name":"controller-leader-election-capi","uid":"ea928e0f-a7ea-4466-9194-e942ea5cba19","apiVersion":"coordination.k8s.io/v1","resourceVersion":"25305"} reason="LeaderElection"
I0919 17:54:16.527693       1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="channel source: 0xc00003b810"
I0919 17:54:16.527763       1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.MachineDeployment"
I0919 17:54:16.527773       1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="channel source: 0xc00003a9a0"
I0919 17:54:16.527780       1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Machine"
I0919 17:54:16.527873       1 controller.go:370] "Starting EventSource" controller="crdmigrator" controllerGroup="apiextensions.k8s.io" controllerKind="CustomResourceDefinition" source="kind source: *v1.PartialObjectMetadata"
I0919 17:54:16.527891       1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.MachineSet"
I0919 17:54:16.528096       1 controller.go:370] "Starting EventSource" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.Cluster"
I0919 17:54:16.528198       1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Machine"
I0919 17:54:16.528100       1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Cluster"
I0919 17:54:16.528130       1 controller.go:370] "Starting EventSource" controller="clustercache" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Cluster"
I0919 17:54:16.528273       1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachinePool"
I0919 17:54:16.528100       1 controller.go:370] "Starting EventSource" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineDeployment"
I0919 17:54:16.528344       1 controller.go:370] "Starting EventSource" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" source="kind source: *v1beta2.ExtensionConfig"
I0919 17:54:16.528372       1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Cluster"
I0919 17:54:16.528430       1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachineDeployment"
I0919 17:54:16.528482       1 controller.go:370] "Starting EventSource" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" source="kind source: *v1beta2.ClusterClass"
I0919 17:54:16.528488       1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.ClusterClass"
I0919 17:54:16.529891       1 controller.go:370] "Starting EventSource" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Cluster"
I0919 17:54:16.530705       1 controller.go:370] "Starting EventSource" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.MachineSet"
I0919 17:54:16.530979       1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="channel source: 0xc00003b420"
I0919 17:54:16.531245       1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.MachineSet"
I0919 17:54:16.531298       1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.MachineDeployment"
I0919 17:54:16.531381       1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.Cluster"
I0919 17:54:16.533173       1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.Machine"
I0919 17:54:16.534123       1 controller.go:370] "Starting EventSource" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" source="kind source: *v1beta2.Cluster"
I0919 17:54:16.536452       1 controller.go:370] "Starting EventSource" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" source="kind source: *v1beta2.Cluster"
I0919 17:54:16.536477       1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="kind source: *v1beta2.MachineHealthCheck"
I0919 17:54:16.536561       1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="channel source: 0xc00003bdc0"
I0919 17:54:16.536682       1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="kind source: *v1beta2.Machine"
I0919 17:54:16.536700       1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachinePool"
I0919 17:54:16.536732       1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineDeployment"
I0919 17:54:16.536761       1 controller.go:370] "Starting EventSource" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" source="channel source: 0xc00003bc70"
I0919 17:54:16.536818       1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1beta2.ClusterResourceSet"
I0919 17:54:16.536711       1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.Cluster"
I0919 17:54:16.536836       1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineSet"
I0919 17:54:16.536878       1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1.PartialObjectMetadata"
I0919 17:54:16.536935       1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="kind source: *v1beta2.Cluster"
I0919 17:54:16.536721       1 controller.go:370] "Starting EventSource" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" source="kind source: *v1beta2.ClusterResourceSetBinding"
I0919 17:54:16.536984       1 controller.go:370] "Starting EventSource" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" source="kind source: *v1beta2.MachinePool"
I0919 17:54:16.536924       1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineSet"
I0919 17:54:16.536770       1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Cluster"
I0919 17:54:16.537125       1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Machine"
I0919 17:54:16.537388       1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1beta2.Cluster"
I0919 17:54:16.537458       1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="channel source: 0xc00003bd50"
I0919 17:54:16.537631       1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1.PartialObjectMetadata"
I0919 17:54:16.537654       1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachineDeployment"
I0919 17:54:16.537697       1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="channel source: 0xc00003ae00"
I0919 17:54:16.538322       1 reflector.go:446] "Caches populated" logger="controller-runtime.cache" type="*v1beta2.MachineDeployment" reflector="k8s.io/client-go@v0.35.4/tools/cache/reflector.go:289"
I0919 17:54:16.540932       1 reflector.go:446] "Caches populated" logger="controller-runtime.cache" type="*v1beta2.ExtensionConfig" reflector="k8s.io/client-go@v0.35.4/tools/cache/reflector.go:289"
I0919 17:54:16.541597       1 reflector.go:446] "Caches populated" logger="controller-runtime.cache" type="*v1beta2.MachineHealthCheck" reflector="k8s.io/client-go@v0.35.4/tools/cache/reflector.go:289"
I0919 17:54:16.542387       1 reflector.go:446] "Caches populated" logger="controller-runtime.cache" type="*v1beta2.ClusterResourceSetBinding" reflector="k8s.io/client-go@v0.35.4/tools/cache/reflector.go:289"
I0919 17:54:16.542517       1 reflector.go:446] "Caches populated" logger="controller-runtime.cache" type="*v1beta2.MachineSet" reflector="k8s.io/client-go@v0.35.4/tools/cache/reflector.go:289"
I0919 17:54:16.543333       1 reflector.go:446] "Caches populated" logger="controller-runtime.cache" type="*v1beta2.ClusterResourceSet" reflector="k8s.io/client-go@v0.35.4/tools/cache/reflector.go:289"
I0919 17:54:16.544018       1 reflector.go:446] "Caches populated" logger="controller-runtime.cache" type="*v1beta2.ClusterClass" reflector="k8s.io/client-go@v0.35.4/tools/cache/reflector.go:289"
I0919 17:54:16.550120       1 reflector.go:446] "Caches populated" logger="controller-runtime.cache" type="*v1.PartialObjectMetadata" reflector="k8s.io/client-go@v0.35.4/tools/cache/reflector.go:289"
I0919 17:54:16.554001       1 reflector.go:446] "Caches populated" logger="controller-runtime.cache" type="*v1.PartialObjectMetadata" reflector="k8s.io/client-go@v0.35.4/tools/cache/reflector.go:289"
I0919 17:54:16.554269       1 reflector.go:446] "Caches populated" logger="controller-runtime.cache" type="*v1.PartialObjectMetadata" reflector="k8s.io/client-go@v0.35.4/tools/cache/reflector.go:289"
I0919 17:54:16.631739       1 controller.go:303] "Starting Controller" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment"
I0919 17:54:16.631773       1 controller.go:306] "Starting workers" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" worker count=1
I0919 17:54:16.633046       1 controller.go:303] "Starting Controller" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster"
I0919 17:54:16.633067       1 controller.go:306] "Starting workers" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" worker count=50
I0919 17:54:16.633358       1 controller.go:303] "Starting Controller" controller="clustercache" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster"
I0919 17:54:16.633377       1 controller.go:303] "Starting Controller" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet"
I0919 17:54:16.633424       1 controller.go:306] "Starting workers" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" worker count=1
I0919 17:54:16.633395       1 controller.go:306] "Starting workers" controller="clustercache" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" worker count=100
I0919 17:54:16.633376       1 controller.go:303] "Starting Controller" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet"
I0919 17:54:16.633751       1 controller.go:306] "Starting workers" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" worker count=50
I0919 17:54:16.633887       1 controller.go:303] "Starting Controller" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass"
I0919 17:54:16.633913       1 controller.go:306] "Starting workers" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" worker count=10
I0919 17:54:16.634090       1 controller.go:303] "Starting Controller" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine"
I0919 17:54:16.634130       1 controller.go:306] "Starting workers" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" worker count=100
I0919 17:54:16.638515       1 controller.go:303] "Starting Controller" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck"
I0919 17:54:16.638540       1 controller.go:303] "Starting Controller" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster"
I0919 17:54:16.638566       1 controller.go:306] "Starting workers" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" worker count=50
I0919 17:54:16.638549       1 controller.go:306] "Starting workers" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" worker count=50
I0919 17:54:16.638522       1 controller.go:303] "Starting Controller" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment"
I0919 17:54:16.638625       1 controller.go:306] "Starting workers" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" worker count=50
I0919 17:54:16.638587       1 controller.go:303] "Starting Controller" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool"
I0919 17:54:16.638658       1 controller.go:306] "Starting workers" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" worker count=50
I0919 17:54:16.638580       1 controller.go:303] "Starting Controller" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding"
I0919 17:54:16.638721       1 controller.go:306] "Starting workers" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" worker count=10
I0919 17:54:16.734027       1 controller.go:303] "Starting Controller" controller="crdmigrator" controllerGroup="apiextensions.k8s.io" controllerKind="CustomResourceDefinition"
I0919 17:54:16.734059       1 controller.go:306] "Starting workers" controller="crdmigrator" controllerGroup="apiextensions.k8s.io" controllerKind="CustomResourceDefinition" worker count=1
I0919 17:54:16.738628       1 controller.go:303] "Starting Controller" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet"
I0919 17:54:16.738652       1 controller.go:306] "Starting workers" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" worker count=10
E0919 17:57:47.839494       1 leaderelection.go:445] "Failed to update lease optimistically, falling back to slow path" err="Put \"https://10.96.0.1:443/apis/coordination.k8s.io/v1/namespaces/capi-system/leases/controller-leader-election-capi?timeout=5s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" lock="capi-system/controller-leader-election-capi"
E0919 17:58:06.314023       1 leaderelection.go:445] "Failed to update lease optimistically, falling back to slow path" err="Put \"https://10.96.0.1:443/apis/coordination.k8s.io/v1/namespaces/capi-system/leases/controller-leader-election-capi?timeout=5s\": context deadline exceeded" lock="capi-system/controller-leader-election-capi"
E0919 17:58:11.321346       1 leaderelection.go:488] "Failed to update lease" err="Put \"https://10.96.0.1:443/apis/coordination.k8s.io/v1/namespaces/capi-system/leases/controller-leader-election-capi?timeout=5s\": context deadline exceeded" lock="capi-system/controller-leader-election-capi"
I0919 17:58:11.321414       1 leaderelection.go:299] "Failed to renew lease" lock="capi-system/controller-leader-election-capi" err="context deadline exceeded"
E0919 17:58:11.321556       1 main.go:402] "Problem running manager" err="leader election lost" logger="setup"
I0919 17:58:11.321642       1 internal.go:571] "Stopping and waiting for non leader election runnables"
I0919 17:58:11.321658       1 internal.go:575] "Stopping and waiting for leader election runnables"
I0919 17:58:11.321701       1 internal.go:583] "Stopping and waiting for caches"
I0919 17:58:11.321712       1 internal.go:587] "Stopping and waiting for webhooks"
I0919 17:58:11.321719       1 internal.go:590] "Stopping and waiting for HTTP servers"
I0919 17:58:11.321726       1 internal.go:594] "Wait completed, proceeding to shutdown the manager"
I0919 17:58:11.321734       1 internal.go:566] "Stopping and waiting for warmup runnables"
I0919 17:58:11.321760       1 controller.go:326] "Shutdown signal received, waiting for all workers to finish" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet"
I0919 17:58:11.321774       1 controller.go:326] "Shutdown signal received, waiting for all workers to finish" controller="crdmigrator" controllerGroup="apiextensions.k8s.io" controllerKind="CustomResourceDefinition"
I0919 17:58:11.321780       1 controller.go:326] "Shutdown signal received, waiting for all workers to finish" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment"
I0919 17:58:11.321787       1 controller.go:326] "Shutdown signal received, waiting for all workers to finish" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding"
I0919 17:58:11.321794       1 controller.go:326] "Shutdown signal received, waiting for all workers to finish" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool"
I0919 17:58:11.321800       1 controller.go:326] "Shutdown signal received, waiting for all workers to finish" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck"
I0919 17:58:11.321806       1 controller.go:326] "Shutdown signal received, waiting for all workers to finish" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster"
I0919 17:58:11.321812       1 controller.go:326] "Shutdown signal received, waiting for all workers to finish" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine"
I0919 17:58:11.321817       1 controller.go:326] "Shutdown signal received, waiting for all workers to finish" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass"
I0919 17:58:11.321822       1 controller.go:326] "Shutdown signal received, waiting for all workers to finish" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet"
I0919 17:58:11.321592       1 recorder.go:116] "capi-controller-manager-5647546676-96b44_de8ad596-0a9d-41ec-94a8-ecfd2d41c2af stopped leading" logger="events" type="Normal" object={"kind":"Lease","namespace":"capi-system","name":"controller-leader-election-capi","uid":"ea928e0f-a7ea-4466-9194-e942ea5cba19","apiVersion":"coordination.k8s.io/v1","resourceVersion":"27224"} reason="LeaderElection"
I0919 17:58:11.321837       1 controller.go:326] "Shutdown signal received, waiting for all workers to finish" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster"
I0919 17:58:11.321833       1 controller.go:326] "Shutdown signal received, waiting for all workers to finish" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet"
I0919 17:58:11.321828       1 controller.go:326] "Shutdown signal received, waiting for all workers to finish" controller="clustercache" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster"
I0919 17:58:11.321847       1 controller.go:326] "Shutdown signal received, waiting for all workers to finish" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment"
I0919 17:58:11.322408       1 controller.go:328] "All workers finished" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet"
I0919 17:58:11.322485       1 controller.go:328] "All workers finished" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet"
I0919 17:58:11.322755       1 controller.go:328] "All workers finished" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment"
I0919 17:58:11.322567       1 controller.go:328] "All workers finished" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool"
I0919 17:58:11.322876       1 controller.go:328] "All workers finished" controller="clustercache" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster"
I0919 17:58:11.322907       1 controller.go:328] "All workers finished" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment"
I0919 17:58:11.323002       1 controller.go:328] "All workers finished" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass"
I0919 17:58:11.323008       1 controller.go:328] "All workers finished" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster"
I0919 17:58:11.323026       1 controller.go:328] "All workers finished" controller="crdmigrator" controllerGroup="apiextensions.k8s.io" controllerKind="CustomResourceDefinition"
I0919 17:58:11.323068       1 controller.go:328] "All workers finished" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster"
I0919 17:58:11.323094       1 controller.go:328] "All workers finished" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding"
I0919 17:58:11.323128       1 controller.go:328] "All workers finished" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet"
I0919 17:58:11.323170       1 controller.go:328] "All workers finished" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine"
I0919 17:58:11.323195       1 controller.go:328] "All workers finished" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck"
I0919 17:58:11.323236       1 server.go:249] "Shutting down webhook server with timeout of 1 minute" logger="controller-runtime.webhook"
I0919 17:58:11.323257       1 server.go:254] "Shutting down metrics server with timeout of 1 minute" logger="controller-runtime.metrics"
I0919 17:58:11.323271       1 server.go:68] "shutting down server" name="health probe" addr="[::]:9440"