2026/02/16 21:20:49 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=822f9ae4-58e9-4971-99d4-48f62c0ad415,--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 21:20:49 Now listening for interrupts
I0216 21:20:49.588533      12 serving.go:386] Generated self-signed cert in-memory
I0216 21:20:49.776384      12 serving.go:386] Generated self-signed cert in-memory
W0216 21:20:49.776587      12 client_config.go:667] Neither --kubeconfig nor --master was specified.  Using the inClusterConfig.  This might not work.
I0216 21:20:50.233471      12 requestheader_controller.go:255] Loaded a new request header values for RequestHeaderAuthRequestController
I0216 21:20:50.293850      12 controllermanager.go:160] Version: v1.34.1
I0216 21:20:50.308881      12 requestheader_controller.go:180] Starting RequestHeaderAuthRequestController
I0216 21:20:50.309024      12 shared_informer.go:349] "Waiting for caches to sync" controller="RequestHeaderAuthRequestController"
I0216 21:20:50.309116      12 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0216 21:20:50.309183      12 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0216 21:20:50.309196      12 shared_informer.go:349] "Waiting for caches to sync" controller="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0216 21:20:50.309238      12 shared_informer.go:349] "Waiting for caches to sync" controller="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0216 21:20:50.313869      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771276849\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771276849\" (2026-02-16 20:20:49 +0000 UTC to 2027-02-16 20:20:49 +0000 UTC (now=2026-02-16 21:20:50.313760042 +0000 UTC))"
I0216 21:20:50.314291      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771276850\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771276850\" (2026-02-16 20:20:49 +0000 UTC to 2029-02-16 20:20:49 +0000 UTC (now=2026-02-16 21:20:50.314238316 +0000 UTC))"
I0216 21:20:50.314429      12 secure_serving.go:211] Serving securely on 127.0.0.1:10258
I0216 21:20:50.314894      12 leaderelection.go:257] attempting to acquire leader lease kube-system/cloud-controller-manager...
I0216 21:20:50.315438      12 tlsconfig.go:243] "Starting DynamicServingCertificateController"
I0216 21:20:50.321264      12 reflector.go:436] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.34.1/tools/cache/reflector.go:290"
I0216 21:20:50.321617      12 reflector.go:436] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.34.1/tools/cache/reflector.go:290"
I0216 21:20:50.321284      12 reflector.go:436] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.34.1/tools/cache/reflector.go:290"
I0216 21:20:50.340745      12 leaderelection.go:271] successfully acquired lease kube-system/cloud-controller-manager
I0216 21:20:50.341065      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-kqj1l-wv7p8-tp9g6_a7618595-27a5-4688-acb2-f58884350a19 became leader"
I0216 21:20:50.346995      12 openstack.go:424] Setting up informers for Cloud
I0216 21:20:50.347077      12 controllermanager.go:310] Starting "service-lb-controller"
I0216 21:20:50.410057      12 shared_informer.go:356] "Caches are synced" controller="RequestHeaderAuthRequestController"
I0216 21:20:50.413610      12 shared_informer.go:356] "Caches are synced" controller="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0216 21:20:50.413922      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 21:15:51 +0000 UTC to 2031-02-15 21:15:51 +0000 UTC (now=2026-02-16 21:20:50.413896781 +0000 UTC))"
I0216 21:20:50.414277      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771276849\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771276849\" (2026-02-16 20:20:49 +0000 UTC to 2027-02-16 20:20:49 +0000 UTC (now=2026-02-16 21:20:50.414234344 +0000 UTC))"
I0216 21:20:50.415230      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771276850\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771276850\" (2026-02-16 20:20:49 +0000 UTC to 2029-02-16 20:20:49 +0000 UTC (now=2026-02-16 21:20:50.415112172 +0000 UTC))"
I0216 21:20:50.420805      12 shared_informer.go:356] "Caches are synced" controller="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0216 21:20:50.422799      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 21:15:49 +0000 UTC to 2031-02-15 21:15:49 +0000 UTC (now=2026-02-16 21:20:50.422777812 +0000 UTC))"
I0216 21:20:50.422834      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 21:15:51 +0000 UTC to 2031-02-15 21:15:51 +0000 UTC (now=2026-02-16 21:20:50.422826073 +0000 UTC))"
I0216 21:20:50.423041      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771276849\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771276849\" (2026-02-16 20:20:49 +0000 UTC to 2027-02-16 20:20:49 +0000 UTC (now=2026-02-16 21:20:50.423032594 +0000 UTC))"
I0216 21:20:50.423318      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771276850\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771276850\" (2026-02-16 20:20:49 +0000 UTC to 2029-02-16 20:20:49 +0000 UTC (now=2026-02-16 21:20:50.423306307 +0000 UTC))"
I0216 21:20:50.781035      12 openstack.go:373] Claiming to support LoadBalancer
I0216 21:20:50.781181      12 controllermanager.go:329] Started "service-lb-controller"
I0216 21:20:50.781196      12 controllermanager.go:310] Starting "node-route-controller"
I0216 21:20:50.781821      12 controller.go:235] Starting service controller
I0216 21:20:50.781861      12 shared_informer.go:349] "Waiting for caches to sync" controller="service"
W0216 21:20:50.837440      12 openstack.go:408] Error initialising Routes support: router-id not set in cloud provider config
W0216 21:20:50.837500      12 core.go:111] --configure-cloud-routes is set, but cloud provider does not support routes. Will not configure cloud provider routes.
W0216 21:20:50.837512      12 controllermanager.go:317] Skipping "node-route-controller"
I0216 21:20:50.837523      12 controllermanager.go:310] Starting "cloud-node-controller"
I0216 21:20:50.849221      12 controllermanager.go:329] Started "cloud-node-controller"
I0216 21:20:50.849289      12 controllermanager.go:310] Starting "cloud-node-lifecycle-controller"
I0216 21:20:50.850517      12 node_controller.go:176] Sending events to api server.
I0216 21:20:50.850811      12 node_controller.go:185] Waiting for informer caches to sync
I0216 21:20:50.876037      12 controllermanager.go:329] Started "cloud-node-lifecycle-controller"
I0216 21:20:50.878195      12 node_lifecycle_controller.go:112] Sending events to api server
I0216 21:20:50.883210      12 reflector.go:436] "Caches populated" type="*v1.Service" reflector="k8s.io/client-go@v0.34.1/tools/cache/reflector.go:290"
I0216 21:20:50.884090      12 reflector.go:436] "Caches populated" type="*v1.Node" reflector="k8s.io/client-go@v0.34.1/tools/cache/reflector.go:290"
I0216 21:20:50.952254      12 node_controller.go:429] Initializing node kube-kqj1l-wv7p8-tp9g6 with cloud provider
I0216 21:20:50.952837      12 node_controller.go:271] Update 1 nodes status took 552.535µs.
I0216 21:20:50.982808      12 shared_informer.go:356] "Caches are synced" controller="service"
I0216 21:20:50.982915      12 controller.go:723] Syncing backends for all LB services.
I0216 21:20:50.982937      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 21:20:51.549923      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0216 21:20:51.549962      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0216 21:20:51.549967      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0216 21:20:51.549971      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0216 21:20:51.549976      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0216 21:20:51.549981      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0216 21:20:51.615458      12 controller.go:723] Syncing backends for all LB services.
I0216 21:20:51.615506      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 21:20:54.057652      12 node_controller.go:474] Successfully initialized node kube-kqj1l-wv7p8-tp9g6 with cloud provider
I0216 21:20:54.058437      12 event.go:389] "Event occurred" object="kube-kqj1l-wv7p8-tp9g6" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0216 21:21:28.940074      12 node_controller.go:429] Initializing node kube-kqj1l-default-worker-4jxs5-gztrb-qqz5b with cloud provider
I0216 21:21:28.940801      12 controller.go:723] Syncing backends for all LB services.
I0216 21:21:28.941966      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 21:21:29.109944      12 controller.go:723] Syncing backends for all LB services.
I0216 21:21:29.111554      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 21:21:29.585995      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0216 21:21:29.586380      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0216 21:21:29.586410      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0216 21:21:29.586467      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0216 21:21:29.586589      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0216 21:21:29.586697      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0216 21:21:29.661456      12 controller.go:723] Syncing backends for all LB services.
I0216 21:21:29.661603      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 21:21:29.683152      12 node_controller.go:474] Successfully initialized node kube-kqj1l-default-worker-4jxs5-gztrb-qqz5b with cloud provider
I0216 21:21:29.683577      12 node_controller.go:429] Initializing node kube-kqj1l-default-worker-4jxs5-gztrb-b9kvv with cloud provider
I0216 21:21:29.684701      12 event.go:389] "Event occurred" object="kube-kqj1l-default-worker-4jxs5-gztrb-qqz5b" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0216 21:21:30.156308      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0216 21:21:30.156359      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0216 21:21:30.156366      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0216 21:21:30.156372      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0216 21:21:30.156407      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0216 21:21:30.156417      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0216 21:21:30.256917      12 controller.go:723] Syncing backends for all LB services.
I0216 21:21:30.257408      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 21:21:30.285062      12 node_controller.go:474] Successfully initialized node kube-kqj1l-default-worker-4jxs5-gztrb-b9kvv with cloud provider
I0216 21:21:30.285709      12 event.go:389] "Event occurred" object="kube-kqj1l-default-worker-4jxs5-gztrb-b9kvv" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
E0216 21:22:14.978727      12 leaderelection.go:441] 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
E0216 21:22:17.247660      12 leaderelection.go:484] Failed to update lock: Operation cannot be fulfilled on leases.coordination.k8s.io "cloud-controller-manager": the object has been modified; please apply your changes to the latest version and try again
E0216 21:22:17.647956      12 leaderelection.go:441] Failed to update lock optimistically: Operation cannot be fulfilled on leases.coordination.k8s.io "cloud-controller-manager": the object has been modified; please apply your changes to the latest version and try again, falling back to slow path
I0216 21:25:52.185215      12 node_controller.go:271] Update 3 nodes status took 1.231645716s.
I0216 21:30:53.263612      12 node_controller.go:271] Update 3 nodes status took 1.078035797s.
I0216 21:35:54.598215      12 node_controller.go:271] Update 3 nodes status took 1.334241124s.
I0216 21:40:55.834977      12 node_controller.go:271] Update 3 nodes status took 1.236529587s.
I0216 21:45:15.183789      12 controller.go:723] Syncing backends for all LB services.
I0216 21:45:15.183848      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 21:45:15.288974      12 controller.go:723] Syncing backends for all LB services.
I0216 21:45:15.289008      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 21:45:57.116207      12 node_controller.go:271] Update 3 nodes status took 1.28021998s.
