2026/02/17 02:37: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=552c8a9c-40cc-4834-814a-f1a9f33cbb1d,--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:37:48 Now listening for interrupts
I0217 02:37:49.245677      12 serving.go:386] Generated self-signed cert in-memory
I0217 02:37:49.509766      12 serving.go:386] Generated self-signed cert in-memory
W0217 02:37:49.509918      12 client_config.go:682] Neither --kubeconfig nor --master was specified.  Using the inClusterConfig.  This might not work.
I0217 02:37:49.979665      12 requestheader_controller.go:255] Loaded a new request header values for RequestHeaderAuthRequestController
I0217 02:37:50.056747      12 controllermanager.go:160] Version: v1.35.0
I0217 02:37:50.062287      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771295869\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771295869\" (2026-02-17 01:37:48 +0000 UTC to 2027-02-17 01:37:48 +0000 UTC (now=2026-02-17 02:37:50.062192665 +0000 UTC))"
I0217 02:37:50.063062      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771295869\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771295869\" (2026-02-17 01:37:49 +0000 UTC to 2029-02-17 01:37:49 +0000 UTC (now=2026-02-17 02:37:50.063043648 +0000 UTC))"
I0217 02:37:50.063130      12 secure_serving.go:211] Serving securely on 127.0.0.1:10258
I0217 02:37:50.063376      12 tlsconfig.go:243] "Starting DynamicServingCertificateController"
I0217 02:37:50.063472      12 requestheader_controller.go:180] Starting RequestHeaderAuthRequestController
I0217 02:37:50.063498      12 shared_informer.go:370] "Waiting for caches to sync"
I0217 02:37:50.063594      12 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0217 02:37:50.063609      12 shared_informer.go:370] "Waiting for caches to sync"
I0217 02:37:50.063628      12 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0217 02:37:50.063636      12 shared_informer.go:370] "Waiting for caches to sync"
I0217 02:37:50.063745      12 leaderelection.go:258] "Attempting to acquire leader lease..." lock="kube-system/cloud-controller-manager"
I0217 02:37:50.075119      12 reflector.go:446] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.35.0/tools/cache/reflector.go:289"
I0217 02:37:50.075340      12 reflector.go:446] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.35.0/tools/cache/reflector.go:289"
I0217 02:37:50.076767      12 reflector.go:446] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.35.0/tools/cache/reflector.go:289"
I0217 02:37:50.085941      12 leaderelection.go:272] "Successfully acquired lease" lock="kube-system/cloud-controller-manager"
I0217 02:37:50.086500      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-tzajw-v45zk-5tqp9_2f6f0dab-8adb-4c89-806c-a9b4965552af became leader"
I0217 02:37:50.102821      12 openstack.go:424] Setting up informers for Cloud
I0217 02:37:50.102875      12 controllermanager.go:310] Starting "cloud-node-controller"
I0217 02:37:50.122333      12 controllermanager.go:329] Started "cloud-node-controller"
I0217 02:37:50.122369      12 controllermanager.go:310] Starting "cloud-node-lifecycle-controller"
I0217 02:37:50.122925      12 node_controller.go:176] Sending events to api server.
I0217 02:37:50.122985      12 node_controller.go:185] Waiting for informer caches to sync
I0217 02:37:50.133741      12 controllermanager.go:329] Started "cloud-node-lifecycle-controller"
I0217 02:37:50.133788      12 controllermanager.go:310] Starting "service-lb-controller"
I0217 02:37:50.134560      12 node_lifecycle_controller.go:112] Sending events to api server
I0217 02:37:50.163854      12 shared_informer.go:377] "Caches are synced"
I0217 02:37:50.164047      12 shared_informer.go:377] "Caches are synced"
I0217 02:37:50.164070      12 shared_informer.go:377] "Caches are synced"
I0217 02:37:50.164253      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:23 +0000 UTC to 2031-02-16 02:30:23 +0000 UTC (now=2026-02-17 02:37:50.164231613 +0000 UTC))"
I0217 02:37:50.164663      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771295869\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771295869\" (2026-02-17 01:37:48 +0000 UTC to 2027-02-17 01:37:48 +0000 UTC (now=2026-02-17 02:37:50.164616754 +0000 UTC))"
I0217 02:37:50.164959      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771295869\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771295869\" (2026-02-17 01:37:49 +0000 UTC to 2029-02-17 01:37:49 +0000 UTC (now=2026-02-17 02:37:50.164944046 +0000 UTC))"
I0217 02:37:50.165644      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:23 +0000 UTC to 2031-02-16 02:30:23 +0000 UTC (now=2026-02-17 02:37:50.165522129 +0000 UTC))"
I0217 02:37:50.165994      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:30:25 +0000 UTC to 2031-02-16 02:30:25 +0000 UTC (now=2026-02-17 02:37:50.16587056 +0000 UTC))"
I0217 02:37:50.167517      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771295869\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771295869\" (2026-02-17 01:37:48 +0000 UTC to 2027-02-17 01:37:48 +0000 UTC (now=2026-02-17 02:37:50.167505967 +0000 UTC))"
I0217 02:37:50.168111      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771295869\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771295869\" (2026-02-17 01:37:49 +0000 UTC to 2029-02-17 01:37:49 +0000 UTC (now=2026-02-17 02:37:50.168097651 +0000 UTC))"
I0217 02:37:50.414037      12 openstack.go:373] Claiming to support LoadBalancer
I0217 02:37:50.414336      12 controllermanager.go:329] Started "service-lb-controller"
I0217 02:37:50.414461      12 controllermanager.go:310] Starting "node-route-controller"
I0217 02:37:50.414881      12 controller.go:235] Starting service controller
I0217 02:37:50.415150      12 shared_informer.go:370] "Waiting for caches to sync"
W0217 02:37:50.438896      12 openstack.go:408] Error initialising Routes support: router-id not set in cloud provider config
W0217 02:37:50.438986      12 core.go:111] --configure-cloud-routes is set, but cloud provider does not support routes. Will not configure cloud provider routes.
W0217 02:37:50.438998      12 controllermanager.go:317] Skipping "node-route-controller"
I0217 02:37:50.550562      12 reflector.go:446] "Caches populated" type="*v1.Service" reflector="k8s.io/client-go@v0.35.0/tools/cache/reflector.go:289"
I0217 02:37:50.553150      12 reflector.go:446] "Caches populated" type="*v1.Node" reflector="k8s.io/client-go@v0.35.0/tools/cache/reflector.go:289"
I0217 02:37:50.617453      12 shared_informer.go:377] "Caches are synced"
I0217 02:37:50.617672      12 controller.go:723] Syncing backends for all LB services.
I0217 02:37:50.617693      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:37:50.617714      12 controller.go:723] Syncing backends for all LB services.
I0217 02:37:50.617995      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:37:50.618051      12 controller.go:723] Syncing backends for all LB services.
I0217 02:37:50.618061      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:37:50.623170      12 node_controller.go:429] Initializing node kube-tzajw-default-worker-h9mkf-ghxkh-ht8wb with cloud provider
I0217 02:37:50.623456      12 node_controller.go:271] Update 3 nodes status took 227.671µs.
I0217 02:37:51.195888      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 02:37:51.196085      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 02:37:51.196461      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 02:37:51.196470      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 02:37:51.196478      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 02:37:51.196486      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 02:37:51.766754      12 controller.go:723] Syncing backends for all LB services.
I0217 02:37:51.766824      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:37:51.857448      12 node_controller.go:474] Successfully initialized node kube-tzajw-default-worker-h9mkf-ghxkh-ht8wb with cloud provider
I0217 02:37:51.857746      12 node_controller.go:429] Initializing node kube-tzajw-default-worker-h9mkf-ghxkh-kdh74 with cloud provider
I0217 02:37:51.859122      12 event.go:389] "Event occurred" object="kube-tzajw-default-worker-h9mkf-ghxkh-ht8wb" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 02:37:52.393315      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 02:37:52.393763      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 02:37:52.393883      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 02:37:52.394049      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 02:37:52.394324      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 02:37:52.394440      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 02:37:52.412662      12 controller.go:723] Syncing backends for all LB services.
I0217 02:37:52.413535      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:37:52.466277      12 node_controller.go:474] Successfully initialized node kube-tzajw-default-worker-h9mkf-ghxkh-kdh74 with cloud provider
I0217 02:37:52.466599      12 node_controller.go:429] Initializing node kube-tzajw-v45zk-5tqp9 with cloud provider
I0217 02:37:52.467506      12 event.go:389] "Event occurred" object="kube-tzajw-default-worker-h9mkf-ghxkh-kdh74" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 02:37:52.850220      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 02:37:52.850841      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 02:37:52.851415      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 02:37:52.851661      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 02:37:52.852127      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 02:37:52.852441      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 02:37:52.886994      12 controller.go:723] Syncing backends for all LB services.
I0217 02:37:52.887080      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:37:53.022281      12 node_controller.go:474] Successfully initialized node kube-tzajw-v45zk-5tqp9 with cloud provider
I0217 02:37:53.022814      12 event.go:389] "Event occurred" object="kube-tzajw-v45zk-5tqp9" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
E0217 02:38:45.001388      12 leaderelection.go:445] "Failed to update lease optimistically, falling back to slow path" err="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)" lock="kube-system/cloud-controller-manager"
I0217 02:42:51.825210      12 node_controller.go:271] Update 3 nodes status took 1.20145808s.
I0217 02:47:52.834313      12 node_controller.go:271] Update 3 nodes status took 1.008761219s.
I0217 02:51:01.766604      12 controller.go:723] Syncing backends for all LB services.
I0217 02:51:01.766686      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:51:01.882428      12 controller.go:723] Syncing backends for all LB services.
I0217 02:51:01.882470      12 controller.go:727] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 02:52:54.086942      12 node_controller.go:271] Update 3 nodes status took 1.251915363s.
I0217 02:57:55.091245      12 node_controller.go:271] Update 3 nodes status took 1.003087006s.
I0217 03:02:56.294499      12 node_controller.go:271] Update 3 nodes status took 1.202048095s.
