2026/02/17 02:35:04 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=15af74c0-86ed-402c-b1ed-9d1262e9cb9c,--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:35:04 Now listening for interrupts
I0217 02:35:05.586739      12 serving.go:386] Generated self-signed cert in-memory
I0217 02:35:06.111578      12 serving.go:386] Generated self-signed cert in-memory
W0217 02:35:06.111631      12 client_config.go:667] Neither --kubeconfig nor --master was specified.  Using the inClusterConfig.  This might not work.
I0217 02:35:06.582778      12 requestheader_controller.go:255] Loaded a new request header values for RequestHeaderAuthRequestController
I0217 02:35:06.639521      12 controllermanager.go:160] Version: v1.32.1
I0217 02:35:06.647514      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771295705\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771295705\" (2026-02-17 01:35:04 +0000 UTC to 2027-02-17 01:35:04 +0000 UTC (now=2026-02-17 02:35:06.647475583 +0000 UTC))"
I0217 02:35:06.647601      12 requestheader_controller.go:180] Starting RequestHeaderAuthRequestController
I0217 02:35:06.647642      12 shared_informer.go:313] Waiting for caches to sync for RequestHeaderAuthRequestController
I0217 02:35:06.647689      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771295706\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771295706\" (2026-02-17 01:35:06 +0000 UTC to 2027-02-17 01:35:06 +0000 UTC (now=2026-02-17 02:35:06.647642858 +0000 UTC))"
I0217 02:35:06.647724      12 secure_serving.go:213] Serving securely on 127.0.0.1:10258
I0217 02:35:06.648205      12 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0217 02:35:06.648247      12 tlsconfig.go:243] "Starting DynamicServingCertificateController"
I0217 02:35:06.648258      12 shared_informer.go:313] Waiting for caches to sync for client-ca::kube-system::extension-apiserver-authentication::client-ca-file
I0217 02:35:06.648225      12 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0217 02:35:06.648286      12 shared_informer.go:313] Waiting for caches to sync for client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file
I0217 02:35:06.648371      12 leaderelection.go:257] attempting to acquire leader lease kube-system/cloud-controller-manager...
I0217 02:35:06.649881      12 reflector.go:376] Caches populated for *v1.ConfigMap from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0217 02:35:06.651118      12 reflector.go:376] Caches populated for *v1.ConfigMap from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0217 02:35:06.651921      12 reflector.go:376] Caches populated for *v1.ConfigMap from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0217 02:35:06.666879      12 leaderelection.go:271] successfully acquired lease kube-system/cloud-controller-manager
I0217 02:35:06.667274      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-965gf-lpshv-rpzlp_8a95f5df-6539-4c16-a073-e38f5fb5f360 became leader"
I0217 02:35:06.670813      12 openstack.go:424] Setting up informers for Cloud
I0217 02:35:06.670917      12 controllermanager.go:310] Starting "cloud-node-lifecycle-controller"
I0217 02:35:06.672325      12 controllermanager.go:329] Started "cloud-node-lifecycle-controller"
I0217 02:35:06.672352      12 controllermanager.go:310] Starting "service-lb-controller"
I0217 02:35:06.672569      12 node_lifecycle_controller.go:112] Sending events to api server
I0217 02:35:06.673578      12 openstack.go:373] Claiming to support LoadBalancer
I0217 02:35:06.673641      12 controllermanager.go:329] Started "service-lb-controller"
I0217 02:35:06.673680      12 controllermanager.go:310] Starting "node-route-controller"
I0217 02:35:06.673795      12 controller.go:234] Starting service controller
I0217 02:35:06.673937      12 shared_informer.go:313] Waiting for caches to sync for service
W0217 02:35:06.717360      12 openstack.go:408] Error initialising Routes support: router-id not set in cloud provider config
W0217 02:35:06.717409      12 core.go:111] --configure-cloud-routes is set, but cloud provider does not support routes. Will not configure cloud provider routes.
W0217 02:35:06.717419      12 controllermanager.go:317] Skipping "node-route-controller"
I0217 02:35:06.717427      12 controllermanager.go:310] Starting "cloud-node-controller"
I0217 02:35:06.717888      12 controllermanager.go:329] Started "cloud-node-controller"
I0217 02:35:06.719118      12 node_controller.go:176] Sending events to api server.
I0217 02:35:06.720147      12 node_controller.go:185] Waiting for informer caches to sync
I0217 02:35:06.724110      12 reflector.go:376] Caches populated for *v1.Node from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0217 02:35:06.724637      12 reflector.go:376] Caches populated for *v1.Service from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0217 02:35:06.748549      12 shared_informer.go:320] Caches are synced for client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file
I0217 02:35:06.748741      12 shared_informer.go:320] Caches are synced for RequestHeaderAuthRequestController
I0217 02:35:06.748792      12 shared_informer.go:320] Caches are synced for client-ca::kube-system::extension-apiserver-authentication::client-ca-file
I0217 02:35:06.748942      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:30:00 +0000 UTC to 2031-02-16 02:30:00 +0000 UTC (now=2026-02-17 02:35:06.748920565 +0000 UTC))"
I0217 02:35:06.748974      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:30:01 +0000 UTC to 2031-02-16 02:30:01 +0000 UTC (now=2026-02-17 02:35:06.748964957 +0000 UTC))"
I0217 02:35:06.749145      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771295705\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771295705\" (2026-02-17 01:35:04 +0000 UTC to 2027-02-17 01:35:04 +0000 UTC (now=2026-02-17 02:35:06.749134151 +0000 UTC))"
I0217 02:35:06.749277      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771295706\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771295706\" (2026-02-17 01:35:06 +0000 UTC to 2027-02-17 01:35:06 +0000 UTC (now=2026-02-17 02:35:06.749265815 +0000 UTC))"
I0217 02:35:06.775116      12 shared_informer.go:320] Caches are synced for service
I0217 02:35:06.775198      12 controller.go:722] Syncing backends for all LB services.
I0217 02:35:06.775208      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:35:06.775225      12 controller.go:722] Syncing backends for all LB services.
I0217 02:35:06.775230      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:35:06.775238      12 controller.go:722] Syncing backends for all LB services.
I0217 02:35:06.775243      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:35:06.821633      12 node_controller.go:429] Initializing node kube-965gf-default-worker-c5p85-fm7kc-8g7k4 with cloud provider
I0217 02:35:06.822445      12 node_controller.go:271] Update 3 nodes status took 343.719µs.
I0217 02:35:07.378643      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 02:35:07.378729      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 02:35:07.378736      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 02:35:07.378742      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 02:35:07.378749      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 02:35:07.378755      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 02:35:07.402775      12 controller.go:722] Syncing backends for all LB services.
I0217 02:35:07.402814      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:35:07.501134      12 node_controller.go:474] Successfully initialized node kube-965gf-default-worker-c5p85-fm7kc-8g7k4 with cloud provider
I0217 02:35:07.509613      12 event.go:389] "Event occurred" object="kube-965gf-default-worker-c5p85-fm7kc-8g7k4" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 02:35:07.516549      12 node_controller.go:429] Initializing node kube-965gf-default-worker-c5p85-fm7kc-929vr with cloud provider
I0217 02:35:07.879475      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 02:35:07.879522      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 02:35:07.879529      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 02:35:07.879535      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 02:35:07.879542      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 02:35:07.879547      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 02:35:07.896437      12 controller.go:722] Syncing backends for all LB services.
I0217 02:35:07.896480      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:35:07.930925      12 node_controller.go:474] Successfully initialized node kube-965gf-default-worker-c5p85-fm7kc-929vr with cloud provider
I0217 02:35:07.930994      12 node_controller.go:429] Initializing node kube-965gf-lpshv-rpzlp with cloud provider
I0217 02:35:07.931904      12 event.go:389] "Event occurred" object="kube-965gf-default-worker-c5p85-fm7kc-929vr" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 02:35:08.378810      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 02:35:08.379063      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 02:35:08.379205      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 02:35:08.379273      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 02:35:08.379371      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 02:35:08.379469      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 02:35:08.401142      12 controller.go:722] Syncing backends for all LB services.
I0217 02:35:08.401190      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:35:08.451324      12 node_controller.go:474] Successfully initialized node kube-965gf-lpshv-rpzlp with cloud provider
I0217 02:35:08.453103      12 event.go:389] "Event occurred" object="kube-965gf-lpshv-rpzlp" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 02:36:27.750099      12 controller.go:722] Syncing backends for all LB services.
I0217 02:36:27.750140      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:36:27.912906      12 controller.go:722] Syncing backends for all LB services.
I0217 02:36:27.912935      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:40:08.224320      12 node_controller.go:271] Update 3 nodes status took 1.40147726s.
I0217 02:45:09.217567      12 node_controller.go:271] Update 3 nodes status took 992.997917ms.
I0217 02:50:10.421730      12 node_controller.go:271] Update 3 nodes status took 1.203720679s.
I0217 02:55:11.734475      12 node_controller.go:271] Update 3 nodes status took 1.312217189s.
