I0427 21:54:12.463924       1 webhook.go:186] "skip registering a mutating webhook, object does not implement admission.Defaulter or WithDefaulter wasn't called" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackCluster"
I0427 21:54:12.464079       1 webhook.go:202] "Registering a validating webhook" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackCluster" path="/validate-infrastructure-cluster-x-k8s-io-v1beta1-openstackcluster"
I0427 21:54:12.464162       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-infrastructure-cluster-x-k8s-io-v1beta1-openstackcluster"
I0427 21:54:12.464261       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/convert"
I0427 21:54:12.464274       1 webhook.go:241] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackCluster"
I0427 21:54:12.464292       1 webhook.go:186] "skip registering a mutating webhook, object does not implement admission.Defaulter or WithDefaulter wasn't called" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackClusterTemplate"
I0427 21:54:12.464329       1 webhook.go:202] "Registering a validating webhook" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackClusterTemplate" path="/validate-infrastructure-cluster-x-k8s-io-v1beta1-openstackclustertemplate"
I0427 21:54:12.464404       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-infrastructure-cluster-x-k8s-io-v1beta1-openstackclustertemplate"
I0427 21:54:12.464447       1 webhook.go:241] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackClusterTemplate"
I0427 21:54:12.464462       1 webhook.go:186] "skip registering a mutating webhook, object does not implement admission.Defaulter or WithDefaulter wasn't called" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackMachine"
I0427 21:54:12.464498       1 webhook.go:202] "Registering a validating webhook" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackMachine" path="/validate-infrastructure-cluster-x-k8s-io-v1beta1-openstackmachine"
I0427 21:54:12.464550       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-infrastructure-cluster-x-k8s-io-v1beta1-openstackmachine"
I0427 21:54:12.464589       1 webhook.go:241] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackMachine"
I0427 21:54:12.464658       1 webhook.go:186] "skip registering a mutating webhook, object does not implement admission.Defaulter or WithDefaulter wasn't called" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackMachineTemplate"
I0427 21:54:12.464706       1 webhook.go:202] "Registering a validating webhook" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackMachineTemplate" path="/validate-infrastructure-cluster-x-k8s-io-v1beta1-openstackmachinetemplate"
I0427 21:54:12.464764       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-infrastructure-cluster-x-k8s-io-v1beta1-openstackmachinetemplate"
I0427 21:54:12.464951       1 webhook.go:241] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackMachineTemplate"
I0427 21:54:12.464971       1 webhook.go:186] "skip registering a mutating webhook, object does not implement admission.Defaulter or WithDefaulter wasn't called" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1alpha1, Kind=OpenStackServer"
I0427 21:54:12.465007       1 webhook.go:202] "Registering a validating webhook" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1alpha1, Kind=OpenStackServer" path="/validate-infrastructure-cluster-x-k8s-io-v1alpha1-openstackserver"
I0427 21:54:12.465061       1 server.go:183] "Registering webhook" logger="controller-runtime.webhook" path="/validate-infrastructure-cluster-x-k8s-io-v1alpha1-openstackserver"
I0427 21:54:12.465358       1 webhook.go:186] "skip registering a mutating webhook, object does not implement admission.Defaulter or WithDefaulter wasn't called" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackClusterList"
I0427 21:54:12.465368       1 webhook.go:225] "skip registering a validating webhook, object does not implement admission.Validator or WithValidator wasn't called" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackClusterList"
I0427 21:54:12.465398       1 webhook.go:241] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackClusterList"
I0427 21:54:12.465409       1 webhook.go:186] "skip registering a mutating webhook, object does not implement admission.Defaulter or WithDefaulter wasn't called" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackClusterTemplateList"
I0427 21:54:12.465418       1 webhook.go:225] "skip registering a validating webhook, object does not implement admission.Validator or WithValidator wasn't called" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackClusterTemplateList"
I0427 21:54:12.465446       1 webhook.go:241] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackClusterTemplateList"
I0427 21:54:12.465456       1 webhook.go:186] "skip registering a mutating webhook, object does not implement admission.Defaulter or WithDefaulter wasn't called" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackMachineList"
I0427 21:54:12.465466       1 webhook.go:225] "skip registering a validating webhook, object does not implement admission.Validator or WithValidator wasn't called" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackMachineList"
I0427 21:54:12.465493       1 webhook.go:241] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackMachineList"
I0427 21:54:12.465511       1 webhook.go:186] "skip registering a mutating webhook, object does not implement admission.Defaulter or WithDefaulter wasn't called" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackMachineTemplateList"
I0427 21:54:12.465551       1 webhook.go:225] "skip registering a validating webhook, object does not implement admission.Validator or WithValidator wasn't called" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackMachineTemplateList"
I0427 21:54:12.465583       1 webhook.go:241] "Conversion webhook enabled" logger="controller-runtime.builder" GVK="infrastructure.cluster.x-k8s.io/v1beta1, Kind=OpenStackMachineTemplateList"
I0427 21:54:12.465606       1 main.go:279] "starting manager" logger="setup" version="v0.12.7"
I0427 21:54:12.465677       1 server.go:208] "Starting metrics server" logger="controller-runtime.metrics"
I0427 21:54:12.465749       1 server.go:247] "Serving metrics server" logger="controller-runtime.metrics" bindAddress="127.0.0.1:8080" secure=false
I0427 21:54:12.465821       1 server.go:83] "starting server" name="health probe" addr="[::]:9440"
I0427 21:54:12.465852       1 server.go:191] "Starting webhook server" logger="controller-runtime.webhook"
I0427 21:54:12.466244       1 certwatcher.go:211] "Updated current TLS certificate" logger="controller-runtime.certwatcher"
I0427 21:54:12.466309       1 server.go:242] "Serving webhook server" logger="controller-runtime.webhook" host="" port=9443
I0427 21:54:12.466431       1 envvar.go:172] "Feature gate default state" feature="WatchListClient" enabled=false
I0427 21:54:12.466472       1 envvar.go:172] "Feature gate default state" feature="InformerResourceVersion" enabled=false
I0427 21:54:12.466765       1 certwatcher.go:133] "Starting certificate poll+watcher" logger="controller-runtime.certwatcher" interval="10s"
I0427 21:54:12.468796       1 reflector.go:368] Caches populated for *v1beta1.IPAddress from pkg/mod/k8s.io/client-go@v0.31.14/tools/cache/reflector.go:243
I0427 21:54:12.469470       1 reflector.go:368] Caches populated for *v1alpha1.OpenStackServer from pkg/mod/k8s.io/client-go@v0.31.14/tools/cache/reflector.go:243
I0427 21:54:12.469711       1 reflector.go:368] Caches populated for *v1beta1.IPAddressClaim from pkg/mod/k8s.io/client-go@v0.31.14/tools/cache/reflector.go:243
I0427 21:54:12.567109       1 leaderelection.go:254] attempting to acquire leader lease capo-system/controller-leader-election-capo...
I0427 21:54:12.614170       1 leaderelection.go:268] successfully acquired lease capo-system/controller-leader-election-capo
I0427 21:54:12.614537       1 controller.go:175] "Starting EventSource" controller="openstackcluster" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackCluster" source="kind source: *v1beta1.OpenStackCluster"
I0427 21:54:12.614579       1 controller.go:175] "Starting EventSource" controller="openstackcluster" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackCluster" source="kind source: *v1beta1.Cluster"
I0427 21:54:12.614592       1 controller.go:175] "Starting EventSource" controller="openstackcluster" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackCluster" source="kind source: *v1alpha1.OpenStackServer"
I0427 21:54:12.614603       1 controller.go:183] "Starting Controller" controller="openstackcluster" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackCluster"
I0427 21:54:12.614702       1 recorder.go:104] "capo-controller-manager-65565fdb7f-s9pvx_4e9cb0f0-c248-4bb8-94c1-bb7feb0fd89b became leader" logger="events" type="Normal" object={"kind":"Lease","namespace":"capo-system","name":"controller-leader-election-capo","uid":"1010a96e-b47e-4a28-bca7-b73a989839c8","apiVersion":"coordination.k8s.io/v1","resourceVersion":"25365"} reason="LeaderElection"
I0427 21:54:12.614809       1 controller.go:175] "Starting EventSource" controller="openstackfloatingippool" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackFloatingIPPool" source="kind source: *v1alpha1.OpenStackFloatingIPPool"
I0427 21:54:12.614862       1 controller.go:175] "Starting EventSource" controller="openstackmachine" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackMachine" source="kind source: *v1beta1.OpenStackMachine"
I0427 21:54:12.614964       1 controller.go:175] "Starting EventSource" controller="openstackmachine" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackMachine" source="kind source: *v1beta1.Machine"
I0427 21:54:12.614980       1 controller.go:175] "Starting EventSource" controller="openstackmachine" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackMachine" source="kind source: *v1beta1.Cluster"
I0427 21:54:12.614987       1 controller.go:175] "Starting EventSource" controller="openstackmachine" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackMachine" source="kind source: *v1beta1.IPAddressClaim"
I0427 21:54:12.614998       1 controller.go:175] "Starting EventSource" controller="openstackmachine" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackMachine" source="kind source: *v1alpha1.OpenStackServer"
I0427 21:54:12.615028       1 controller.go:183] "Starting Controller" controller="openstackmachine" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackMachine"
I0427 21:54:12.614963       1 controller.go:175] "Starting EventSource" controller="openstackfloatingippool" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackFloatingIPPool" source="kind source: *v1beta1.IPAddressClaim"
I0427 21:54:12.615068       1 controller.go:175] "Starting EventSource" controller="openstackserver" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackServer" source="kind source: *v1alpha1.OpenStackServer"
I0427 21:54:12.615109       1 controller.go:175] "Starting EventSource" controller="openstackfloatingippool" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackFloatingIPPool" source="kind source: *v1beta1.IPAddress"
I0427 21:54:12.615122       1 controller.go:183] "Starting Controller" controller="openstackfloatingippool" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackFloatingIPPool"
I0427 21:54:12.615122       1 controller.go:175] "Starting EventSource" controller="openstackserver" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackServer" source="kind source: *v1alpha1.Image"
I0427 21:54:12.615147       1 controller.go:175] "Starting EventSource" controller="openstackserver" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackServer" source="kind source: *v1beta1.IPAddressClaim"
I0427 21:54:12.615160       1 controller.go:183] "Starting Controller" controller="openstackserver" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackServer"
I0427 21:54:12.621901       1 reflector.go:368] Caches populated for *v1beta1.OpenStackCluster from pkg/mod/k8s.io/client-go@v0.31.14/tools/cache/reflector.go:243
I0427 21:54:12.640157       1 reflector.go:368] Caches populated for *v1alpha1.Image from pkg/mod/k8s.io/client-go@v0.31.14/tools/cache/reflector.go:243
I0427 21:54:12.645546       1 reflector.go:368] Caches populated for *v1beta1.Cluster from pkg/mod/k8s.io/client-go@v0.31.14/tools/cache/reflector.go:243
I0427 21:54:12.646629       1 reflector.go:368] Caches populated for *v1beta1.OpenStackMachine from pkg/mod/k8s.io/client-go@v0.31.14/tools/cache/reflector.go:243
I0427 21:54:12.653161       1 reflector.go:368] Caches populated for *v1alpha1.OpenStackFloatingIPPool from pkg/mod/k8s.io/client-go@v0.31.14/tools/cache/reflector.go:243
I0427 21:54:12.653380       1 reflector.go:368] Caches populated for *v1beta1.Machine from pkg/mod/k8s.io/client-go@v0.31.14/tools/cache/reflector.go:243
I0427 21:54:12.738246       1 controller.go:217] "Starting workers" controller="openstackserver" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackServer" worker count=10
I0427 21:54:12.741940       1 controller.go:217] "Starting workers" controller="openstackcluster" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackCluster" worker count=10
I0427 21:54:12.742173       1 controller.go:217] "Starting workers" controller="openstackfloatingippool" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackFloatingIPPool" worker count=1
I0427 21:54:12.742380       1 controller.go:217] "Starting workers" controller="openstackmachine" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackMachine" worker count=10
E0427 21:55:31.514404       1 leaderelection.go:429] Failed to update lock optimitically: Put "https://10.96.0.1:443/apis/coordination.k8s.io/v1/namespaces/capo-system/leases/controller-leader-election-capo?timeout=5s": net/http: request canceled (Client.Timeout exceeded while awaiting headers), falling back to slow path
E0427 21:55:36.514382       1 leaderelection.go:472] Failed to update lock: Put "https://10.96.0.1:443/apis/coordination.k8s.io/v1/namespaces/capo-system/leases/controller-leader-election-capo?timeout=5s": context deadline exceeded
I0427 21:55:36.514454       1 leaderelection.go:297] failed to renew lease capo-system/controller-leader-election-capo: timed out waiting for the condition
E0427 21:55:41.515654       1 leaderelection.go:322] Failed to release lock: Put "https://10.96.0.1:443/apis/coordination.k8s.io/v1/namespaces/capo-system/leases/controller-leader-election-capo?timeout=5s": context deadline exceeded
I0427 21:55:41.515796       1 internal.go:538] "Stopping and waiting for non leader election runnables"
I0427 21:55:41.515771       1 recorder.go:104] "capo-controller-manager-65565fdb7f-s9pvx_4e9cb0f0-c248-4bb8-94c1-bb7feb0fd89b stopped leading" logger="events" type="Normal" object={"kind":"Lease","namespace":"capo-system","name":"controller-leader-election-capo","uid":"1010a96e-b47e-4a28-bca7-b73a989839c8","apiVersion":"coordination.k8s.io/v1","resourceVersion":"25996"} reason="LeaderElection"
I0427 21:55:41.515814       1 internal.go:542] "Stopping and waiting for leader election runnables"
I0427 21:55:41.515918       1 internal.go:550] "Stopping and waiting for caches"
I0427 21:55:41.515945       1 internal.go:554] "Stopping and waiting for webhooks"
I0427 21:55:41.515957       1 internal.go:557] "Stopping and waiting for HTTP servers"
I0427 21:55:41.515967       1 internal.go:561] "Wait completed, proceeding to shutdown the manager"
I0427 21:55:41.515971       1 controller.go:237] "Shutdown signal received, waiting for all workers to finish" controller="openstackmachine" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackMachine"
I0427 21:55:41.516064       1 controller.go:237] "Shutdown signal received, waiting for all workers to finish" controller="openstackfloatingippool" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackFloatingIPPool"
I0427 21:55:41.516089       1 controller.go:237] "Shutdown signal received, waiting for all workers to finish" controller="openstackcluster" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackCluster"
I0427 21:55:41.516106       1 controller.go:237] "Shutdown signal received, waiting for all workers to finish" controller="openstackserver" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackServer"
W0427 21:55:41.516131       1 reflector.go:484] pkg/mod/k8s.io/client-go@v0.31.14/tools/cache/reflector.go:243: watch of *v1beta1.Machine ended with: an error on the server ("unable to decode an event from the watch stream: context canceled") has prevented the request from succeeding
I0427 21:55:41.516149       1 controller.go:239] "All workers finished" controller="openstackserver" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="OpenStackServer"
E0427 21:55:41.516716       1 main.go:281] "problem running manager" err="leader election lost" logger="setup"