2026/02/16 19:48:41 Running command:
Command env: (log-file=, also-stdout=false, redirect-stderr=true)
Run from directory: 
Executable path: /bin/openstack-cloud-controller-manager
Args (comma-delimited): /bin/openstack-cloud-controller-manager,--v=2,--cloud-config=/etc/config/cloud.conf,--cluster-name=e304e17d-cab6-418b-99c1-1bb3b3f7ee3e,--cloud-provider=openstack,--use-service-account-credentials=false,--controllers=cloud-node,cloud-node-lifecycle,route,service,--bind-address=127.0.0.1
2026/02/16 19:48:41 Now listening for interrupts
I0216 19:48:41.686019      12 serving.go:386] Generated self-signed cert in-memory
I0216 19:48:42.080539      12 serving.go:386] Generated self-signed cert in-memory
W0216 19:48:42.080592      12 client_config.go:667] Neither --kubeconfig nor --master was specified.  Using the inClusterConfig.  This might not work.
I0216 19:48:42.443301      12 requestheader_controller.go:255] Loaded a new request header values for RequestHeaderAuthRequestController
I0216 19:48:42.511550      12 controllermanager.go:160] Version: v1.32.1
I0216 19:48:42.518670      12 requestheader_controller.go:180] Starting RequestHeaderAuthRequestController
I0216 19:48:42.518725      12 shared_informer.go:313] Waiting for caches to sync for RequestHeaderAuthRequestController
I0216 19:48:42.518819      12 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0216 19:48:42.518831      12 shared_informer.go:313] Waiting for caches to sync for client-ca::kube-system::extension-apiserver-authentication::client-ca-file
I0216 19:48:42.518846      12 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0216 19:48:42.518875      12 shared_informer.go:313] Waiting for caches to sync for client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file
I0216 19:48:42.519308      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771271321\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771271321\" (2026-02-16 18:48:41 +0000 UTC to 2027-02-16 18:48:41 +0000 UTC (now=2026-02-16 19:48:42.519279722 +0000 UTC))"
I0216 19:48:42.522101      12 reflector.go:376] Caches populated for *v1.ConfigMap from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0216 19:48:42.522985      12 reflector.go:376] Caches populated for *v1.ConfigMap from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0216 19:48:42.524970      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771271322\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771271322\" (2026-02-16 18:48:42 +0000 UTC to 2027-02-16 18:48:42 +0000 UTC (now=2026-02-16 19:48:42.524898082 +0000 UTC))"
I0216 19:48:42.525216      12 secure_serving.go:213] Serving securely on 127.0.0.1:10258
I0216 19:48:42.525474      12 reflector.go:376] Caches populated for *v1.ConfigMap from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0216 19:48:42.525799      12 tlsconfig.go:243] "Starting DynamicServingCertificateController"
I0216 19:48:42.527129      12 leaderelection.go:257] attempting to acquire leader lease kube-system/cloud-controller-manager...
I0216 19:48:42.564052      12 leaderelection.go:271] successfully acquired lease kube-system/cloud-controller-manager
I0216 19:48:42.566011      12 event.go:389] "Event occurred" object="kube-system/cloud-controller-manager" fieldPath="" kind="Lease" apiVersion="coordination.k8s.io/v1" type="Normal" reason="LeaderElection" message="kube-7qc92-psgxj-gbcvf_be065387-d4b3-4232-bf52-8b74c3d93155 became leader"
I0216 19:48:42.575446      12 openstack.go:424] Setting up informers for Cloud
I0216 19:48:42.575813      12 controllermanager.go:310] Starting "service-lb-controller"
I0216 19:48:42.577775      12 openstack.go:373] Claiming to support LoadBalancer
I0216 19:48:42.578026      12 controllermanager.go:329] Started "service-lb-controller"
I0216 19:48:42.578109      12 controllermanager.go:310] Starting "node-route-controller"
I0216 19:48:42.578945      12 controller.go:234] Starting service controller
I0216 19:48:42.579212      12 shared_informer.go:313] Waiting for caches to sync for service
I0216 19:48:42.619107      12 shared_informer.go:320] Caches are synced for RequestHeaderAuthRequestController
I0216 19:48:42.619371      12 shared_informer.go:320] Caches are synced for client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file
I0216 19:48:42.619471      12 shared_informer.go:320] Caches are synced for client-ca::kube-system::extension-apiserver-authentication::client-ca-file
I0216 19:48:42.619985      12 tlsconfig.go:181] "Loaded client CA" index=0 certName="client-ca::kube-system::extension-apiserver-authentication::client-ca-file,client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file" certDetail="\"k8s-cluster\" [] issuer=\"<self>\" (2026-02-15 19:43:55 +0000 UTC to 2031-02-15 19:43:55 +0000 UTC (now=2026-02-16 19:48:42.61991468 +0000 UTC))"
I0216 19:48:42.620086      12 tlsconfig.go:181] "Loaded client CA" index=1 certName="client-ca::kube-system::extension-apiserver-authentication::client-ca-file,client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file" certDetail="\"k8s-cluster\" [] issuer=\"<self>\" (2026-02-15 19:43:56 +0000 UTC to 2031-02-15 19:43:56 +0000 UTC (now=2026-02-16 19:48:42.620075253 +0000 UTC))"
I0216 19:48:42.620474      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771271321\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771271321\" (2026-02-16 18:48:41 +0000 UTC to 2027-02-16 18:48:41 +0000 UTC (now=2026-02-16 19:48:42.620463219 +0000 UTC))"
I0216 19:48:42.620713      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771271322\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771271322\" (2026-02-16 18:48:42 +0000 UTC to 2027-02-16 18:48:42 +0000 UTC (now=2026-02-16 19:48:42.620702444 +0000 UTC))"
W0216 19:48:42.627693      12 openstack.go:408] Error initialising Routes support: router-id not set in cloud provider config
W0216 19:48:42.627723      12 core.go:111] --configure-cloud-routes is set, but cloud provider does not support routes. Will not configure cloud provider routes.
W0216 19:48:42.627730      12 controllermanager.go:317] Skipping "node-route-controller"
I0216 19:48:42.627736      12 controllermanager.go:310] Starting "cloud-node-controller"
I0216 19:48:42.628336      12 controllermanager.go:329] Started "cloud-node-controller"
I0216 19:48:42.628362      12 controllermanager.go:310] Starting "cloud-node-lifecycle-controller"
I0216 19:48:42.628929      12 node_controller.go:176] Sending events to api server.
I0216 19:48:42.629736      12 node_controller.go:185] Waiting for informer caches to sync
I0216 19:48:42.630404      12 controllermanager.go:329] Started "cloud-node-lifecycle-controller"
I0216 19:48:42.630783      12 node_lifecycle_controller.go:112] Sending events to api server
I0216 19:48:42.634947      12 reflector.go:376] Caches populated for *v1.Service from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0216 19:48:42.638391      12 reflector.go:376] Caches populated for *v1.Node from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0216 19:48:42.680438      12 shared_informer.go:320] Caches are synced for service
I0216 19:48:42.680641      12 controller.go:722] Syncing backends for all LB services.
I0216 19:48:42.680697      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 19:48:42.731008      12 node_controller.go:271] Update 1 nodes status took 118.362µs.
I0216 19:48:42.731413      12 node_controller.go:429] Initializing node kube-7qc92-psgxj-gbcvf with cloud provider
I0216 19:48:43.477527      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0216 19:48:43.477873      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0216 19:48:43.478021      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0216 19:48:43.478114      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0216 19:48:43.478639      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0216 19:48:43.478968      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0216 19:48:43.705966      12 controller.go:722] Syncing backends for all LB services.
I0216 19:48:43.706027      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 19:48:43.878998      12 node_controller.go:474] Successfully initialized node kube-7qc92-psgxj-gbcvf with cloud provider
I0216 19:48:43.879471      12 event.go:389] "Event occurred" object="kube-7qc92-psgxj-gbcvf" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0216 19:49:10.102628      12 node_controller.go:429] Initializing node kube-7qc92-default-worker-tg98v-75b24-24tq2 with cloud provider
I0216 19:49:10.102936      12 controller.go:722] Syncing backends for all LB services.
I0216 19:49:10.102949      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 19:49:10.643700      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0216 19:49:10.643746      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0216 19:49:10.643761      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0216 19:49:10.643770      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0216 19:49:10.643776      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0216 19:49:10.643783      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0216 19:49:10.661610      12 controller.go:722] Syncing backends for all LB services.
I0216 19:49:10.661630      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 19:49:10.675100      12 node_controller.go:474] Successfully initialized node kube-7qc92-default-worker-tg98v-75b24-24tq2 with cloud provider
I0216 19:49:10.675428      12 event.go:389] "Event occurred" object="kube-7qc92-default-worker-tg98v-75b24-24tq2" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0216 19:49:26.932200      12 node_controller.go:429] Initializing node kube-7qc92-default-worker-tg98v-75b24-lsxjr with cloud provider
I0216 19:49:26.932802      12 controller.go:722] Syncing backends for all LB services.
I0216 19:49:26.932830      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 19:49:27.309971      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0216 19:49:27.310020      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0216 19:49:27.310027      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0216 19:49:27.310033      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0216 19:49:27.310039      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0216 19:49:27.310045      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0216 19:49:27.328847      12 controller.go:722] Syncing backends for all LB services.
I0216 19:49:27.328893      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 19:49:27.391903      12 node_controller.go:474] Successfully initialized node kube-7qc92-default-worker-tg98v-75b24-lsxjr with cloud provider
I0216 19:49:27.392146      12 event.go:389] "Event occurred" object="kube-7qc92-default-worker-tg98v-75b24-lsxjr" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
E0216 19:49:53.324265      12 leaderelection.go:429] Failed to update lock optimistically: Put "https://10.254.0.1:443/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/cloud-controller-manager?timeout=5s": net/http: request canceled (Client.Timeout exceeded while awaiting headers), falling back to slow path
I0216 19:53:43.885152      12 node_controller.go:271] Update 3 nodes status took 1.153567802s.
I0216 19:58:44.864290      12 node_controller.go:271] Update 3 nodes status took 978.88344ms.
