2026/02/16 19:51: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=d846d49d-dfc6-48ae-af24-35d3e0c16a76,--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 19:51:48 Now listening for interrupts
I0216 19:51:49.254260      13 serving.go:386] Generated self-signed cert in-memory
I0216 19:51:49.643627      13 serving.go:386] Generated self-signed cert in-memory
W0216 19:51:49.643689      13 client_config.go:667] Neither --kubeconfig nor --master was specified.  Using the inClusterConfig.  This might not work.
I0216 19:51:49.926404      13 requestheader_controller.go:255] Loaded a new request header values for RequestHeaderAuthRequestController
I0216 19:51:49.988870      13 controllermanager.go:160] Version: v1.34.1
I0216 19:51:49.996140      13 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771271509\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771271508\" (2026-02-16 18:51:48 +0000 UTC to 2027-02-16 18:51:48 +0000 UTC (now=2026-02-16 19:51:49.996107407 +0000 UTC))"
I0216 19:51:49.996558      13 requestheader_controller.go:180] Starting RequestHeaderAuthRequestController
I0216 19:51:49.996589      13 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0216 19:51:49.996640      13 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0216 19:51:49.996748      13 shared_informer.go:349] "Waiting for caches to sync" controller="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0216 19:51:49.996472      13 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771271509\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771271509\" (2026-02-16 18:51:49 +0000 UTC to 2029-02-16 18:51:49 +0000 UTC (now=2026-02-16 19:51:49.996457327 +0000 UTC))"
I0216 19:51:49.997454      13 secure_serving.go:211] Serving securely on 127.0.0.1:10258
I0216 19:51:49.996703      13 shared_informer.go:349] "Waiting for caches to sync" controller="RequestHeaderAuthRequestController"
I0216 19:51:49.997655      13 tlsconfig.go:243] "Starting DynamicServingCertificateController"
I0216 19:51:49.998000      13 leaderelection.go:257] attempting to acquire leader lease kube-system/cloud-controller-manager...
I0216 19:51:49.998653      13 shared_informer.go:349] "Waiting for caches to sync" controller="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0216 19:51:50.001366      13 reflector.go:436] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.34.1/tools/cache/reflector.go:290"
I0216 19:51:50.001444      13 reflector.go:436] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.34.1/tools/cache/reflector.go:290"
I0216 19:51:50.001902      13 reflector.go:436] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.34.1/tools/cache/reflector.go:290"
I0216 19:51:50.013448      13 leaderelection.go:271] successfully acquired lease kube-system/cloud-controller-manager
I0216 19:51:50.014194      13 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-rbivc-rjwmz-h2467_ba07d50e-6d66-4056-8faa-ea2567511d3a became leader"
I0216 19:51:50.027118      13 openstack.go:424] Setting up informers for Cloud
I0216 19:51:50.027357      13 controllermanager.go:310] Starting "node-route-controller"
W0216 19:51:50.089120      13 openstack.go:408] Error initialising Routes support: router-id not set in cloud provider config
W0216 19:51:50.089187      13 core.go:111] --configure-cloud-routes is set, but cloud provider does not support routes. Will not configure cloud provider routes.
W0216 19:51:50.089199      13 controllermanager.go:317] Skipping "node-route-controller"
I0216 19:51:50.089209      13 controllermanager.go:310] Starting "cloud-node-controller"
I0216 19:51:50.097392      13 shared_informer.go:356] "Caches are synced" controller="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0216 19:51:50.098073      13 shared_informer.go:356] "Caches are synced" controller="RequestHeaderAuthRequestController"
I0216 19:51:50.098174      13 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 19:45:07 +0000 UTC to 2031-02-15 19:45:07 +0000 UTC (now=2026-02-16 19:51:50.09815374 +0000 UTC))"
I0216 19:51:50.099036      13 shared_informer.go:356] "Caches are synced" controller="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0216 19:51:50.099533      13 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771271509\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771271508\" (2026-02-16 18:51:48 +0000 UTC to 2027-02-16 18:51:48 +0000 UTC (now=2026-02-16 19:51:50.099517587 +0000 UTC))"
I0216 19:51:50.100304      13 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771271509\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771271509\" (2026-02-16 18:51:49 +0000 UTC to 2029-02-16 18:51:49 +0000 UTC (now=2026-02-16 19:51:50.100227626 +0000 UTC))"
I0216 19:51:50.101257      13 controllermanager.go:329] Started "cloud-node-controller"
I0216 19:51:50.101299      13 controllermanager.go:310] Starting "cloud-node-lifecycle-controller"
I0216 19:51:50.102129      13 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 19:45:07 +0000 UTC to 2031-02-15 19:45:07 +0000 UTC (now=2026-02-16 19:51:50.102090475 +0000 UTC))"
I0216 19:51:50.102533      13 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 19:45:09 +0000 UTC to 2031-02-15 19:45:09 +0000 UTC (now=2026-02-16 19:51:50.102509546 +0000 UTC))"
I0216 19:51:50.103181      13 node_controller.go:176] Sending events to api server.
I0216 19:51:50.103819      13 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771271509\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771271508\" (2026-02-16 18:51:48 +0000 UTC to 2027-02-16 18:51:48 +0000 UTC (now=2026-02-16 19:51:50.10379309 +0000 UTC))"
I0216 19:51:50.104119      13 node_controller.go:185] Waiting for informer caches to sync
I0216 19:51:50.105238      13 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771271509\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771271509\" (2026-02-16 18:51:49 +0000 UTC to 2029-02-16 18:51:49 +0000 UTC (now=2026-02-16 19:51:50.105218828 +0000 UTC))"
I0216 19:51:50.115284      13 controllermanager.go:329] Started "cloud-node-lifecycle-controller"
I0216 19:51:50.115351      13 controllermanager.go:310] Starting "service-lb-controller"
I0216 19:51:50.115418      13 node_lifecycle_controller.go:112] Sending events to api server
I0216 19:51:50.146275      13 openstack.go:373] Claiming to support LoadBalancer
I0216 19:51:50.147029      13 controllermanager.go:329] Started "service-lb-controller"
I0216 19:51:50.150355      13 controller.go:235] Starting service controller
I0216 19:51:50.151677      13 shared_informer.go:349] "Waiting for caches to sync" controller="service"
I0216 19:51:50.153552      13 reflector.go:436] "Caches populated" type="*v1.Node" reflector="k8s.io/client-go@v0.34.1/tools/cache/reflector.go:290"
I0216 19:51:50.155434      13 reflector.go:436] "Caches populated" type="*v1.Service" reflector="k8s.io/client-go@v0.34.1/tools/cache/reflector.go:290"
I0216 19:51:50.205240      13 node_controller.go:271] Update 3 nodes status took 210.286µs.
I0216 19:51:50.205340      13 node_controller.go:429] Initializing node kube-rbivc-default-worker-s74kz-nh95p-5zfzs with cloud provider
I0216 19:51:50.253866      13 shared_informer.go:356] "Caches are synced" controller="service"
I0216 19:51:50.253943      13 controller.go:723] Syncing backends for all LB services.
I0216 19:51:50.253958      13 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 19:51:50.253980      13 controller.go:723] Syncing backends for all LB services.
I0216 19:51:50.253988      13 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 19:51:50.253999      13 controller.go:723] Syncing backends for all LB services.
I0216 19:51:50.254006      13 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 19:51:50.806174      13 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0216 19:51:50.806265      13 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0216 19:51:50.806282      13 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0216 19:51:50.806295      13 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0216 19:51:50.806308      13 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0216 19:51:50.806323      13 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0216 19:51:50.827428      13 controller.go:723] Syncing backends for all LB services.
I0216 19:51:50.827500      13 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 19:51:50.903635      13 node_controller.go:474] Successfully initialized node kube-rbivc-default-worker-s74kz-nh95p-5zfzs with cloud provider
I0216 19:51:50.903745      13 node_controller.go:429] Initializing node kube-rbivc-default-worker-s74kz-nh95p-xtk5g with cloud provider
I0216 19:51:50.909146      13 event.go:389] "Event occurred" object="kube-rbivc-default-worker-s74kz-nh95p-5zfzs" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0216 19:51:51.354064      13 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0216 19:51:51.354114      13 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0216 19:51:51.354122      13 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0216 19:51:51.354127      13 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0216 19:51:51.354134      13 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0216 19:51:51.354140      13 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0216 19:51:51.375435      13 controller.go:723] Syncing backends for all LB services.
I0216 19:51:51.375483      13 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 19:51:51.420405      13 node_controller.go:474] Successfully initialized node kube-rbivc-default-worker-s74kz-nh95p-xtk5g with cloud provider
I0216 19:51:51.420499      13 node_controller.go:429] Initializing node kube-rbivc-rjwmz-h2467 with cloud provider
I0216 19:51:51.421169      13 event.go:389] "Event occurred" object="kube-rbivc-default-worker-s74kz-nh95p-xtk5g" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0216 19:51:51.886811      13 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0216 19:51:51.887071      13 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0216 19:51:51.887148      13 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0216 19:51:51.887180      13 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0216 19:51:51.887242      13 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0216 19:51:51.887292      13 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0216 19:51:51.904266      13 controller.go:723] Syncing backends for all LB services.
I0216 19:51:51.904443      13 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 19:51:51.978385      13 node_controller.go:474] Successfully initialized node kube-rbivc-rjwmz-h2467 with cloud provider
I0216 19:51:51.979314      13 event.go:389] "Event occurred" object="kube-rbivc-rjwmz-h2467" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0216 19:56:13.983396      13 controller.go:723] Syncing backends for all LB services.
I0216 19:56:13.996384      13 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 19:56:14.106219      13 controller.go:723] Syncing backends for all LB services.
I0216 19:56:14.106810      13 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0216 19:56:51.461776      13 node_controller.go:271] Update 3 nodes status took 1.255518746s.
I0216 20:01:52.545290      13 node_controller.go:271] Update 3 nodes status took 1.082928314s.
I0216 20:06:53.749497      13 node_controller.go:271] Update 3 nodes status took 1.203430821s.
I0216 20:11:54.763124      13 node_controller.go:271] Update 3 nodes status took 1.013417505s.
