W0628 20:46:11.483733 1 feature_gate.go:410] Setting GA feature gate MachineWaitForVolumeDetachConsiderVolumeAttachments=true. It will be removed in a future release. I0628 20:46:11.483869 1 main.go:308] FLAG: --additional-sync-machine-annotations="[]" I0628 20:46:11.483878 1 main.go:308] FLAG: --additional-sync-machine-labels="[]" I0628 20:46:11.483883 1 main.go:308] FLAG: --cluster-concurrency="50" I0628 20:46:11.483887 1 main.go:308] FLAG: --clustercache-client-burst="30" I0628 20:46:11.483890 1 main.go:308] FLAG: --clustercache-client-qps="20" I0628 20:46:11.483895 1 main.go:308] FLAG: --clustercache-concurrency="100" I0628 20:46:11.483898 1 main.go:308] FLAG: --clusterclass-concurrency="10" I0628 20:46:11.483901 1 main.go:308] FLAG: --clusterresourceset-concurrency="10" I0628 20:46:11.483904 1 main.go:308] FLAG: --clustertopology-concurrency="50" I0628 20:46:11.483908 1 main.go:308] FLAG: --contention-profiling="false" I0628 20:46:11.483912 1 main.go:308] FLAG: --diagnostics-address=":8443" I0628 20:46:11.483916 1 main.go:308] FLAG: --extensionconfig-concurrency="10" I0628 20:46:11.483919 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" I0628 20:46:11.483941 1 main.go:308] FLAG: --health-addr=":9440" I0628 20:46:11.483946 1 main.go:308] FLAG: --insecure-diagnostics="false" I0628 20:46:11.483949 1 main.go:308] FLAG: --kube-api-burst="200" I0628 20:46:11.483953 1 main.go:308] FLAG: --kube-api-qps="100" I0628 20:46:11.483957 1 main.go:308] FLAG: --kubeconfig="" I0628 20:46:11.483961 1 main.go:308] FLAG: --leader-elect="true" I0628 20:46:11.483964 1 main.go:308] FLAG: --leader-elect-lease-duration="15s" I0628 20:46:11.483969 1 main.go:308] FLAG: --leader-elect-renew-deadline="10s" I0628 20:46:11.483973 1 main.go:308] FLAG: --leader-elect-retry-period="2s" I0628 20:46:11.483976 1 main.go:308] FLAG: --log-flush-frequency="5s" I0628 20:46:11.483979 1 main.go:308] FLAG: --log-json-info-buffer-size="0" I0628 20:46:11.483994 1 main.go:308] FLAG: --log-json-split-stream="false" I0628 20:46:11.483997 1 main.go:308] FLAG: --log-text-info-buffer-size="0" I0628 20:46:11.484000 1 main.go:308] FLAG: --log-text-split-stream="false" I0628 20:46:11.484003 1 main.go:308] FLAG: --logging-format="text" I0628 20:46:11.484006 1 main.go:308] FLAG: --machine-concurrency="100" I0628 20:46:11.484009 1 main.go:308] FLAG: --machinedeployment-concurrency="50" I0628 20:46:11.484012 1 main.go:308] FLAG: --machinehealthcheck-concurrency="50" I0628 20:46:11.484015 1 main.go:308] FLAG: --machinepool-concurrency="50" I0628 20:46:11.484018 1 main.go:308] FLAG: --machineset-concurrency="50" I0628 20:46:11.484021 1 main.go:308] FLAG: --machineset-preflight-checks="[All]" I0628 20:46:11.484026 1 main.go:308] FLAG: --namespace="" I0628 20:46:11.484029 1 main.go:308] FLAG: --profiler-address="" I0628 20:46:11.484031 1 main.go:308] FLAG: --remote-conditions-grace-period="5m0s" I0628 20:46:11.484035 1 main.go:308] FLAG: --remote-connection-grace-period="50s" I0628 20:46:11.484038 1 main.go:308] FLAG: --runtime-extension-client-cert-file="" I0628 20:46:11.484041 1 main.go:308] FLAG: --runtime-extension-client-key-file="" I0628 20:46:11.484044 1 main.go:308] FLAG: --skip-crd-migration-phases="[]" I0628 20:46:11.484048 1 main.go:308] FLAG: --sync-period="10m0s" I0628 20:46:11.484052 1 main.go:308] FLAG: --tls-cipher-suites="[]" I0628 20:46:11.484055 1 main.go:308] FLAG: --tls-min-version="VersionTLS12" I0628 20:46:11.484058 1 main.go:308] FLAG: --v="2" I0628 20:46:11.484063 1 main.go:308] FLAG: --vmodule="" I0628 20:46:11.484067 1 main.go:308] FLAG: --watch-filter="" I0628 20:46:11.484071 1 main.go:308] FLAG: --webhook-cert-dir="/tmp/k8s-webhook-server/serving-certs/" I0628 20:46:11.484082 1 main.go:308] FLAG: --webhook-cert-name="tls.crt" I0628 20:46:11.484085 1 main.go:308] FLAG: --webhook-key-name="tls.key" I0628 20:46:11.484088 1 main.go:308] FLAG: --webhook-port="9443" I0628 20:46:11.484125 1 main.go:315] "Version: v1.13.2 (git commit: ebd807c66351f3ad96ca99b68d2219305d794640)" logger="setup" I0628 20:46:11.484436 1 envvar.go:172] "Feature gate default state" feature="InOrderInformers" enabled=true I0628 20:46:11.484451 1 envvar.go:172] "Feature gate default state" feature="InOrderInformersBatchProcess" enabled=true I0628 20:46:11.484457 1 envvar.go:172] "Feature gate default state" feature="InformerResourceVersion" enabled=true I0628 20:46:11.484461 1 envvar.go:172] "Feature gate default state" feature="WatchListClient" enabled=true I0628 20:46:11.484467 1 envvar.go:172] "Feature gate default state" feature="ClientsAllowCBOR" enabled=false I0628 20:46:11.484473 1 envvar.go:172] "Feature gate default state" feature="ClientsPreferCBOR" enabled=false I0628 20:46:11.519938 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" I0628 20:46:11.521053 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" I0628 20:46:11.521265 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-clusterclass" I0628 20:46:11.521930 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/convert" I0628 20:46:11.521946 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=ClusterClass" I0628 20:46:11.522015 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" I0628 20:46:11.522099 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-cluster" I0628 20:46:11.522161 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" I0628 20:46:11.522243 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-cluster" I0628 20:46:11.522293 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=Cluster" I0628 20:46:11.522353 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" I0628 20:46:11.522430 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machine" I0628 20:46:11.522482 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" I0628 20:46:11.522556 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machine" I0628 20:46:11.522601 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=Machine" I0628 20:46:11.522667 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" I0628 20:46:11.522745 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machineset" I0628 20:46:11.522777 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" I0628 20:46:11.522854 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machineset" I0628 20:46:11.522922 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineSet" I0628 20:46:11.522993 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" I0628 20:46:11.523070 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machinedeployment" I0628 20:46:11.523108 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" I0628 20:46:11.523177 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinedeployment" I0628 20:46:11.523240 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineDeployment" I0628 20:46:11.523273 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" I0628 20:46:11.523353 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinedrainrule" I0628 20:46:11.523412 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineDrainRule" I0628 20:46:11.523482 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" I0628 20:46:11.523559 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machinepool" I0628 20:46:11.523589 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" I0628 20:46:11.523654 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinepool" I0628 20:46:11.523710 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachinePool" I0628 20:46:11.523747 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" I0628 20:46:11.523824 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-addons-cluster-x-k8s-io-v1beta2-clusterresourceset" I0628 20:46:11.523856 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" I0628 20:46:11.523938 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-addons-cluster-x-k8s-io-v1beta2-clusterresourceset" I0628 20:46:11.523995 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="addons.cluster.x-k8s.io/v1beta2, Kind=ClusterResourceSet" I0628 20:46:11.524049 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" I0628 20:46:11.524129 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-addons-cluster-x-k8s-io-v1beta2-clusterresourcesetbinding" I0628 20:46:11.524172 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="addons.cluster.x-k8s.io/v1beta2, Kind=ClusterResourceSetBinding" I0628 20:46:11.524223 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" I0628 20:46:11.524300 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machinehealthcheck" I0628 20:46:11.524334 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" I0628 20:46:11.524410 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinehealthcheck" I0628 20:46:11.524469 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineHealthCheck" I0628 20:46:11.524570 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" I0628 20:46:11.524650 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-runtime-cluster-x-k8s-io-v1beta2-extensionconfig" I0628 20:46:11.524703 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" I0628 20:46:11.524777 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-runtime-cluster-x-k8s-io-v1beta2-extensionconfig" I0628 20:46:11.524822 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="runtime.cluster.x-k8s.io/v1beta2, Kind=ExtensionConfig" I0628 20:46:11.524876 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" I0628 20:46:11.524960 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-ipam-cluster-x-k8s-io-v1beta2-ipaddress" I0628 20:46:11.525020 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="ipam.cluster.x-k8s.io/v1beta2, Kind=IPAddress" I0628 20:46:11.525061 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" I0628 20:46:11.525138 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-ipam-cluster-x-k8s-io-v1beta2-ipaddressclaim" I0628 20:46:11.525302 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="ipam.cluster.x-k8s.io/v1beta2, Kind=IPAddressClaim" I0628 20:46:11.525321 1 main.go:400] "Starting manager" logger="setup" version="v1.13.2" I0628 20:46:11.525395 1 server.go:208] "Starting metrics server" logger="controller-runtime.metrics" I0628 20:46:11.525527 1 server.go:83] "starting server" name="health probe" addr="[::]:9440" I0628 20:46:11.525642 1 server.go:191] "Starting webhook server" logger="controller-runtime.webhook" I0628 20:46:11.526790 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" I0628 20:46:11.526931 1 server.go:242] "Serving webhook server" logger="controller-runtime.webhook" host="" port=9443 I0628 20:46:11.527269 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" I0628 20:46:11.530686 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" I0628 20:46:11.531988 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" I0628 20:46:11.532294 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" I0628 20:46:11.629240 1 leaderelection.go:258] "Attempting to acquire leader lease..." lock="capi-system/controller-leader-election-capi" I0628 20:46:11.638114 1 server.go:247] "Serving metrics server" logger="controller-runtime.metrics" bindAddress=":8443" secure=true I0628 20:46:26.923655 1 leaderelection.go:272] "Successfully acquired lease" lock="capi-system/controller-leader-election-capi" I0628 20:46:26.924131 1 controller.go:370] "Starting EventSource" controller="clustercache" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Cluster" I0628 20:46:26.924202 1 controller.go:303] "Starting Controller" controller="clustercache" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" I0628 20:46:26.924208 1 controller.go:306] "Starting workers" controller="clustercache" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" worker count=100 I0628 20:46:26.924423 1 recorder.go:116] "capi-controller-manager-5647546676-9zgkz_ec5043e7-2d9c-40ef-9741-e73a75412306 became leader" logger="events" type="Normal" object={"kind":"Lease","namespace":"capi-system","name":"controller-leader-election-capi","uid":"b8dccaa1-6825-452d-b494-d21503e9f833","apiVersion":"coordination.k8s.io/v1","resourceVersion":"24929"} reason="LeaderElection" I0628 20:46:26.924494 1 controller.go:370] "Starting EventSource" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" source="kind source: *v1beta2.ExtensionConfig" I0628 20:46:26.924922 1 controller.go:370] "Starting EventSource" controller="crdmigrator" controllerGroup="apiextensions.k8s.io" controllerKind="CustomResourceDefinition" source="kind source: *v1.PartialObjectMetadata" I0628 20:46:26.925073 1 controller.go:370] "Starting EventSource" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.Cluster" I0628 20:46:26.925190 1 controller.go:370] "Starting EventSource" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" source="kind source: *v1beta2.ClusterClass" I0628 20:46:26.925317 1 controller.go:370] "Starting EventSource" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineDeployment" I0628 20:46:26.925475 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="channel source: 0xc0003ce620" I0628 20:46:26.925570 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="channel source: 0xc0003ce070" I0628 20:46:26.925630 1 controller.go:370] "Starting EventSource" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Cluster" I0628 20:46:26.925763 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="channel source: 0xc0003cf420" I0628 20:46:26.925792 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Cluster" I0628 20:46:26.925846 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Machine" I0628 20:46:26.925882 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachineDeployment" I0628 20:46:26.925925 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachinePool" I0628 20:46:26.926001 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Cluster" I0628 20:46:26.926043 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.ClusterClass" I0628 20:46:26.926070 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachineDeployment" I0628 20:46:26.926090 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.MachineSet" I0628 20:46:26.926118 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Machine" I0628 20:46:26.926122 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachinePool" I0628 20:46:26.926160 1 controller.go:370] "Starting EventSource" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.MachineSet" I0628 20:46:26.926230 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.MachineDeployment" I0628 20:46:26.926264 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Cluster" I0628 20:46:26.926247 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Machine" I0628 20:46:26.926543 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="channel source: 0xc0003cefc0" I0628 20:46:26.926595 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.Machine" I0628 20:46:26.926840 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.Cluster" I0628 20:46:26.926858 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.MachineSet" I0628 20:46:26.926889 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.MachineDeployment" I0628 20:46:26.926991 1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.Cluster" I0628 20:46:26.927021 1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineDeployment" I0628 20:46:26.927063 1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineSet" I0628 20:46:26.927085 1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineSet" I0628 20:46:26.927131 1 controller.go:370] "Starting EventSource" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" source="kind source: *v1beta2.Cluster" I0628 20:46:26.927196 1 controller.go:370] "Starting EventSource" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" source="kind source: *v1beta2.ClusterResourceSetBinding" I0628 20:46:26.927230 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1beta2.ClusterResourceSet" I0628 20:46:26.927245 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1beta2.Cluster" I0628 20:46:26.927278 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1.PartialObjectMetadata" I0628 20:46:26.927296 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="channel source: 0xc0003cfc70" I0628 20:46:26.927199 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1.PartialObjectMetadata" I0628 20:46:26.927382 1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="channel source: 0xc0003cfd50" I0628 20:46:26.927423 1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="kind source: *v1beta2.MachineHealthCheck" I0628 20:46:26.927440 1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="kind source: *v1beta2.Machine" I0628 20:46:26.927464 1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="kind source: *v1beta2.Cluster" I0628 20:46:26.932671 1 controller.go:370] "Starting EventSource" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" source="kind source: *v1beta2.Cluster" I0628 20:46:26.933020 1 controller.go:370] "Starting EventSource" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" source="kind source: *v1beta2.MachinePool" I0628 20:46:26.933041 1 controller.go:370] "Starting EventSource" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" source="channel source: 0xc0003cfc00" I0628 20:46:26.937689 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" I0628 20:46:26.938870 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" I0628 20:46:26.942615 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" I0628 20:46:26.942881 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" I0628 20:46:26.943018 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" I0628 20:46:26.943307 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" I0628 20:46:26.943767 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" I0628 20:46:26.943972 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" I0628 20:46:26.952928 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" I0628 20:46:26.954872 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" I0628 20:46:27.026238 1 controller.go:303] "Starting Controller" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" I0628 20:46:27.026261 1 controller.go:303] "Starting Controller" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" I0628 20:46:27.026283 1 controller.go:306] "Starting workers" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" worker count=1 I0628 20:46:27.026305 1 controller.go:306] "Starting workers" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" worker count=10 I0628 20:46:27.026559 1 controller.go:303] "Starting Controller" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" I0628 20:46:27.026588 1 controller.go:306] "Starting workers" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" worker count=50 I0628 20:46:27.026621 1 controller.go:303] "Starting Controller" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" I0628 20:46:27.026647 1 controller.go:306] "Starting workers" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" worker count=50 I0628 20:46:27.026658 1 controller.go:303] "Starting Controller" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" I0628 20:46:27.026683 1 controller.go:306] "Starting workers" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" worker count=1 I0628 20:46:27.037321 1 controller.go:303] "Starting Controller" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" I0628 20:46:27.037370 1 controller.go:306] "Starting workers" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" worker count=50 I0628 20:46:27.037328 1 controller.go:303] "Starting Controller" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" I0628 20:46:27.037470 1 controller.go:306] "Starting workers" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" worker count=100 I0628 20:46:27.038597 1 controller.go:303] "Starting Controller" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" I0628 20:46:27.038641 1 controller.go:306] "Starting workers" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" worker count=50 I0628 20:46:27.038702 1 controller.go:303] "Starting Controller" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" I0628 20:46:27.038713 1 controller.go:303] "Starting Controller" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" I0628 20:46:27.038726 1 controller.go:306] "Starting workers" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" worker count=50 I0628 20:46:27.038740 1 controller.go:306] "Starting workers" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" worker count=50 I0628 20:46:27.038912 1 controller.go:303] "Starting Controller" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" I0628 20:46:27.038938 1 controller.go:306] "Starting workers" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" worker count=10 I0628 20:46:27.127063 1 controller.go:303] "Starting Controller" controller="crdmigrator" controllerGroup="apiextensions.k8s.io" controllerKind="CustomResourceDefinition" I0628 20:46:27.127116 1 controller.go:306] "Starting workers" controller="crdmigrator" controllerGroup="apiextensions.k8s.io" controllerKind="CustomResourceDefinition" worker count=1 I0628 20:46:27.139319 1 controller.go:303] "Starting Controller" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" I0628 20:46:27.139375 1 controller.go:306] "Starting workers" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" worker count=10 E0628 20:46:37.603724 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" E0628 20:46:42.603516 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\": context deadline exceeded" lock="capi-system/controller-leader-election-capi" I0628 20:46:42.603580 1 leaderelection.go:299] "Failed to renew lease" lock="capi-system/controller-leader-election-capi" err="context deadline exceeded" E0628 20:46:42.603719 1 main.go:402] "Problem running manager" err="leader election lost" logger="setup"