2026/02/17 01:03:26 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=306085ad-7192-4f09-b105-bd9063b7d995,--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 01:03:26 Now listening for interrupts
I0217 01:03:27.096901      13 serving.go:386] Generated self-signed cert in-memory
I0217 01:03:28.127245      13 serving.go:386] Generated self-signed cert in-memory
W0217 01:03:28.127383      13 client_config.go:667] Neither --kubeconfig nor --master was specified.  Using the inClusterConfig.  This might not work.
I0217 01:03:29.163946      13 requestheader_controller.go:255] Loaded a new request header values for RequestHeaderAuthRequestController
I0217 01:03:29.207407      13 controllermanager.go:160] Version: v1.33.1
I0217 01:03:29.247958      13 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771290207\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771290206\" (2026-02-17 00:03:26 +0000 UTC to 2027-02-17 00:03:26 +0000 UTC (now=2026-02-17 01:03:29.247925553 +0000 UTC))"
I0217 01:03:29.248197      13 requestheader_controller.go:180] Starting RequestHeaderAuthRequestController
I0217 01:03:29.248403      13 shared_informer.go:350] "Waiting for caches to sync" controller="RequestHeaderAuthRequestController"
I0217 01:03:29.248362      13 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771290209\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771290208\" (2026-02-17 00:03:28 +0000 UTC to 2029-02-17 00:03:28 +0000 UTC (now=2026-02-17 01:03:29.248351528 +0000 UTC))"
I0217 01:03:29.248541      13 secure_serving.go:211] Serving securely on 127.0.0.1:10258
I0217 01:03:29.248417      13 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0217 01:03:29.248936      13 shared_informer.go:350] "Waiting for caches to sync" controller="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0217 01:03:29.249079      13 leaderelection.go:257] attempting to acquire leader lease kube-system/cloud-controller-manager...
I0217 01:03:29.249092      13 tlsconfig.go:243] "Starting DynamicServingCertificateController"
I0217 01:03:29.248366      13 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0217 01:03:29.250384      13 shared_informer.go:350] "Waiting for caches to sync" controller="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0217 01:03:29.251948      13 reflector.go:430] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.33.3/tools/cache/reflector.go:285"
I0217 01:03:29.255504      13 reflector.go:430] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.33.3/tools/cache/reflector.go:285"
I0217 01:03:29.255999      13 reflector.go:430] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.33.3/tools/cache/reflector.go:285"
I0217 01:03:29.349703      13 shared_informer.go:357] "Caches are synced" controller="RequestHeaderAuthRequestController"
I0217 01:03:29.350149      13 shared_informer.go:357] "Caches are synced" controller="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0217 01:03:29.350569      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-16 00:58:12 +0000 UTC to 2031-02-16 00:58:12 +0000 UTC (now=2026-02-17 01:03:29.350537059 +0000 UTC))"
I0217 01:03:29.351091      13 shared_informer.go:357] "Caches are synced" controller="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0217 01:03:29.351178      13 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771290207\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771290206\" (2026-02-17 00:03:26 +0000 UTC to 2027-02-17 00:03:26 +0000 UTC (now=2026-02-17 01:03:29.351157236 +0000 UTC))"
I0217 01:03:29.351625      13 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771290209\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771290208\" (2026-02-17 00:03:28 +0000 UTC to 2029-02-17 00:03:28 +0000 UTC (now=2026-02-17 01:03:29.351603311 +0000 UTC))"
I0217 01:03:29.351891      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-16 00:58:12 +0000 UTC to 2031-02-16 00:58:12 +0000 UTC (now=2026-02-17 01:03:29.351872814 +0000 UTC))"
I0217 01:03:29.351964      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-16 00:58:13 +0000 UTC to 2031-02-16 00:58:13 +0000 UTC (now=2026-02-17 01:03:29.351950335 +0000 UTC))"
I0217 01:03:29.352394      13 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771290207\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771290206\" (2026-02-17 00:03:26 +0000 UTC to 2027-02-17 00:03:26 +0000 UTC (now=2026-02-17 01:03:29.352375419 +0000 UTC))"
I0217 01:03:29.352754      13 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771290209\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771290208\" (2026-02-17 00:03:28 +0000 UTC to 2029-02-17 00:03:28 +0000 UTC (now=2026-02-17 01:03:29.352738853 +0000 UTC))"
I0217 01:03:29.373994      13 leaderelection.go:271] successfully acquired lease kube-system/cloud-controller-manager
I0217 01:03:29.374568      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-7xpwh-nq7b6-cz5hq_e19bacd8-1350-4898-8738-804c1b74a1a5 became leader"
I0217 01:03:29.381718      13 openstack.go:424] Setting up informers for Cloud
I0217 01:03:29.381789      13 controllermanager.go:310] Starting "node-route-controller"
W0217 01:03:29.425125      13 openstack.go:408] Error initialising Routes support: router-id not set in cloud provider config
W0217 01:03:29.425189      13 core.go:111] --configure-cloud-routes is set, but cloud provider does not support routes. Will not configure cloud provider routes.
W0217 01:03:29.425202      13 controllermanager.go:317] Skipping "node-route-controller"
I0217 01:03:29.425214      13 controllermanager.go:310] Starting "cloud-node-controller"
I0217 01:03:29.426737      13 controllermanager.go:329] Started "cloud-node-controller"
I0217 01:03:29.426794      13 controllermanager.go:310] Starting "cloud-node-lifecycle-controller"
I0217 01:03:29.426888      13 node_controller.go:176] Sending events to api server.
I0217 01:03:29.426999      13 node_controller.go:185] Waiting for informer caches to sync
I0217 01:03:29.427562      13 controllermanager.go:329] Started "cloud-node-lifecycle-controller"
I0217 01:03:29.427616      13 controllermanager.go:310] Starting "service-lb-controller"
I0217 01:03:29.427922      13 node_lifecycle_controller.go:112] Sending events to api server
I0217 01:03:29.428664      13 openstack.go:373] Claiming to support LoadBalancer
I0217 01:03:29.429015      13 controllermanager.go:329] Started "service-lb-controller"
I0217 01:03:29.429201      13 controller.go:234] Starting service controller
I0217 01:03:29.429259      13 shared_informer.go:350] "Waiting for caches to sync" controller="service"
I0217 01:03:29.439028      13 reflector.go:430] "Caches populated" type="*v1.Service" reflector="k8s.io/client-go@v0.33.3/tools/cache/reflector.go:285"
I0217 01:03:29.440483      13 reflector.go:430] "Caches populated" type="*v1.Node" reflector="k8s.io/client-go@v0.33.3/tools/cache/reflector.go:285"
I0217 01:03:29.528218      13 node_controller.go:271] Update 3 nodes status took 212.722µs.
I0217 01:03:29.528644      13 node_controller.go:429] Initializing node kube-7xpwh-default-worker-mrvjd-z6xqp-9dlsv with cloud provider
I0217 01:03:29.529750      13 shared_informer.go:357] "Caches are synced" controller="service"
I0217 01:03:29.530177      13 controller.go:722] Syncing backends for all LB services.
I0217 01:03:29.530382      13 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 01:03:29.530412      13 controller.go:722] Syncing backends for all LB services.
I0217 01:03:29.530420      13 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 01:03:29.530433      13 controller.go:722] Syncing backends for all LB services.
I0217 01:03:29.530440      13 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 01:03:30.083955      13 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 01:03:30.083998      13 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 01:03:30.084005      13 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 01:03:30.084012      13 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 01:03:30.084019      13 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 01:03:30.084025      13 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 01:03:30.215020      13 controller.go:722] Syncing backends for all LB services.
I0217 01:03:30.215075      13 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 01:03:30.291821      13 node_controller.go:474] Successfully initialized node kube-7xpwh-default-worker-mrvjd-z6xqp-9dlsv with cloud provider
I0217 01:03:30.291936      13 node_controller.go:429] Initializing node kube-7xpwh-default-worker-mrvjd-z6xqp-nxpg5 with cloud provider
I0217 01:03:30.292833      13 event.go:389] "Event occurred" object="kube-7xpwh-default-worker-mrvjd-z6xqp-9dlsv" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 01:03:30.860246      13 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 01:03:30.860485      13 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 01:03:30.860527      13 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 01:03:30.860594      13 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 01:03:30.860636      13 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 01:03:30.860752      13 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 01:03:31.095347      13 controller.go:722] Syncing backends for all LB services.
I0217 01:03:31.095377      13 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 01:03:31.368403      13 node_controller.go:474] Successfully initialized node kube-7xpwh-default-worker-mrvjd-z6xqp-nxpg5 with cloud provider
I0217 01:03:31.370535      13 node_controller.go:429] Initializing node kube-7xpwh-nq7b6-cz5hq with cloud provider
I0217 01:03:31.371105      13 event.go:389] "Event occurred" object="kube-7xpwh-default-worker-mrvjd-z6xqp-nxpg5" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 01:03:31.840576      13 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 01:03:31.840641      13 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 01:03:31.840652      13 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 01:03:31.840661      13 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 01:03:31.840670      13 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 01:03:31.841052      13 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 01:03:31.990819      13 controller.go:722] Syncing backends for all LB services.
I0217 01:03:31.990852      13 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 01:03:32.640417      13 node_controller.go:474] Successfully initialized node kube-7xpwh-nq7b6-cz5hq with cloud provider
I0217 01:03:32.645747      13 event.go:389] "Event occurred" object="kube-7xpwh-nq7b6-cz5hq" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 01:08:30.822368      13 node_controller.go:271] Update 3 nodes status took 1.29380982s.
I0217 01:13:32.118910      13 node_controller.go:271] Update 3 nodes status took 1.295929207s.
I0217 01:15:30.484047      13 controller.go:722] Syncing backends for all LB services.
I0217 01:15:30.484095      13 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 01:15:30.626201      13 controller.go:722] Syncing backends for all LB services.
I0217 01:15:30.626618      13 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 01:18:33.364582      13 node_controller.go:271] Update 3 nodes status took 1.243976506s.
I0217 01:23:34.367650      13 node_controller.go:271] Update 3 nodes status took 1.002312932s.
I0217 01:28:35.590673      13 node_controller.go:271] Update 3 nodes status took 1.222512134s.
