2026/02/17 02:36:27 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=1131be08-40dd-4c36-843b-4e22acbd28cf,--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:36:27 Now listening for interrupts
I0217 02:36:28.484005      12 serving.go:386] Generated self-signed cert in-memory
I0217 02:36:28.801222      12 serving.go:386] Generated self-signed cert in-memory
W0217 02:36:28.801272      12 client_config.go:667] Neither --kubeconfig nor --master was specified.  Using the inClusterConfig.  This might not work.
I0217 02:36:29.257152      12 requestheader_controller.go:255] Loaded a new request header values for RequestHeaderAuthRequestController
I0217 02:36:29.326127      12 controllermanager.go:160] Version: v1.32.1
I0217 02:36:29.333119      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771295788\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771295788\" (2026-02-17 01:36:27 +0000 UTC to 2027-02-17 01:36:27 +0000 UTC (now=2026-02-17 02:36:29.333078238 +0000 UTC))"
I0217 02:36:29.333400      12 requestheader_controller.go:180] Starting RequestHeaderAuthRequestController
I0217 02:36:29.333439      12 shared_informer.go:313] Waiting for caches to sync for RequestHeaderAuthRequestController
I0217 02:36:29.333483      12 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0217 02:36:29.333497      12 shared_informer.go:313] Waiting for caches to sync for client-ca::kube-system::extension-apiserver-authentication::client-ca-file
I0217 02:36:29.333513      12 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0217 02:36:29.333547      12 shared_informer.go:313] Waiting for caches to sync for client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file
I0217 02:36:29.335847      12 reflector.go:376] Caches populated for *v1.ConfigMap from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0217 02:36:29.337082      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771295789\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771295789\" (2026-02-17 01:36:28 +0000 UTC to 2027-02-17 01:36:28 +0000 UTC (now=2026-02-17 02:36:29.333945174 +0000 UTC))"
I0217 02:36:29.337156      12 secure_serving.go:213] Serving securely on 127.0.0.1:10258
I0217 02:36:29.337460      12 leaderelection.go:257] attempting to acquire leader lease kube-system/cloud-controller-manager...
I0217 02:36:29.337728      12 reflector.go:376] Caches populated for *v1.ConfigMap from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0217 02:36:29.337939      12 tlsconfig.go:243] "Starting DynamicServingCertificateController"
I0217 02:36:29.342585      12 reflector.go:376] Caches populated for *v1.ConfigMap from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0217 02:36:29.402097      12 leaderelection.go:271] successfully acquired lease kube-system/cloud-controller-manager
I0217 02:36:29.402334      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-cwnyv-thfz4-q556h_f7311fb3-2206-4930-8d90-9ee223d70b72 became leader"
I0217 02:36:29.405557      12 openstack.go:424] Setting up informers for Cloud
I0217 02:36:29.405614      12 controllermanager.go:310] Starting "service-lb-controller"
I0217 02:36:29.406022      12 openstack.go:373] Claiming to support LoadBalancer
I0217 02:36:29.406132      12 controllermanager.go:329] Started "service-lb-controller"
I0217 02:36:29.406164      12 controllermanager.go:310] Starting "node-route-controller"
I0217 02:36:29.406538      12 controller.go:234] Starting service controller
I0217 02:36:29.406576      12 shared_informer.go:313] Waiting for caches to sync for service
I0217 02:36:29.433605      12 shared_informer.go:320] Caches are synced for client-ca::kube-system::extension-apiserver-authentication::client-ca-file
I0217 02:36:29.433693      12 shared_informer.go:320] Caches are synced for RequestHeaderAuthRequestController
I0217 02:36:29.433816      12 shared_informer.go:320] Caches are synced for client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file
I0217 02:36:29.433909      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:58 +0000 UTC to 2031-02-16 02:30:58 +0000 UTC (now=2026-02-17 02:36:29.433890612 +0000 UTC))"
I0217 02:36:29.434099      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771295788\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771295788\" (2026-02-17 01:36:27 +0000 UTC to 2027-02-17 01:36:27 +0000 UTC (now=2026-02-17 02:36:29.434088774 +0000 UTC))"
I0217 02:36:29.434223      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771295789\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771295789\" (2026-02-17 01:36:28 +0000 UTC to 2027-02-17 01:36:28 +0000 UTC (now=2026-02-17 02:36:29.434214842 +0000 UTC))"
I0217 02:36:29.434266      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:58 +0000 UTC to 2031-02-16 02:30:58 +0000 UTC (now=2026-02-17 02:36:29.434259545 +0000 UTC))"
I0217 02:36:29.434280      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:31:00 +0000 UTC to 2031-02-16 02:31:00 +0000 UTC (now=2026-02-17 02:36:29.434275946 +0000 UTC))"
I0217 02:36:29.434571      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771295788\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771295788\" (2026-02-17 01:36:27 +0000 UTC to 2027-02-17 01:36:27 +0000 UTC (now=2026-02-17 02:36:29.434562774 +0000 UTC))"
I0217 02:36:29.434683      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771295789\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771295789\" (2026-02-17 01:36:28 +0000 UTC to 2027-02-17 01:36:28 +0000 UTC (now=2026-02-17 02:36:29.434675702 +0000 UTC))"
W0217 02:36:29.456297      12 openstack.go:408] Error initialising Routes support: router-id not set in cloud provider config
W0217 02:36:29.456348      12 core.go:111] --configure-cloud-routes is set, but cloud provider does not support routes. Will not configure cloud provider routes.
W0217 02:36:29.456358      12 controllermanager.go:317] Skipping "node-route-controller"
I0217 02:36:29.456367      12 controllermanager.go:310] Starting "cloud-node-controller"
I0217 02:36:29.456833      12 controllermanager.go:329] Started "cloud-node-controller"
I0217 02:36:29.457231      12 controllermanager.go:310] Starting "cloud-node-lifecycle-controller"
I0217 02:36:29.457509      12 controllermanager.go:329] Started "cloud-node-lifecycle-controller"
I0217 02:36:29.456941      12 node_controller.go:176] Sending events to api server.
I0217 02:36:29.460307      12 node_controller.go:185] Waiting for informer caches to sync
I0217 02:36:29.461877      12 node_lifecycle_controller.go:112] Sending events to api server
I0217 02:36:29.467635      12 reflector.go:376] Caches populated for *v1.Service from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0217 02:36:29.468284      12 reflector.go:376] Caches populated for *v1.Node from k8s.io/client-go@v0.32.7/tools/cache/reflector.go:251
I0217 02:36:29.507679      12 shared_informer.go:320] Caches are synced for service
I0217 02:36:29.508035      12 controller.go:722] Syncing backends for all LB services.
I0217 02:36:29.508070      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:36:29.508154      12 controller.go:722] Syncing backends for all LB services.
I0217 02:36:29.508183      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:36:29.508255      12 controller.go:722] Syncing backends for all LB services.
I0217 02:36:29.508262      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:36:29.561468      12 node_controller.go:429] Initializing node kube-cwnyv-default-worker-nfxbn-9qn2b-57rnn with cloud provider
I0217 02:36:29.561540      12 node_controller.go:271] Update 3 nodes status took 128.759µs.
I0217 02:36:30.089666      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 02:36:30.089716      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 02:36:30.089723      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 02:36:30.089729      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 02:36:30.089736      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 02:36:30.089743      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 02:36:30.899138      12 controller.go:722] Syncing backends for all LB services.
I0217 02:36:30.899207      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:36:31.193688      12 node_controller.go:474] Successfully initialized node kube-cwnyv-default-worker-nfxbn-9qn2b-57rnn with cloud provider
I0217 02:36:31.193788      12 node_controller.go:429] Initializing node kube-cwnyv-default-worker-nfxbn-9qn2b-cwcbm with cloud provider
I0217 02:36:31.194324      12 event.go:389] "Event occurred" object="kube-cwnyv-default-worker-nfxbn-9qn2b-57rnn" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 02:36:32.751389      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 02:36:32.751457      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 02:36:32.751468      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 02:36:32.751478      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 02:36:32.751488      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 02:36:32.751497      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 02:36:32.933969      12 controller.go:722] Syncing backends for all LB services.
I0217 02:36:32.934025      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:36:33.565240      12 node_controller.go:474] Successfully initialized node kube-cwnyv-default-worker-nfxbn-9qn2b-cwcbm with cloud provider
I0217 02:36:33.565475      12 node_controller.go:429] Initializing node kube-cwnyv-thfz4-q556h with cloud provider
I0217 02:36:33.566043      12 event.go:389] "Event occurred" object="kube-cwnyv-default-worker-nfxbn-9qn2b-cwcbm" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 02:36:33.899653      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 02:36:33.899920      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 02:36:33.900203      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 02:36:33.900320      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 02:36:33.900405      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 02:36:33.900514      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 02:36:33.985325      12 controller.go:722] Syncing backends for all LB services.
I0217 02:36:33.985363      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:36:34.291538      12 node_controller.go:474] Successfully initialized node kube-cwnyv-thfz4-q556h with cloud provider
I0217 02:36:34.292002      12 event.go:389] "Event occurred" object="kube-cwnyv-thfz4-q556h" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
E0217 02:37:03.823654      12 leaderelection.go:429] Failed to update lock optimistically: the server was unable to return a response in the time allotted, but may still be processing the request (put leases.coordination.k8s.io cloud-controller-manager), falling back to slow path
I0217 02:41:30.705904      12 node_controller.go:271] Update 3 nodes status took 1.144180023s.
I0217 02:46:31.782251      12 node_controller.go:271] Update 3 nodes status took 1.075149331s.
I0217 02:51:33.081813      12 node_controller.go:271] Update 3 nodes status took 1.298622751s.
I0217 02:52:08.154862      12 controller.go:722] Syncing backends for all LB services.
I0217 02:52:08.155417      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:52:08.222124      12 controller.go:722] Syncing backends for all LB services.
I0217 02:52:08.223250      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:56:34.184503      12 node_controller.go:271] Update 3 nodes status took 1.101814536s.
I0217 03:01:35.437112      12 node_controller.go:271] Update 3 nodes status took 1.252466964s.
