2026/02/17 02:36:16 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=3c95cb6e-0275-4c2a-becf-f5d4371bd9cd,--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:16 Now listening for interrupts
I0217 02:36:16.943313      12 serving.go:386] Generated self-signed cert in-memory
I0217 02:36:17.083037      12 serving.go:386] Generated self-signed cert in-memory
W0217 02:36:17.083086      12 client_config.go:682] Neither --kubeconfig nor --master was specified.  Using the inClusterConfig.  This might not work.
I0217 02:36:17.412069      12 requestheader_controller.go:255] Loaded a new request header values for RequestHeaderAuthRequestController
I0217 02:36:17.472626      12 controllermanager.go:160] Version: v1.35.0
I0217 02:36:17.711948      12 requestheader_controller.go:180] Starting RequestHeaderAuthRequestController
I0217 02:36:17.712526      12 shared_informer.go:370] "Waiting for caches to sync"
I0217 02:36:17.712988      12 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0217 02:36:17.714225      12 shared_informer.go:370] "Waiting for caches to sync"
I0217 02:36:17.713372      12 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0217 02:36:17.715089      12 shared_informer.go:370] "Waiting for caches to sync"
I0217 02:36:17.716763      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771295776\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771295776\" (2026-02-17 01:36:16 +0000 UTC to 2027-02-17 01:36:16 +0000 UTC (now=2026-02-17 02:36:17.715638494 +0000 UTC))"
I0217 02:36:17.718295      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771295777\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771295777\" (2026-02-17 01:36:17 +0000 UTC to 2029-02-17 01:36:17 +0000 UTC (now=2026-02-17 02:36:17.71806169 +0000 UTC))"
I0217 02:36:17.718646      12 secure_serving.go:211] Serving securely on 127.0.0.1:10258
I0217 02:36:17.719067      12 reflector.go:446] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.35.0/tools/cache/reflector.go:289"
I0217 02:36:17.718991      12 tlsconfig.go:243] "Starting DynamicServingCertificateController"
I0217 02:36:17.720540      12 reflector.go:446] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.35.0/tools/cache/reflector.go:289"
I0217 02:36:17.720979      12 reflector.go:446] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.35.0/tools/cache/reflector.go:289"
I0217 02:36:17.722263      12 leaderelection.go:258] "Attempting to acquire leader lease..." lock="kube-system/cloud-controller-manager"
I0217 02:36:17.790029      12 leaderelection.go:272] "Successfully acquired lease" lock="kube-system/cloud-controller-manager"
I0217 02:36:17.790534      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-k1xih-pt4gp-drcgv_e0feebe7-d420-4471-9248-1aa2afe3490d became leader"
I0217 02:36:17.796472      12 openstack.go:424] Setting up informers for Cloud
I0217 02:36:17.796673      12 controllermanager.go:310] Starting "cloud-node-controller"
I0217 02:36:17.807282      12 controllermanager.go:329] Started "cloud-node-controller"
I0217 02:36:17.807661      12 controllermanager.go:310] Starting "cloud-node-lifecycle-controller"
I0217 02:36:17.807567      12 node_controller.go:176] Sending events to api server.
I0217 02:36:17.808662      12 node_controller.go:185] Waiting for informer caches to sync
I0217 02:36:17.813306      12 shared_informer.go:377] "Caches are synced"
I0217 02:36:17.815350      12 shared_informer.go:377] "Caches are synced"
I0217 02:36:17.815654      12 shared_informer.go:377] "Caches are synced"
I0217 02:36:17.816165      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:29:24 +0000 UTC to 2031-02-16 02:29:24 +0000 UTC (now=2026-02-17 02:36:17.816134739 +0000 UTC))"
I0217 02:36:17.816762      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771295776\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771295776\" (2026-02-17 01:36:16 +0000 UTC to 2027-02-17 01:36:16 +0000 UTC (now=2026-02-17 02:36:17.816741558 +0000 UTC))"
I0217 02:36:17.817767      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771295777\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771295777\" (2026-02-17 01:36:17 +0000 UTC to 2029-02-17 01:36:17 +0000 UTC (now=2026-02-17 02:36:17.81774164 +0000 UTC))"
I0217 02:36:17.817922      12 controllermanager.go:329] Started "cloud-node-lifecycle-controller"
I0217 02:36:17.817945      12 controllermanager.go:310] Starting "service-lb-controller"
I0217 02:36:17.818015      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:29:23 +0000 UTC to 2031-02-16 02:29:23 +0000 UTC (now=2026-02-17 02:36:17.817991267 +0000 UTC))"
I0217 02:36:17.818867      12 node_lifecycle_controller.go:112] Sending events to api server
I0217 02:36:17.818244      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:29:24 +0000 UTC to 2031-02-16 02:29:24 +0000 UTC (now=2026-02-17 02:36:17.818223895 +0000 UTC))"
I0217 02:36:17.820391      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771295776\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771295776\" (2026-02-17 01:36:16 +0000 UTC to 2027-02-17 01:36:16 +0000 UTC (now=2026-02-17 02:36:17.819924677 +0000 UTC))"
I0217 02:36:17.821171      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771295777\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771295777\" (2026-02-17 01:36:17 +0000 UTC to 2029-02-17 01:36:17 +0000 UTC (now=2026-02-17 02:36:17.821149226 +0000 UTC))"
I0217 02:36:18.141726      12 openstack.go:373] Claiming to support LoadBalancer
I0217 02:36:18.141807      12 controllermanager.go:329] Started "service-lb-controller"
I0217 02:36:18.141817      12 controllermanager.go:310] Starting "node-route-controller"
I0217 02:36:18.142496      12 controller.go:235] Starting service controller
I0217 02:36:18.142550      12 shared_informer.go:370] "Waiting for caches to sync"
W0217 02:36:18.215885      12 openstack.go:408] Error initialising Routes support: router-id not set in cloud provider config
W0217 02:36:18.215972      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:18.215998      12 controllermanager.go:317] Skipping "node-route-controller"
I0217 02:36:18.224257      12 reflector.go:446] "Caches populated" type="*v1.Node" reflector="k8s.io/client-go@v0.35.0/tools/cache/reflector.go:289"
I0217 02:36:18.224394      12 reflector.go:446] "Caches populated" type="*v1.Service" reflector="k8s.io/client-go@v0.35.0/tools/cache/reflector.go:289"
I0217 02:36:18.242874      12 shared_informer.go:377] "Caches are synced"
I0217 02:36:18.242985      12 controller.go:723] Syncing backends for all LB services.
I0217 02:36:18.243002      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:36:18.313030      12 node_controller.go:429] Initializing node kube-k1xih-pt4gp-drcgv with cloud provider
I0217 02:36:18.313337      12 node_controller.go:271] Update 1 nodes status took 108.163µs.
I0217 02:36:18.848684      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 02:36:18.848723      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 02:36:18.848729      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 02:36:18.848734      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 02:36:18.848739      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 02:36:18.848744      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 02:36:18.942071      12 controller.go:723] Syncing backends for all LB services.
I0217 02:36:18.942126      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:36:19.152900      12 node_controller.go:474] Successfully initialized node kube-k1xih-pt4gp-drcgv with cloud provider
I0217 02:36:19.153531      12 event.go:389] "Event occurred" object="kube-k1xih-pt4gp-drcgv" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 02:36:26.982590      12 controller.go:723] Syncing backends for all LB services.
I0217 02:36:26.982609      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:36:26.982633      12 node_controller.go:429] Initializing node kube-k1xih-default-worker-9txdx-kf4b4-wqzkr with cloud provider
I0217 02:36:27.064659      12 controller.go:723] Syncing backends for all LB services.
I0217 02:36:27.064705      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:36:27.412721      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 02:36:27.412926      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 02:36:27.412966      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 02:36:27.413000      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 02:36:27.413032      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 02:36:27.413064      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 02:36:27.507835      12 controller.go:723] Syncing backends for all LB services.
I0217 02:36:27.507894      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:36:27.885200      12 node_controller.go:474] Successfully initialized node kube-k1xih-default-worker-9txdx-kf4b4-wqzkr with cloud provider
I0217 02:36:27.885909      12 node_controller.go:429] Initializing node kube-k1xih-default-worker-9txdx-kf4b4-qp5zp with cloud provider
I0217 02:36:27.886654      12 event.go:389] "Event occurred" object="kube-k1xih-default-worker-9txdx-kf4b4-wqzkr" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 02:36:28.362536      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 02:36:28.362566      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 02:36:28.362573      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 02:36:28.362579      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 02:36:28.362587      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 02:36:28.362594      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 02:36:28.375882      12 controller.go:723] Syncing backends for all LB services.
I0217 02:36:28.376893      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:36:28.395328      12 node_controller.go:474] Successfully initialized node kube-k1xih-default-worker-9txdx-kf4b4-qp5zp with cloud provider
I0217 02:36:28.395967      12 event.go:389] "Event occurred" object="kube-k1xih-default-worker-9txdx-kf4b4-qp5zp" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
E0217 02:39:36.785324      12 leaderelection.go:445] "Failed to update lease optimistically, falling back to slow path" err="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)" lock="kube-system/cloud-controller-manager"
I0217 02:41:19.637164      12 node_controller.go:271] Update 3 nodes status took 1.322858632s.
I0217 02:46:20.644909      12 node_controller.go:271] Update 3 nodes status took 1.006784605s.
I0217 02:51:21.954879      12 node_controller.go:271] Update 3 nodes status took 1.309671893s.
I0217 02:56:16.438886      12 controller.go:723] Syncing backends for all LB services.
I0217 02:56:16.438957      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:56:16.507834      12 controller.go:723] Syncing backends for all LB services.
I0217 02:56:16.507899      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:56:22.962738      12 node_controller.go:271] Update 3 nodes status took 1.006717644s.
I0217 03:01:24.227173      12 node_controller.go:271] Update 3 nodes status took 1.263446878s.
I0217 03:06:25.324160      12 node_controller.go:271] Update 3 nodes status took 1.096647182s.
