W0623 19:35:31.422907 1 feature_gate.go:410] Setting GA feature gate MachineWaitForVolumeDetachConsiderVolumeAttachments=true. It will be removed in a future release. I0623 19:35:31.423007 1 main.go:308] FLAG: --additional-sync-machine-annotations="[]" I0623 19:35:31.423015 1 main.go:308] FLAG: --additional-sync-machine-labels="[]" I0623 19:35:31.423020 1 main.go:308] FLAG: --cluster-concurrency="50" I0623 19:35:31.423024 1 main.go:308] FLAG: --clustercache-client-burst="30" I0623 19:35:31.423027 1 main.go:308] FLAG: --clustercache-client-qps="20" I0623 19:35:31.423031 1 main.go:308] FLAG: --clustercache-concurrency="100" I0623 19:35:31.423034 1 main.go:308] FLAG: --clusterclass-concurrency="10" I0623 19:35:31.423037 1 main.go:308] FLAG: --clusterresourceset-concurrency="10" I0623 19:35:31.423039 1 main.go:308] FLAG: --clustertopology-concurrency="50" I0623 19:35:31.423042 1 main.go:308] FLAG: --contention-profiling="false" I0623 19:35:31.423048 1 main.go:308] FLAG: --diagnostics-address=":8443" I0623 19:35:31.423052 1 main.go:308] FLAG: --extensionconfig-concurrency="10" I0623 19:35:31.423054 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" I0623 19:35:31.423073 1 main.go:308] FLAG: --health-addr=":9440" I0623 19:35:31.423076 1 main.go:308] FLAG: --insecure-diagnostics="false" I0623 19:35:31.423080 1 main.go:308] FLAG: --kube-api-burst="200" I0623 19:35:31.423083 1 main.go:308] FLAG: --kube-api-qps="100" I0623 19:35:31.423085 1 main.go:308] FLAG: --kubeconfig="" I0623 19:35:31.423101 1 main.go:308] FLAG: --leader-elect="true" I0623 19:35:31.423103 1 main.go:308] FLAG: --leader-elect-lease-duration="15s" I0623 19:35:31.423107 1 main.go:308] FLAG: --leader-elect-renew-deadline="10s" I0623 19:35:31.423110 1 main.go:308] FLAG: --leader-elect-retry-period="2s" I0623 19:35:31.423112 1 main.go:308] FLAG: --log-flush-frequency="5s" I0623 19:35:31.423114 1 main.go:308] FLAG: --log-json-info-buffer-size="0" I0623 19:35:31.423120 1 main.go:308] FLAG: --log-json-split-stream="false" I0623 19:35:31.423122 1 main.go:308] FLAG: --log-text-info-buffer-size="0" I0623 19:35:31.423125 1 main.go:308] FLAG: --log-text-split-stream="false" I0623 19:35:31.423128 1 main.go:308] FLAG: --logging-format="text" I0623 19:35:31.423131 1 main.go:308] FLAG: --machine-concurrency="100" I0623 19:35:31.423134 1 main.go:308] FLAG: --machinedeployment-concurrency="50" I0623 19:35:31.423137 1 main.go:308] FLAG: --machinehealthcheck-concurrency="50" I0623 19:35:31.423139 1 main.go:308] FLAG: --machinepool-concurrency="50" I0623 19:35:31.423142 1 main.go:308] FLAG: --machineset-concurrency="50" I0623 19:35:31.423145 1 main.go:308] FLAG: --machineset-preflight-checks="[All]" I0623 19:35:31.423148 1 main.go:308] FLAG: --namespace="" I0623 19:35:31.423210 1 main.go:308] FLAG: --profiler-address="" I0623 19:35:31.423219 1 main.go:308] FLAG: --remote-conditions-grace-period="5m0s" I0623 19:35:31.423221 1 main.go:308] FLAG: --remote-connection-grace-period="50s" I0623 19:35:31.423224 1 main.go:308] FLAG: --runtime-extension-client-cert-file="" I0623 19:35:31.423226 1 main.go:308] FLAG: --runtime-extension-client-key-file="" I0623 19:35:31.423228 1 main.go:308] FLAG: --skip-crd-migration-phases="[]" I0623 19:35:31.423233 1 main.go:308] FLAG: --sync-period="10m0s" I0623 19:35:31.423235 1 main.go:308] FLAG: --tls-cipher-suites="[]" I0623 19:35:31.423238 1 main.go:308] FLAG: --tls-min-version="VersionTLS12" I0623 19:35:31.423241 1 main.go:308] FLAG: --v="2" I0623 19:35:31.423273 1 main.go:308] FLAG: --vmodule="" I0623 19:35:31.423276 1 main.go:308] FLAG: --watch-filter="" I0623 19:35:31.423278 1 main.go:308] FLAG: --webhook-cert-dir="/tmp/k8s-webhook-server/serving-certs/" I0623 19:35:31.423282 1 main.go:308] FLAG: --webhook-cert-name="tls.crt" I0623 19:35:31.423285 1 main.go:308] FLAG: --webhook-key-name="tls.key" I0623 19:35:31.423305 1 main.go:308] FLAG: --webhook-port="9443" I0623 19:35:31.423336 1 main.go:315] "Version: v1.13.2 (git commit: ebd807c66351f3ad96ca99b68d2219305d794640)" logger="setup" I0623 19:35:31.423631 1 envvar.go:172] "Feature gate default state" feature="WatchListClient" enabled=true I0623 19:35:31.423649 1 envvar.go:172] "Feature gate default state" feature="ClientsAllowCBOR" enabled=false I0623 19:35:31.423654 1 envvar.go:172] "Feature gate default state" feature="ClientsPreferCBOR" enabled=false I0623 19:35:31.423658 1 envvar.go:172] "Feature gate default state" feature="InOrderInformers" enabled=true I0623 19:35:31.423662 1 envvar.go:172] "Feature gate default state" feature="InOrderInformersBatchProcess" enabled=true I0623 19:35:31.423665 1 envvar.go:172] "Feature gate default state" feature="InformerResourceVersion" enabled=true I0623 19:35:31.445191 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" I0623 19:35:31.446101 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" I0623 19:35:31.446201 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-clusterclass" I0623 19:35:31.446304 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/convert" I0623 19:35:31.446315 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=ClusterClass" I0623 19:35:31.446358 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" I0623 19:35:31.446400 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-cluster" I0623 19:35:31.446467 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" I0623 19:35:31.446523 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-cluster" I0623 19:35:31.446561 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=Cluster" I0623 19:35:31.446601 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" I0623 19:35:31.446647 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machine" I0623 19:35:31.446674 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" I0623 19:35:31.446721 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machine" I0623 19:35:31.446753 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=Machine" I0623 19:35:31.446802 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" I0623 19:35:31.446874 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machineset" I0623 19:35:31.446940 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" I0623 19:35:31.446990 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machineset" I0623 19:35:31.447032 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineSet" I0623 19:35:31.447080 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" I0623 19:35:31.447137 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machinedeployment" I0623 19:35:31.447173 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" I0623 19:35:31.447221 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinedeployment" I0623 19:35:31.447259 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineDeployment" I0623 19:35:31.447294 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" I0623 19:35:31.447372 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinedrainrule" I0623 19:35:31.447407 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineDrainRule" I0623 19:35:31.447452 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" I0623 19:35:31.447504 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machinepool" I0623 19:35:31.447536 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" I0623 19:35:31.447582 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinepool" I0623 19:35:31.447617 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachinePool" I0623 19:35:31.447654 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" I0623 19:35:31.447701 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-addons-cluster-x-k8s-io-v1beta2-clusterresourceset" I0623 19:35:31.447728 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" I0623 19:35:31.447929 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-addons-cluster-x-k8s-io-v1beta2-clusterresourceset" I0623 19:35:31.449276 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="addons.cluster.x-k8s.io/v1beta2, Kind=ClusterResourceSet" I0623 19:35:31.449451 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" I0623 19:35:31.449648 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-addons-cluster-x-k8s-io-v1beta2-clusterresourcesetbinding" I0623 19:35:31.449735 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="addons.cluster.x-k8s.io/v1beta2, Kind=ClusterResourceSetBinding" I0623 19:35:31.449827 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" I0623 19:35:31.449953 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machinehealthcheck" I0623 19:35:31.450026 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" I0623 19:35:31.451080 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinehealthcheck" I0623 19:35:31.451233 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineHealthCheck" I0623 19:35:31.451445 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" I0623 19:35:31.451601 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-runtime-cluster-x-k8s-io-v1beta2-extensionconfig" I0623 19:35:31.451662 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" I0623 19:35:31.451765 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-runtime-cluster-x-k8s-io-v1beta2-extensionconfig" I0623 19:35:31.451854 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="runtime.cluster.x-k8s.io/v1beta2, Kind=ExtensionConfig" I0623 19:35:31.451908 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" I0623 19:35:31.451997 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-ipam-cluster-x-k8s-io-v1beta2-ipaddress" I0623 19:35:31.452063 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="ipam.cluster.x-k8s.io/v1beta2, Kind=IPAddress" I0623 19:35:31.452119 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" I0623 19:35:31.452205 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-ipam-cluster-x-k8s-io-v1beta2-ipaddressclaim" I0623 19:35:31.452265 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="ipam.cluster.x-k8s.io/v1beta2, Kind=IPAddressClaim" I0623 19:35:31.452290 1 main.go:400] "Starting manager" logger="setup" version="v1.13.2" I0623 19:35:31.452421 1 server.go:208] "Starting metrics server" logger="controller-runtime.metrics" I0623 19:35:31.452500 1 server.go:83] "starting server" name="health probe" addr="[::]:9440" I0623 19:35:31.452704 1 server.go:191] "Starting webhook server" logger="controller-runtime.webhook" I0623 19:35:31.453208 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" I0623 19:35:31.453361 1 server.go:242] "Serving webhook server" logger="controller-runtime.webhook" host="" port=9443 I0623 19:35:31.453538 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" I0623 19:35:31.456980 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" I0623 19:35:31.456985 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" I0623 19:35:31.457368 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" I0623 19:35:31.553855 1 leaderelection.go:258] "Attempting to acquire leader lease..." lock="capi-system/controller-leader-election-capi" I0623 19:35:31.580354 1 server.go:247] "Serving metrics server" logger="controller-runtime.metrics" bindAddress=":8443" secure=true I0623 19:35:56.856840 1 leaderelection.go:272] "Successfully acquired lease" lock="capi-system/controller-leader-election-capi" I0623 19:35:56.857942 1 controller.go:370] "Starting EventSource" controller="clustercache" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Cluster" I0623 19:35:56.858247 1 controller.go:303] "Starting Controller" controller="clustercache" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" I0623 19:35:56.858262 1 controller.go:306] "Starting workers" controller="clustercache" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" worker count=100 I0623 19:35:56.858319 1 controller.go:370] "Starting EventSource" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" source="kind source: *v1beta2.ExtensionConfig" I0623 19:35:56.858565 1 controller.go:370] "Starting EventSource" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" source="kind source: *v1beta2.ClusterClass" I0623 19:35:56.858748 1 controller.go:370] "Starting EventSource" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Cluster" I0623 19:35:56.858603 1 controller.go:370] "Starting EventSource" controller="crdmigrator" controllerGroup="apiextensions.k8s.io" controllerKind="CustomResourceDefinition" source="kind source: *v1.PartialObjectMetadata" I0623 19:35:56.859103 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="channel source: 0xc0001510a0" I0623 19:35:56.859274 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="channel source: 0xc00037e1c0" I0623 19:35:56.859283 1 controller.go:370] "Starting EventSource" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" source="kind source: *v1beta2.ClusterResourceSetBinding" I0623 19:35:56.857339 1 recorder.go:116] "capi-controller-manager-5647546676-6zz7v_f42b1482-1db7-45e0-b053-662e26384288 became leader" logger="events" type="Normal" object={"kind":"Lease","namespace":"capi-system","name":"controller-leader-election-capi","uid":"ffee40db-79fa-4e2f-93f8-a2738c3321cb","apiVersion":"coordination.k8s.io/v1","resourceVersion":"24274"} reason="LeaderElection" I0623 19:35:56.859626 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.ClusterClass" I0623 19:35:56.859863 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1.PartialObjectMetadata" I0623 19:35:56.860667 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.MachineDeployment" I0623 19:35:56.860633 1 controller.go:370] "Starting EventSource" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" source="kind source: *v1beta2.Cluster" I0623 19:35:56.860719 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.MachineSet" I0623 19:35:56.862635 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Machine" I0623 19:35:56.862688 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Machine" I0623 19:35:56.862715 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Cluster" I0623 19:35:56.862721 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Cluster" I0623 19:35:56.862729 1 controller.go:370] "Starting EventSource" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.MachineSet" I0623 19:35:56.862748 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Cluster" I0623 19:35:56.862756 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Machine" I0623 19:35:56.862775 1 controller.go:370] "Starting EventSource" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineDeployment" I0623 19:35:56.862794 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachinePool" I0623 19:35:56.860709 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="channel source: 0xc0001515e0" I0623 19:35:56.859925 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachineDeployment" I0623 19:35:56.863063 1 controller.go:370] "Starting EventSource" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" source="channel source: 0xc00037e620" I0623 19:35:56.863109 1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.Cluster" I0623 19:35:56.863163 1 controller.go:370] "Starting EventSource" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" source="kind source: *v1beta2.Cluster" I0623 19:35:56.863200 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1.PartialObjectMetadata" I0623 19:35:56.863218 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="channel source: 0xc00037e690" I0623 19:35:56.863242 1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineSet" I0623 19:35:56.863248 1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineDeployment" I0623 19:35:56.863258 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachinePool" I0623 19:35:56.863303 1 controller.go:370] "Starting EventSource" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" source="kind source: *v1beta2.MachinePool" I0623 19:35:56.863338 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1beta2.ClusterResourceSet" I0623 19:35:56.863375 1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineSet" I0623 19:35:56.863423 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1beta2.Cluster" I0623 19:35:56.863482 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachineDeployment" I0623 19:35:56.863553 1 controller.go:370] "Starting EventSource" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.Cluster" I0623 19:35:56.863586 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.MachineDeployment" I0623 19:35:56.863600 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.Machine" I0623 19:35:56.863641 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.Cluster" I0623 19:35:56.863684 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.MachineSet" I0623 19:35:56.863721 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="channel source: 0xc000151c70" I0623 19:35:56.863736 1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="channel source: 0xc00037e700" I0623 19:35:56.863759 1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="kind source: *v1beta2.MachineHealthCheck" I0623 19:35:56.863770 1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="kind source: *v1beta2.Machine" I0623 19:35:56.863801 1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="kind source: *v1beta2.Cluster" I0623 19:35:56.867646 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" I0623 19:35:56.868250 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" I0623 19:35:56.868734 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" I0623 19:35:56.870338 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" I0623 19:35:56.870405 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" I0623 19:35:56.870440 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" I0623 19:35:56.879770 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" I0623 19:35:56.880984 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" I0623 19:35:56.885186 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" I0623 19:35:56.885892 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" I0623 19:35:56.960316 1 controller.go:303] "Starting Controller" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" I0623 19:35:56.960374 1 controller.go:306] "Starting workers" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" worker count=10 I0623 19:35:56.964766 1 controller.go:303] "Starting Controller" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" I0623 19:35:56.964798 1 controller.go:306] "Starting workers" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" worker count=50 I0623 19:35:56.964973 1 controller.go:303] "Starting Controller" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" I0623 19:35:56.964998 1 controller.go:306] "Starting workers" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" worker count=1 I0623 19:35:56.965011 1 controller.go:303] "Starting Controller" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" I0623 19:35:56.965025 1 controller.go:306] "Starting workers" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" worker count=10 I0623 19:35:56.965103 1 controller.go:303] "Starting Controller" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" I0623 19:35:56.965114 1 controller.go:306] "Starting workers" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" worker count=50 I0623 19:35:56.965149 1 controller.go:303] "Starting Controller" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" I0623 19:35:56.965159 1 controller.go:306] "Starting workers" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" worker count=50 I0623 19:35:56.965215 1 controller.go:303] "Starting Controller" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" I0623 19:35:56.965224 1 controller.go:306] "Starting workers" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" worker count=50 I0623 19:35:56.965440 1 controller.go:303] "Starting Controller" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" I0623 19:35:56.965479 1 controller.go:303] "Starting Controller" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" I0623 19:35:56.965522 1 controller.go:306] "Starting workers" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" worker count=100 I0623 19:35:56.965501 1 controller.go:306] "Starting workers" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" worker count=1 I0623 19:35:56.965485 1 controller.go:303] "Starting Controller" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" I0623 19:35:56.965685 1 controller.go:306] "Starting workers" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" worker count=50 I0623 19:35:56.965642 1 controller.go:303] "Starting Controller" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" I0623 19:35:56.965813 1 controller.go:306] "Starting workers" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" worker count=50 I0623 19:35:57.064424 1 controller.go:303] "Starting Controller" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" I0623 19:35:57.064454 1 controller.go:306] "Starting workers" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" worker count=10 I0623 19:35:57.066622 1 controller.go:303] "Starting Controller" controller="crdmigrator" controllerGroup="apiextensions.k8s.io" controllerKind="CustomResourceDefinition" I0623 19:35:57.066658 1 controller.go:306] "Starting workers" controller="crdmigrator" controllerGroup="apiextensions.k8s.io" controllerKind="CustomResourceDefinition" worker count=1 E0623 19:39:23.609550 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" E0623 19:39:28.609445 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" I0623 19:39:28.609515 1 leaderelection.go:299] "Failed to renew lease" lock="capi-system/controller-leader-election-capi" err="context deadline exceeded" E0623 19:39:28.612893 1 main.go:402] "Problem running manager" err="leader election lost" logger="setup"