W0722 22:50:05.500862 1 feature_gate.go:410] Setting GA feature gate MachineWaitForVolumeDetachConsiderVolumeAttachments=true. It will be removed in a future release. I0722 22:50:05.500955 1 main.go:308] FLAG: --additional-sync-machine-annotations="[]" I0722 22:50:05.500963 1 main.go:308] FLAG: --additional-sync-machine-labels="[]" I0722 22:50:05.500966 1 main.go:308] FLAG: --cluster-concurrency="50" I0722 22:50:05.500973 1 main.go:308] FLAG: --clustercache-client-burst="30" I0722 22:50:05.500975 1 main.go:308] FLAG: --clustercache-client-qps="20" I0722 22:50:05.500980 1 main.go:308] FLAG: --clustercache-concurrency="100" I0722 22:50:05.500982 1 main.go:308] FLAG: --clusterclass-concurrency="10" I0722 22:50:05.500984 1 main.go:308] FLAG: --clusterresourceset-concurrency="10" I0722 22:50:05.500987 1 main.go:308] FLAG: --clustertopology-concurrency="50" I0722 22:50:05.500989 1 main.go:308] FLAG: --contention-profiling="false" I0722 22:50:05.500993 1 main.go:308] FLAG: --diagnostics-address=":8443" I0722 22:50:05.500997 1 main.go:308] FLAG: --extensionconfig-concurrency="10" I0722 22:50:05.501000 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" I0722 22:50:05.501021 1 main.go:308] FLAG: --health-addr=":9440" I0722 22:50:05.501024 1 main.go:308] FLAG: --insecure-diagnostics="false" I0722 22:50:05.501026 1 main.go:308] FLAG: --kube-api-burst="200" I0722 22:50:05.501029 1 main.go:308] FLAG: --kube-api-qps="100" I0722 22:50:05.501032 1 main.go:308] FLAG: --kubeconfig="" I0722 22:50:05.501035 1 main.go:308] FLAG: --leader-elect="true" I0722 22:50:05.501038 1 main.go:308] FLAG: --leader-elect-lease-duration="15s" I0722 22:50:05.501042 1 main.go:308] FLAG: --leader-elect-renew-deadline="10s" I0722 22:50:05.501049 1 main.go:308] FLAG: --leader-elect-retry-period="2s" I0722 22:50:05.501055 1 main.go:308] FLAG: --log-flush-frequency="5s" I0722 22:50:05.501058 1 main.go:308] FLAG: --log-json-info-buffer-size="0" I0722 22:50:05.501065 1 main.go:308] FLAG: --log-json-split-stream="false" I0722 22:50:05.501068 1 main.go:308] FLAG: --log-text-info-buffer-size="0" I0722 22:50:05.501071 1 main.go:308] FLAG: --log-text-split-stream="false" I0722 22:50:05.501074 1 main.go:308] FLAG: --logging-format="text" I0722 22:50:05.501083 1 main.go:308] FLAG: --machine-concurrency="100" I0722 22:50:05.501086 1 main.go:308] FLAG: --machinedeployment-concurrency="50" I0722 22:50:05.501089 1 main.go:308] FLAG: --machinehealthcheck-concurrency="50" I0722 22:50:05.501093 1 main.go:308] FLAG: --machinepool-concurrency="50" I0722 22:50:05.501096 1 main.go:308] FLAG: --machineset-concurrency="50" I0722 22:50:05.501099 1 main.go:308] FLAG: --machineset-preflight-checks="[All]" I0722 22:50:05.501103 1 main.go:308] FLAG: --namespace="" I0722 22:50:05.501106 1 main.go:308] FLAG: --profiler-address="" I0722 22:50:05.501109 1 main.go:308] FLAG: --remote-conditions-grace-period="5m0s" I0722 22:50:05.501113 1 main.go:308] FLAG: --remote-connection-grace-period="50s" I0722 22:50:05.501116 1 main.go:308] FLAG: --runtime-extension-client-cert-file="" I0722 22:50:05.501119 1 main.go:308] FLAG: --runtime-extension-client-key-file="" I0722 22:50:05.501121 1 main.go:308] FLAG: --skip-crd-migration-phases="[]" I0722 22:50:05.501124 1 main.go:308] FLAG: --sync-period="10m0s" I0722 22:50:05.501127 1 main.go:308] FLAG: --tls-cipher-suites="[]" I0722 22:50:05.501132 1 main.go:308] FLAG: --tls-min-version="VersionTLS12" I0722 22:50:05.501134 1 main.go:308] FLAG: --v="2" I0722 22:50:05.501138 1 main.go:308] FLAG: --vmodule="" I0722 22:50:05.501141 1 main.go:308] FLAG: --watch-filter="" I0722 22:50:05.501144 1 main.go:308] FLAG: --webhook-cert-dir="/tmp/k8s-webhook-server/serving-certs/" I0722 22:50:05.501147 1 main.go:308] FLAG: --webhook-cert-name="tls.crt" I0722 22:50:05.501150 1 main.go:308] FLAG: --webhook-key-name="tls.key" I0722 22:50:05.501152 1 main.go:308] FLAG: --webhook-port="9443" I0722 22:50:05.501195 1 main.go:315] "Version: v1.13.2 (git commit: ebd807c66351f3ad96ca99b68d2219305d794640)" logger="setup" I0722 22:50:05.501502 1 envvar.go:172] "Feature gate default state" feature="InOrderInformers" enabled=true I0722 22:50:05.501532 1 envvar.go:172] "Feature gate default state" feature="InOrderInformersBatchProcess" enabled=true I0722 22:50:05.501543 1 envvar.go:172] "Feature gate default state" feature="InformerResourceVersion" enabled=true I0722 22:50:05.501550 1 envvar.go:172] "Feature gate default state" feature="WatchListClient" enabled=true I0722 22:50:05.501555 1 envvar.go:172] "Feature gate default state" feature="ClientsAllowCBOR" enabled=false I0722 22:50:05.501564 1 envvar.go:172] "Feature gate default state" feature="ClientsPreferCBOR" enabled=false I0722 22:50:05.525967 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" I0722 22:50:05.527483 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" I0722 22:50:05.527613 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-clusterclass" I0722 22:50:05.527838 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/convert" I0722 22:50:05.527860 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=ClusterClass" I0722 22:50:05.527940 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" I0722 22:50:05.528030 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-cluster" I0722 22:50:05.528077 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" I0722 22:50:05.528167 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-cluster" I0722 22:50:05.528245 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=Cluster" I0722 22:50:05.528303 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" I0722 22:50:05.528381 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machine" I0722 22:50:05.528424 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" I0722 22:50:05.528500 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machine" I0722 22:50:05.528562 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=Machine" I0722 22:50:05.528636 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" I0722 22:50:05.528706 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machineset" I0722 22:50:05.528754 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" I0722 22:50:05.528828 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machineset" I0722 22:50:05.529244 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineSet" I0722 22:50:05.529332 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" I0722 22:50:05.529422 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machinedeployment" I0722 22:50:05.529470 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" I0722 22:50:05.529537 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinedeployment" I0722 22:50:05.529600 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineDeployment" I0722 22:50:05.529676 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" I0722 22:50:05.529749 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinedrainrule" I0722 22:50:05.529803 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineDrainRule" I0722 22:50:05.529871 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" I0722 22:50:05.529943 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machinepool" I0722 22:50:05.530095 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" I0722 22:50:05.530294 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinepool" I0722 22:50:05.530358 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachinePool" I0722 22:50:05.530412 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" I0722 22:50:05.530496 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-addons-cluster-x-k8s-io-v1beta2-clusterresourceset" I0722 22:50:05.530574 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" I0722 22:50:05.530664 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-addons-cluster-x-k8s-io-v1beta2-clusterresourceset" I0722 22:50:05.530719 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="addons.cluster.x-k8s.io/v1beta2, Kind=ClusterResourceSet" I0722 22:50:05.530773 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" I0722 22:50:05.530850 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-addons-cluster-x-k8s-io-v1beta2-clusterresourcesetbinding" I0722 22:50:05.530912 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="addons.cluster.x-k8s.io/v1beta2, Kind=ClusterResourceSetBinding" I0722 22:50:05.530963 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" I0722 22:50:05.531032 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machinehealthcheck" I0722 22:50:05.531059 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" I0722 22:50:05.531158 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinehealthcheck" I0722 22:50:05.531193 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineHealthCheck" I0722 22:50:05.531226 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" I0722 22:50:05.531270 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-runtime-cluster-x-k8s-io-v1beta2-extensionconfig" I0722 22:50:05.531300 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" I0722 22:50:05.531344 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-runtime-cluster-x-k8s-io-v1beta2-extensionconfig" I0722 22:50:05.531382 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="runtime.cluster.x-k8s.io/v1beta2, Kind=ExtensionConfig" I0722 22:50:05.531414 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" I0722 22:50:05.531465 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-ipam-cluster-x-k8s-io-v1beta2-ipaddress" I0722 22:50:05.531499 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="ipam.cluster.x-k8s.io/v1beta2, Kind=IPAddress" I0722 22:50:05.531530 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" I0722 22:50:05.531579 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-ipam-cluster-x-k8s-io-v1beta2-ipaddressclaim" I0722 22:50:05.531614 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="ipam.cluster.x-k8s.io/v1beta2, Kind=IPAddressClaim" I0722 22:50:05.531628 1 main.go:400] "Starting manager" logger="setup" version="v1.13.2" I0722 22:50:05.531697 1 server.go:208] "Starting metrics server" logger="controller-runtime.metrics" I0722 22:50:05.531764 1 server.go:83] "starting server" name="health probe" addr="[::]:9440" I0722 22:50:05.531908 1 server.go:191] "Starting webhook server" logger="controller-runtime.webhook" I0722 22:50:05.532199 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" I0722 22:50:05.532286 1 server.go:242] "Serving webhook server" logger="controller-runtime.webhook" host="" port=9443 I0722 22:50:05.532629 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" I0722 22:50:05.536237 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" I0722 22:50:05.536740 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" I0722 22:50:05.537154 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" I0722 22:50:05.633618 1 leaderelection.go:258] "Attempting to acquire leader lease..." lock="capi-system/controller-leader-election-capi" I0722 22:50:05.898388 1 server.go:247] "Serving metrics server" logger="controller-runtime.metrics" bindAddress=":8443" secure=true I0722 22:50:29.663565 1 leaderelection.go:272] "Successfully acquired lease" lock="capi-system/controller-leader-election-capi" I0722 22:50:29.663690 1 recorder.go:116] "capi-controller-manager-5647546676-dn9q6_fd9eefd5-0aaa-4f57-8623-79f70e217549 became leader" logger="events" type="Normal" object={"kind":"Lease","namespace":"capi-system","name":"controller-leader-election-capi","uid":"114b50c0-0c76-48b0-86fb-05acc617c8ec","apiVersion":"coordination.k8s.io/v1","resourceVersion":"22473"} reason="LeaderElection" I0722 22:50:29.663966 1 controller.go:370] "Starting EventSource" controller="clustercache" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Cluster" I0722 22:50:29.664001 1 controller.go:370] "Starting EventSource" controller="crdmigrator" controllerGroup="apiextensions.k8s.io" controllerKind="CustomResourceDefinition" source="kind source: *v1.PartialObjectMetadata" I0722 22:50:29.664007 1 controller.go:370] "Starting EventSource" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.Cluster" I0722 22:50:29.664194 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="channel source: 0xc00024ca80" I0722 22:50:29.664242 1 controller.go:370] "Starting EventSource" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Cluster" I0722 22:50:29.664254 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="channel source: 0xc0002521c0" I0722 22:50:29.664296 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Cluster" I0722 22:50:29.664320 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Machine" I0722 22:50:29.664380 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachineDeployment" I0722 22:50:29.664398 1 controller.go:370] "Starting EventSource" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" source="kind source: *v1beta2.ExtensionConfig" I0722 22:50:29.664413 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachinePool" I0722 22:50:29.664426 1 controller.go:370] "Starting EventSource" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.MachineSet" I0722 22:50:29.664507 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachinePool" I0722 22:50:29.664527 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="channel source: 0xc00024dc70" I0722 22:50:29.664578 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="channel source: 0xc00024d2d0" I0722 22:50:29.664619 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Cluster" I0722 22:50:29.664637 1 controller.go:370] "Starting EventSource" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" source="kind source: *v1beta2.ClusterClass" I0722 22:50:29.664646 1 controller.go:370] "Starting EventSource" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" source="channel source: 0xc000252700" I0722 22:50:29.664682 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.MachineSet" I0722 22:50:29.664682 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.ClusterClass" I0722 22:50:29.664705 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Machine" I0722 22:50:29.664726 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Machine" I0722 22:50:29.664737 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.MachineSet" I0722 22:50:29.664746 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.MachineDeployment" I0722 22:50:29.664799 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.MachineDeployment" I0722 22:50:29.664838 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Cluster" I0722 22:50:29.664875 1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.Cluster" I0722 22:50:29.664258 1 controller.go:370] "Starting EventSource" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineDeployment" I0722 22:50:29.664961 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.Machine" I0722 22:50:29.664985 1 controller.go:370] "Starting EventSource" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" source="kind source: *v1beta2.MachinePool" I0722 22:50:29.665020 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.Cluster" I0722 22:50:29.664969 1 controller.go:370] "Starting EventSource" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" source="kind source: *v1beta2.Cluster" I0722 22:50:29.665069 1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineDeployment" I0722 22:50:29.665104 1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineSet" I0722 22:50:29.665129 1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineSet" I0722 22:50:29.665051 1 controller.go:370] "Starting EventSource" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" source="kind source: *v1beta2.Cluster" I0722 22:50:29.665221 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1.PartialObjectMetadata" I0722 22:50:29.665290 1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="channel source: 0xc0002527e0" I0722 22:50:29.665336 1 controller.go:370] "Starting EventSource" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" source="kind source: *v1beta2.ClusterResourceSetBinding" I0722 22:50:29.664644 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachineDeployment" I0722 22:50:29.665364 1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="kind source: *v1beta2.MachineHealthCheck" I0722 22:50:29.665386 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1beta2.ClusterResourceSet" I0722 22:50:29.665405 1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="kind source: *v1beta2.Machine" I0722 22:50:29.665421 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1.PartialObjectMetadata" I0722 22:50:29.665445 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="channel source: 0xc000252770" I0722 22:50:29.665450 1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="kind source: *v1beta2.Cluster" I0722 22:50:29.665407 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1beta2.Cluster" I0722 22:50:29.673226 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" I0722 22:50:29.673770 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" I0722 22:50:29.674826 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" I0722 22:50:29.675118 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" I0722 22:50:29.675829 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" I0722 22:50:29.677692 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" I0722 22:50:29.678904 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" I0722 22:50:29.678994 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" I0722 22:50:29.681081 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" I0722 22:50:29.681624 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" I0722 22:50:29.767015 1 controller.go:303] "Starting Controller" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" I0722 22:50:29.767042 1 controller.go:303] "Starting Controller" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" I0722 22:50:29.767094 1 controller.go:303] "Starting Controller" controller="clustercache" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" I0722 22:50:29.767060 1 controller.go:303] "Starting Controller" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" I0722 22:50:29.767120 1 controller.go:306] "Starting workers" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" worker count=1 I0722 22:50:29.767026 1 controller.go:303] "Starting Controller" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" I0722 22:50:29.767146 1 controller.go:306] "Starting workers" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" worker count=10 I0722 22:50:29.767100 1 controller.go:303] "Starting Controller" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" I0722 22:50:29.767208 1 controller.go:306] "Starting workers" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" worker count=50 I0722 22:50:29.767115 1 controller.go:306] "Starting workers" controller="clustercache" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" worker count=100 I0722 22:50:29.767121 1 controller.go:303] "Starting Controller" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" I0722 22:50:29.767321 1 controller.go:306] "Starting workers" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" worker count=50 I0722 22:50:29.767053 1 controller.go:306] "Starting workers" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" worker count=100 I0722 22:50:29.767093 1 controller.go:306] "Starting workers" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" worker count=50 I0722 22:50:29.771074 1 controller.go:303] "Starting Controller" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" I0722 22:50:29.771108 1 controller.go:306] "Starting workers" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" worker count=1 I0722 22:50:29.771161 1 controller.go:303] "Starting Controller" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" I0722 22:50:29.771175 1 controller.go:306] "Starting workers" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" worker count=50 I0722 22:50:29.771273 1 controller.go:303] "Starting Controller" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" I0722 22:50:29.771287 1 controller.go:306] "Starting workers" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" worker count=50 I0722 22:50:29.771917 1 controller.go:303] "Starting Controller" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" I0722 22:50:29.771935 1 controller.go:306] "Starting workers" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" worker count=10 I0722 22:50:29.772118 1 controller.go:303] "Starting Controller" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" I0722 22:50:29.772137 1 controller.go:306] "Starting workers" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" worker count=50 I0722 22:50:29.867589 1 controller.go:303] "Starting Controller" controller="crdmigrator" controllerGroup="apiextensions.k8s.io" controllerKind="CustomResourceDefinition" I0722 22:50:29.867635 1 controller.go:306] "Starting workers" controller="crdmigrator" controllerGroup="apiextensions.k8s.io" controllerKind="CustomResourceDefinition" worker count=1 I0722 22:50:29.869876 1 controller.go:303] "Starting Controller" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" I0722 22:50:29.869912 1 controller.go:306] "Starting workers" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" worker count=10 E0722 22:54:16.576576 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" E0722 22:54:21.576973 1 leaderelection.go:452] "Error retrieving lease lock" err="Get \"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" E0722 22:54:21.577123 1 leaderelection.go:445] "Failed to update lease optimistically, falling back to slow path" err="client rate limiter Wait returned an error: rate: Wait(n=1) would exceed context deadline" lock="capi-system/controller-leader-election-capi" E0722 22:54:21.577183 1 leaderelection.go:452] "Error retrieving lease lock" err="client rate limiter Wait returned an error: rate: Wait(n=1) would exceed context deadline" lock="capi-system/controller-leader-election-capi" I0722 22:54:21.577735 1 leaderelection.go:299] "Failed to renew lease" lock="capi-system/controller-leader-election-capi" err="context deadline exceeded" E0722 22:54:21.577829 1 main.go:402] "Problem running manager" err="leader election lost" logger="setup"