W0731 22:15:03.785970 1 feature_gate.go:410] Setting GA feature gate MachineWaitForVolumeDetachConsiderVolumeAttachments=true. It will be removed in a future release. I0731 22:15:03.786110 1 main.go:308] FLAG: --additional-sync-machine-annotations="[]" I0731 22:15:03.786120 1 main.go:308] FLAG: --additional-sync-machine-labels="[]" I0731 22:15:03.786125 1 main.go:308] FLAG: --cluster-concurrency="50" I0731 22:15:03.786130 1 main.go:308] FLAG: --clustercache-client-burst="30" I0731 22:15:03.786133 1 main.go:308] FLAG: --clustercache-client-qps="20" I0731 22:15:03.786138 1 main.go:308] FLAG: --clustercache-concurrency="100" I0731 22:15:03.786141 1 main.go:308] FLAG: --clusterclass-concurrency="10" I0731 22:15:03.786144 1 main.go:308] FLAG: --clusterresourceset-concurrency="10" I0731 22:15:03.786148 1 main.go:308] FLAG: --clustertopology-concurrency="50" I0731 22:15:03.786151 1 main.go:308] FLAG: --contention-profiling="false" I0731 22:15:03.786160 1 main.go:308] FLAG: --diagnostics-address=":8443" I0731 22:15:03.786165 1 main.go:308] FLAG: --extensionconfig-concurrency="10" I0731 22:15:03.786169 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" I0731 22:15:03.786191 1 main.go:308] FLAG: --health-addr=":9440" I0731 22:15:03.786196 1 main.go:308] FLAG: --insecure-diagnostics="false" I0731 22:15:03.786199 1 main.go:308] FLAG: --kube-api-burst="200" I0731 22:15:03.786202 1 main.go:308] FLAG: --kube-api-qps="100" I0731 22:15:03.786215 1 main.go:308] FLAG: --kubeconfig="" I0731 22:15:03.786227 1 main.go:308] FLAG: --leader-elect="true" I0731 22:15:03.786230 1 main.go:308] FLAG: --leader-elect-lease-duration="15s" I0731 22:15:03.786235 1 main.go:308] FLAG: --leader-elect-renew-deadline="10s" I0731 22:15:03.786238 1 main.go:308] FLAG: --leader-elect-retry-period="2s" I0731 22:15:03.786241 1 main.go:308] FLAG: --log-flush-frequency="5s" I0731 22:15:03.786244 1 main.go:308] FLAG: --log-json-info-buffer-size="0" I0731 22:15:03.786254 1 main.go:308] FLAG: --log-json-split-stream="false" I0731 22:15:03.786258 1 main.go:308] FLAG: --log-text-info-buffer-size="0" I0731 22:15:03.786261 1 main.go:308] FLAG: --log-text-split-stream="false" I0731 22:15:03.786265 1 main.go:308] FLAG: --logging-format="text" I0731 22:15:03.786268 1 main.go:308] FLAG: --machine-concurrency="100" I0731 22:15:03.786271 1 main.go:308] FLAG: --machinedeployment-concurrency="50" I0731 22:15:03.786275 1 main.go:308] FLAG: --machinehealthcheck-concurrency="50" I0731 22:15:03.786277 1 main.go:308] FLAG: --machinepool-concurrency="50" I0731 22:15:03.786281 1 main.go:308] FLAG: --machineset-concurrency="50" I0731 22:15:03.786288 1 main.go:308] FLAG: --machineset-preflight-checks="[All]" I0731 22:15:03.786295 1 main.go:308] FLAG: --namespace="" I0731 22:15:03.786298 1 main.go:308] FLAG: --profiler-address="" I0731 22:15:03.786301 1 main.go:308] FLAG: --remote-conditions-grace-period="5m0s" I0731 22:15:03.786304 1 main.go:308] FLAG: --remote-connection-grace-period="50s" I0731 22:15:03.786307 1 main.go:308] FLAG: --runtime-extension-client-cert-file="" I0731 22:15:03.786310 1 main.go:308] FLAG: --runtime-extension-client-key-file="" I0731 22:15:03.786313 1 main.go:308] FLAG: --skip-crd-migration-phases="[]" I0731 22:15:03.786319 1 main.go:308] FLAG: --sync-period="10m0s" I0731 22:15:03.786322 1 main.go:308] FLAG: --tls-cipher-suites="[]" I0731 22:15:03.786328 1 main.go:308] FLAG: --tls-min-version="VersionTLS12" I0731 22:15:03.786331 1 main.go:308] FLAG: --v="2" I0731 22:15:03.786339 1 main.go:308] FLAG: --vmodule="" I0731 22:15:03.786344 1 main.go:308] FLAG: --watch-filter="" I0731 22:15:03.786347 1 main.go:308] FLAG: --webhook-cert-dir="/tmp/k8s-webhook-server/serving-certs/" I0731 22:15:03.786350 1 main.go:308] FLAG: --webhook-cert-name="tls.crt" I0731 22:15:03.786353 1 main.go:308] FLAG: --webhook-key-name="tls.key" I0731 22:15:03.786359 1 main.go:308] FLAG: --webhook-port="9443" I0731 22:15:03.786429 1 main.go:315] "Version: v1.13.2 (git commit: ebd807c66351f3ad96ca99b68d2219305d794640)" logger="setup" I0731 22:15:03.786773 1 envvar.go:172] "Feature gate default state" feature="InOrderInformersBatchProcess" enabled=true I0731 22:15:03.786791 1 envvar.go:172] "Feature gate default state" feature="InformerResourceVersion" enabled=true I0731 22:15:03.786797 1 envvar.go:172] "Feature gate default state" feature="WatchListClient" enabled=true I0731 22:15:03.786801 1 envvar.go:172] "Feature gate default state" feature="ClientsAllowCBOR" enabled=false I0731 22:15:03.786806 1 envvar.go:172] "Feature gate default state" feature="ClientsPreferCBOR" enabled=false I0731 22:15:03.786810 1 envvar.go:172] "Feature gate default state" feature="InOrderInformers" enabled=true I0731 22:15:03.810311 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" I0731 22:15:03.811940 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" I0731 22:15:03.812104 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-clusterclass" I0731 22:15:03.812278 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/convert" I0731 22:15:03.812296 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=ClusterClass" I0731 22:15:03.812387 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" I0731 22:15:03.812467 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-cluster" I0731 22:15:03.812523 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" I0731 22:15:03.812603 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-cluster" I0731 22:15:03.812660 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=Cluster" I0731 22:15:03.812768 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" I0731 22:15:03.812851 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machine" I0731 22:15:03.812908 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" I0731 22:15:03.812991 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machine" I0731 22:15:03.813058 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=Machine" I0731 22:15:03.813138 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" I0731 22:15:03.813218 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machineset" I0731 22:15:03.813260 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" I0731 22:15:03.813351 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machineset" I0731 22:15:03.813412 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineSet" I0731 22:15:03.813979 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" I0731 22:15:03.814217 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machinedeployment" I0731 22:15:03.814398 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" I0731 22:15:03.814590 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinedeployment" I0731 22:15:03.814773 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineDeployment" I0731 22:15:03.814887 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" I0731 22:15:03.815124 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinedrainrule" I0731 22:15:03.815289 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineDrainRule" I0731 22:15:03.815536 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" I0731 22:15:03.815949 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machinepool" I0731 22:15:03.816442 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" I0731 22:15:03.816555 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinepool" I0731 22:15:03.816631 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachinePool" I0731 22:15:03.816680 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" I0731 22:15:03.816794 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-addons-cluster-x-k8s-io-v1beta2-clusterresourceset" I0731 22:15:03.816829 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" I0731 22:15:03.817038 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-addons-cluster-x-k8s-io-v1beta2-clusterresourceset" I0731 22:15:03.817081 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="addons.cluster.x-k8s.io/v1beta2, Kind=ClusterResourceSet" I0731 22:15:03.817162 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" I0731 22:15:03.817303 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-addons-cluster-x-k8s-io-v1beta2-clusterresourcesetbinding" I0731 22:15:03.817348 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="addons.cluster.x-k8s.io/v1beta2, Kind=ClusterResourceSetBinding" I0731 22:15:03.817389 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" I0731 22:15:03.817582 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-cluster-x-k8s-io-v1beta2-machinehealthcheck" I0731 22:15:03.817622 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" I0731 22:15:03.817676 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-cluster-x-k8s-io-v1beta2-machinehealthcheck" I0731 22:15:03.817743 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="cluster.x-k8s.io/v1beta2, Kind=MachineHealthCheck" I0731 22:15:03.817806 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" I0731 22:15:03.817985 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/mutate-runtime-cluster-x-k8s-io-v1beta2-extensionconfig" I0731 22:15:03.818114 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" I0731 22:15:03.818248 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-runtime-cluster-x-k8s-io-v1beta2-extensionconfig" I0731 22:15:03.818298 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="runtime.cluster.x-k8s.io/v1beta2, Kind=ExtensionConfig" I0731 22:15:03.818374 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" I0731 22:15:03.818528 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-ipam-cluster-x-k8s-io-v1beta2-ipaddress" I0731 22:15:03.818653 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="ipam.cluster.x-k8s.io/v1beta2, Kind=IPAddress" I0731 22:15:03.818752 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" I0731 22:15:03.818817 1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-ipam-cluster-x-k8s-io-v1beta2-ipaddressclaim" I0731 22:15:03.818924 1 webhook.go:344] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="ipam.cluster.x-k8s.io/v1beta2, Kind=IPAddressClaim" I0731 22:15:03.819024 1 main.go:400] "Starting manager" logger="setup" version="v1.13.2" I0731 22:15:03.819183 1 server.go:208] "Starting metrics server" logger="controller-runtime.metrics" I0731 22:15:03.819264 1 server.go:83] "starting server" name="health probe" addr="[::]:9440" I0731 22:15:03.819447 1 server.go:191] "Starting webhook server" logger="controller-runtime.webhook" I0731 22:15:03.820056 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" I0731 22:15:03.820195 1 server.go:242] "Serving webhook server" logger="controller-runtime.webhook" host="" port=9443 I0731 22:15:03.820320 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" I0731 22:15:03.824229 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" I0731 22:15:03.824284 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" I0731 22:15:03.824773 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" I0731 22:15:03.909837 1 server.go:247] "Serving metrics server" logger="controller-runtime.metrics" bindAddress=":8443" secure=true I0731 22:15:03.920036 1 leaderelection.go:258] "Attempting to acquire leader lease..." lock="capi-system/controller-leader-election-capi" I0731 22:15:31.821581 1 leaderelection.go:272] "Successfully acquired lease" lock="capi-system/controller-leader-election-capi" I0731 22:15:31.821644 1 recorder.go:116] "capi-controller-manager-5647546676-4mrjs_76428b86-43ef-41d7-a184-0c8802836e37 became leader" logger="events" type="Normal" object={"kind":"Lease","namespace":"capi-system","name":"controller-leader-election-capi","uid":"1b95d3a0-0615-4f1a-aaa6-ac072a0feed3","apiVersion":"coordination.k8s.io/v1","resourceVersion":"24216"} reason="LeaderElection" I0731 22:15:31.822925 1 controller.go:370] "Starting EventSource" controller="clustercache" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Cluster" I0731 22:15:31.824374 1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.Cluster" I0731 22:15:31.824492 1 controller.go:303] "Starting Controller" controller="clustercache" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" I0731 22:15:31.824633 1 controller.go:306] "Starting workers" controller="clustercache" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" worker count=100 I0731 22:15:31.824742 1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineDeployment" I0731 22:15:31.824930 1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineSet" I0731 22:15:31.824971 1 controller.go:370] "Starting EventSource" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineSet" I0731 22:15:31.825038 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="channel source: 0xc00057c7e0" I0731 22:15:31.825142 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachineDeployment" I0731 22:15:31.825137 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="channel source: 0xc00057cc40" I0731 22:15:31.825141 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.ClusterClass" I0731 22:15:31.825219 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Cluster" I0731 22:15:31.825232 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Cluster" I0731 22:15:31.825260 1 controller.go:370] "Starting EventSource" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachinePool" I0731 22:15:31.825324 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.Machine" I0731 22:15:31.825356 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachineDeployment" I0731 22:15:31.825590 1 controller.go:370] "Starting EventSource" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" source="kind source: *v1beta2.MachinePool" I0731 22:15:31.825954 1 controller.go:370] "Starting EventSource" controller="crdmigrator" controllerGroup="apiextensions.k8s.io" controllerKind="CustomResourceDefinition" source="kind source: *v1.PartialObjectMetadata" I0731 22:15:31.826021 1 controller.go:370] "Starting EventSource" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.Cluster" I0731 22:15:31.826251 1 controller.go:370] "Starting EventSource" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Cluster" I0731 22:15:31.829233 1 controller.go:370] "Starting EventSource" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.MachineSet" I0731 22:15:31.829327 1 controller.go:370] "Starting EventSource" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" source="kind source: *v1beta2.MachineDeployment" I0731 22:15:31.829541 1 controller.go:370] "Starting EventSource" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" source="kind source: *v1beta2.ExtensionConfig" I0731 22:15:31.829609 1 controller.go:370] "Starting EventSource" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" source="kind source: *v1beta2.ClusterClass" I0731 22:15:31.832362 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" I0731 22:15:31.832674 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.Machine" I0731 22:15:31.832698 1 controller.go:370] "Starting EventSource" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" source="kind source: *v1beta2.Cluster" I0731 22:15:31.832706 1 controller.go:370] "Starting EventSource" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" source="channel source: 0xc00057da40" I0731 22:15:31.832719 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.Cluster" I0731 22:15:31.832827 1 controller.go:370] "Starting EventSource" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" source="kind source: *v1beta2.ClusterResourceSetBinding" I0731 22:15:31.832846 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1.PartialObjectMetadata" I0731 22:15:31.832853 1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="kind source: *v1beta2.MachineHealthCheck" I0731 22:15:31.832901 1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="kind source: *v1beta2.Machine" I0731 22:15:31.832931 1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="kind source: *v1beta2.Cluster" I0731 22:15:31.832828 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1beta2.Cluster" I0731 22:15:31.832829 1 controller.go:370] "Starting EventSource" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" source="channel source: 0xc00057db20" I0731 22:15:31.832981 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Machine" I0731 22:15:31.832756 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1.PartialObjectMetadata" I0731 22:15:31.832933 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="channel source: 0xc00057d5e0" I0731 22:15:31.833008 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" I0731 22:15:31.832964 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.MachineSet" I0731 22:15:31.832796 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="kind source: *v1beta2.ClusterResourceSet" I0731 22:15:31.833055 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Machine" I0731 22:15:31.833108 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.Cluster" I0731 22:15:31.833075 1 controller.go:370] "Starting EventSource" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" source="kind source: *v1beta2.MachineDeployment" I0731 22:15:31.833160 1 controller.go:370] "Starting EventSource" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" source="kind source: *v1beta2.Cluster" I0731 22:15:31.832680 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="channel source: 0xc00057d1f0" I0731 22:15:31.833195 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.MachineSet" I0731 22:15:31.833202 1 controller.go:370] "Starting EventSource" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" source="kind source: *v1beta2.MachineDeployment" I0731 22:15:31.833521 1 controller.go:370] "Starting EventSource" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" source="channel source: 0xc00057dab0" I0731 22:15:31.833531 1 controller.go:370] "Starting EventSource" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" source="kind source: *v1beta2.MachinePool" I0731 22:15:31.834247 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" I0731 22:15:31.834732 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" I0731 22:15:31.836799 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" I0731 22:15:31.838185 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" I0731 22:15:31.838343 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" I0731 22:15:31.841950 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" I0731 22:15:31.845777 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" I0731 22:15:31.846915 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" I0731 22:15:31.930842 1 controller.go:303] "Starting Controller" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" I0731 22:15:31.930876 1 controller.go:303] "Starting Controller" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" I0731 22:15:31.930918 1 controller.go:303] "Starting Controller" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" I0731 22:15:31.930864 1 controller.go:303] "Starting Controller" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" I0731 22:15:31.930952 1 controller.go:306] "Starting workers" controller="topology/machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" worker count=1 I0731 22:15:31.930946 1 controller.go:306] "Starting workers" controller="cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" worker count=50 I0731 22:15:31.930965 1 controller.go:303] "Starting Controller" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" I0731 22:15:31.930981 1 controller.go:306] "Starting workers" controller="topology/cluster" controllerGroup="cluster.x-k8s.io" controllerKind="Cluster" worker count=50 I0731 22:15:31.930899 1 controller.go:306] "Starting workers" controller="clusterclass" controllerGroup="cluster.x-k8s.io" controllerKind="ClusterClass" worker count=10 I0731 22:15:31.931131 1 controller.go:303] "Starting Controller" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" I0731 22:15:31.931194 1 controller.go:306] "Starting workers" controller="topology/machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" worker count=1 I0731 22:15:31.930911 1 controller.go:306] "Starting workers" controller="machinedeployment" controllerGroup="cluster.x-k8s.io" controllerKind="MachineDeployment" worker count=50 I0731 22:15:31.933686 1 controller.go:303] "Starting Controller" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" I0731 22:15:31.933712 1 controller.go:303] "Starting Controller" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" I0731 22:15:31.933711 1 controller.go:306] "Starting workers" controller="clusterresourcesetbinding" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSetBinding" worker count=10 I0731 22:15:31.933726 1 controller.go:306] "Starting workers" controller="machineset" controllerGroup="cluster.x-k8s.io" controllerKind="MachineSet" worker count=50 I0731 22:15:31.933688 1 controller.go:303] "Starting Controller" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" I0731 22:15:31.933775 1 controller.go:306] "Starting workers" controller="machinehealthcheck" controllerGroup="cluster.x-k8s.io" controllerKind="MachineHealthCheck" worker count=50 I0731 22:15:31.933855 1 controller.go:303] "Starting Controller" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" I0731 22:15:31.933871 1 controller.go:306] "Starting workers" controller="machine" controllerGroup="cluster.x-k8s.io" controllerKind="Machine" worker count=100 I0731 22:15:31.934153 1 controller.go:303] "Starting Controller" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" I0731 22:15:31.934181 1 controller.go:306] "Starting workers" controller="machinepool" controllerGroup="cluster.x-k8s.io" controllerKind="MachinePool" worker count=50 I0731 22:15:32.031424 1 controller.go:303] "Starting Controller" controller="crdmigrator" controllerGroup="apiextensions.k8s.io" controllerKind="CustomResourceDefinition" I0731 22:15:32.031459 1 controller.go:306] "Starting workers" controller="crdmigrator" controllerGroup="apiextensions.k8s.io" controllerKind="CustomResourceDefinition" worker count=1 I0731 22:15:32.035039 1 controller.go:303] "Starting Controller" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" I0731 22:15:32.035056 1 controller.go:306] "Starting workers" controller="clusterresourceset" controllerGroup="addons.cluster.x-k8s.io" controllerKind="ClusterResourceSet" worker count=10