2026/02/17 02:30:48 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=52a45889-5d59-470d-92f3-f063fe901e39,--cloud-provider=openstack,--use-service-account-credentials=false,--controllers=cloud-node,cloud-node-lifecycle,route,service,--bind-address=127.0.0.1
2026/02/17 02:30:48 Now listening for interrupts
I0217 02:30:48.793825      12 serving.go:386] Generated self-signed cert in-memory
I0217 02:30:49.806329      12 serving.go:386] Generated self-signed cert in-memory
W0217 02:30:49.806386      12 client_config.go:667] Neither --kubeconfig nor --master was specified.  Using the inClusterConfig.  This might not work.
I0217 02:30:50.030566      12 requestheader_controller.go:255] Loaded a new request header values for RequestHeaderAuthRequestController
I0217 02:30:50.092862      12 controllermanager.go:160] Version: v1.32.1
I0217 02:30:50.100145      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771295448\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771295448\" (2026-02-17 01:30:48 +0000 UTC to 2027-02-17 01:30:48 +0000 UTC (now=2026-02-17 02:30:50.100077394 +0000 UTC))"
I0217 02:30:50.100943      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771295450\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771295449\" (2026-02-17 01:30:49 +0000 UTC to 2027-02-17 01:30:49 +0000 UTC (now=2026-02-17 02:30:50.100921142 +0000 UTC))"
I0217 02:30:50.101033      12 secure_serving.go:213] Serving securely on 127.0.0.1:10258
I0217 02:30:50.101515      12 tlsconfig.go:243] "Starting DynamicServingCertificateController"
I0217 02:30:50.101570      12 requestheader_controller.go:180] Starting RequestHeaderAuthRequestController
I0217 02:30:50.101599      12 shared_informer.go:313] Waiting for caches to sync for RequestHeaderAuthRequestController
I0217 02:30:50.101623      12 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0217 02:30:50.101615      12 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0217 02:30:50.101633      12 shared_informer.go:313] Waiting for caches to sync for client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file
I0217 02:30:50.101654      12 shared_informer.go:313] Waiting for caches to sync for client-ca::kube-system::extension-apiserver-authentication::client-ca-file
I0217 02:30:50.102300      12 leaderelection.go:257] attempting to acquire leader lease kube-system/cloud-controller-manager...
I0217 02:30:50.104877      12 reflector.go:376] Caches populated for *v1.ConfigMap from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0217 02:30:50.105411      12 reflector.go:376] Caches populated for *v1.ConfigMap from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0217 02:30:50.109356      12 reflector.go:376] Caches populated for *v1.ConfigMap from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0217 02:30:50.116875      12 leaderelection.go:271] successfully acquired lease kube-system/cloud-controller-manager
I0217 02:30:50.117211      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-f4vg7-wgb4p-p4q4p_fd9a7a89-6ab7-485a-9d41-e032e2666bdd became leader"
I0217 02:30:50.120304      12 openstack.go:424] Setting up informers for Cloud
I0217 02:30:50.120352      12 controllermanager.go:310] Starting "cloud-node-controller"
I0217 02:30:50.120751      12 controllermanager.go:329] Started "cloud-node-controller"
I0217 02:30:50.120783      12 controllermanager.go:310] Starting "cloud-node-lifecycle-controller"
I0217 02:30:50.120935      12 controllermanager.go:329] Started "cloud-node-lifecycle-controller"
I0217 02:30:50.120959      12 controllermanager.go:310] Starting "service-lb-controller"
I0217 02:30:50.121321      12 openstack.go:373] Claiming to support LoadBalancer
I0217 02:30:50.121406      12 controllermanager.go:329] Started "service-lb-controller"
I0217 02:30:50.121413      12 controllermanager.go:310] Starting "node-route-controller"
I0217 02:30:50.121677      12 node_lifecycle_controller.go:112] Sending events to api server
I0217 02:30:50.121681      12 controller.go:234] Starting service controller
I0217 02:30:50.122284      12 shared_informer.go:313] Waiting for caches to sync for service
I0217 02:30:50.121770      12 node_controller.go:176] Sending events to api server.
I0217 02:30:50.122613      12 node_controller.go:185] Waiting for informer caches to sync
W0217 02:30:50.183683      12 openstack.go:408] Error initialising Routes support: router-id not set in cloud provider config
W0217 02:30:50.183730      12 core.go:111] --configure-cloud-routes is set, but cloud provider does not support routes. Will not configure cloud provider routes.
W0217 02:30:50.183737      12 controllermanager.go:317] Skipping "node-route-controller"
I0217 02:30:50.198133      12 reflector.go:376] Caches populated for *v1.Service from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0217 02:30:50.198166      12 reflector.go:376] Caches populated for *v1.Node from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0217 02:30:50.201968      12 shared_informer.go:320] Caches are synced for client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file
I0217 02:30:50.202144      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-16 02:25:27 +0000 UTC to 2031-02-16 02:25:27 +0000 UTC (now=2026-02-17 02:30:50.202123674 +0000 UTC))"
I0217 02:30:50.202344      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771295448\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771295448\" (2026-02-17 01:30:48 +0000 UTC to 2027-02-17 01:30:48 +0000 UTC (now=2026-02-17 02:30:50.202328766 +0000 UTC))"
I0217 02:30:50.202591      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771295450\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771295449\" (2026-02-17 01:30:49 +0000 UTC to 2027-02-17 01:30:49 +0000 UTC (now=2026-02-17 02:30:50.20257839 +0000 UTC))"
I0217 02:30:50.203647      12 shared_informer.go:320] Caches are synced for client-ca::kube-system::extension-apiserver-authentication::client-ca-file
I0217 02:30:50.203755      12 shared_informer.go:320] Caches are synced for RequestHeaderAuthRequestController
I0217 02:30:50.204381      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-16 02:25:25 +0000 UTC to 2031-02-16 02:25:25 +0000 UTC (now=2026-02-17 02:30:50.204347882 +0000 UTC))"
I0217 02:30:50.204481      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-16 02:25:27 +0000 UTC to 2031-02-16 02:25:27 +0000 UTC (now=2026-02-17 02:30:50.204470379 +0000 UTC))"
I0217 02:30:50.204899      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771295448\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771295448\" (2026-02-17 01:30:48 +0000 UTC to 2027-02-17 01:30:48 +0000 UTC (now=2026-02-17 02:30:50.204884043 +0000 UTC))"
I0217 02:30:50.205274      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771295450\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771295449\" (2026-02-17 01:30:49 +0000 UTC to 2027-02-17 01:30:49 +0000 UTC (now=2026-02-17 02:30:50.205258995 +0000 UTC))"
I0217 02:30:50.222422      12 shared_informer.go:320] Caches are synced for service
I0217 02:30:50.222624      12 controller.go:722] Syncing backends for all LB services.
I0217 02:30:50.222646      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:30:50.222692      12 controller.go:722] Syncing backends for all LB services.
I0217 02:30:50.222703      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:30:50.222722      12 controller.go:722] Syncing backends for all LB services.
I0217 02:30:50.222783      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:30:50.224120      12 node_controller.go:429] Initializing node kube-f4vg7-default-worker-ks885-dx4k4-579rm with cloud provider
I0217 02:30:50.224409      12 node_controller.go:271] Update 3 nodes status took 115.876µs.
I0217 02:30:50.794250      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 02:30:50.794281      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 02:30:50.794286      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 02:30:50.794291      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 02:30:50.794296      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 02:30:50.794301      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 02:30:50.917941      12 controller.go:722] Syncing backends for all LB services.
I0217 02:30:50.917988      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:30:51.334219      12 node_controller.go:474] Successfully initialized node kube-f4vg7-default-worker-ks885-dx4k4-579rm with cloud provider
I0217 02:30:51.334905      12 node_controller.go:429] Initializing node kube-f4vg7-default-worker-ks885-dx4k4-tqzk9 with cloud provider
I0217 02:30:51.335378      12 event.go:389] "Event occurred" object="kube-f4vg7-default-worker-ks885-dx4k4-579rm" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 02:30:51.768252      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 02:30:51.768594      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 02:30:51.768750      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 02:30:51.768827      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 02:30:51.769038      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 02:30:51.769112      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 02:30:52.656100      12 controller.go:722] Syncing backends for all LB services.
I0217 02:30:52.656188      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:30:52.706380      12 node_controller.go:474] Successfully initialized node kube-f4vg7-default-worker-ks885-dx4k4-tqzk9 with cloud provider
I0217 02:30:52.706442      12 node_controller.go:429] Initializing node kube-f4vg7-wgb4p-p4q4p with cloud provider
I0217 02:30:52.707209      12 event.go:389] "Event occurred" object="kube-f4vg7-default-worker-ks885-dx4k4-tqzk9" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 02:30:53.065310      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 02:30:53.065576      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 02:30:53.065706      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 02:30:53.065825      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 02:30:53.065980      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 02:30:53.066159      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 02:30:53.109862      12 controller.go:722] Syncing backends for all LB services.
I0217 02:30:53.109900      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:30:53.218716      12 node_controller.go:474] Successfully initialized node kube-f4vg7-wgb4p-p4q4p with cloud provider
I0217 02:30:53.218815      12 event.go:389] "Event occurred" object="kube-f4vg7-wgb4p-p4q4p" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 02:35:51.359394      12 node_controller.go:271] Update 3 nodes status took 1.134261415s.
I0217 02:39:34.831910      12 controller.go:722] Syncing backends for all LB services.
I0217 02:39:34.831949      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:39:34.921319      12 controller.go:722] Syncing backends for all LB services.
I0217 02:39:34.921399      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:40:52.399022      12 node_controller.go:271] Update 3 nodes status took 1.039148302s.
I0217 02:45:53.572022      12 node_controller.go:271] Update 3 nodes status took 1.171107108s.
I0217 02:50:54.554560      12 node_controller.go:271] Update 3 nodes status took 981.232816ms.
I0217 02:55:55.728354      12 node_controller.go:271] Update 3 nodes status took 1.173432549s.
