2026/02/17 01:03:11 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=03711061-da81-45de-aaec-b8a74974783e,--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:11 Now listening for interrupts
I0217 01:03:12.529516      12 serving.go:386] Generated self-signed cert in-memory
I0217 01:03:13.547785      12 serving.go:386] Generated self-signed cert in-memory
W0217 01:03:13.547862      12 client_config.go:667] Neither --kubeconfig nor --master was specified.  Using the inClusterConfig.  This might not work.
I0217 01:03:14.050373      12 requestheader_controller.go:255] Loaded a new request header values for RequestHeaderAuthRequestController
I0217 01:03:14.169381      12 controllermanager.go:160] Version: v1.33.1
I0217 01:03:14.178276      12 requestheader_controller.go:180] Starting RequestHeaderAuthRequestController
I0217 01:03:14.178317      12 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0217 01:03:14.178345      12 shared_informer.go:350] "Waiting for caches to sync" controller="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0217 01:03:14.178347      12 shared_informer.go:350] "Waiting for caches to sync" controller="RequestHeaderAuthRequestController"
I0217 01:03:14.178299      12 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0217 01:03:14.178376      12 shared_informer.go:350] "Waiting for caches to sync" controller="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0217 01:03:14.178899      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771290192\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771290192\" (2026-02-17 00:03:12 +0000 UTC to 2027-02-17 00:03:12 +0000 UTC (now=2026-02-17 01:03:14.178822071 +0000 UTC))"
I0217 01:03:14.179296      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771290194\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771290193\" (2026-02-17 00:03:13 +0000 UTC to 2029-02-17 00:03:13 +0000 UTC (now=2026-02-17 01:03:14.179236977 +0000 UTC))"
I0217 01:03:14.179327      12 secure_serving.go:211] Serving securely on 127.0.0.1:10258
I0217 01:03:14.179620      12 tlsconfig.go:243] "Starting DynamicServingCertificateController"
I0217 01:03:14.179635      12 reflector.go:430] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.33.3/tools/cache/reflector.go:285"
I0217 01:03:14.180488      12 leaderelection.go:257] attempting to acquire leader lease kube-system/cloud-controller-manager...
I0217 01:03:14.181329      12 reflector.go:430] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.33.3/tools/cache/reflector.go:285"
I0217 01:03:14.181445      12 reflector.go:430] "Caches populated" type="*v1.ConfigMap" reflector="k8s.io/client-go@v0.33.3/tools/cache/reflector.go:285"
I0217 01:03:14.199510      12 leaderelection.go:271] successfully acquired lease kube-system/cloud-controller-manager
I0217 01:03:14.200983      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-she4g-dd6vb-h2mr4_52f648e3-bc1a-42c9-97f1-15aaf324d991 became leader"
I0217 01:03:14.208287      12 openstack.go:424] Setting up informers for Cloud
I0217 01:03:14.208727      12 controllermanager.go:310] Starting "cloud-node-lifecycle-controller"
I0217 01:03:14.209890      12 controllermanager.go:329] Started "cloud-node-lifecycle-controller"
I0217 01:03:14.210305      12 controllermanager.go:310] Starting "service-lb-controller"
I0217 01:03:14.210548      12 node_lifecycle_controller.go:112] Sending events to api server
I0217 01:03:14.212442      12 openstack.go:373] Claiming to support LoadBalancer
I0217 01:03:14.212775      12 controllermanager.go:329] Started "service-lb-controller"
I0217 01:03:14.212801      12 controllermanager.go:310] Starting "node-route-controller"
I0217 01:03:14.212888      12 controller.go:234] Starting service controller
I0217 01:03:14.212919      12 shared_informer.go:350] "Waiting for caches to sync" controller="service"
W0217 01:03:14.266925      12 openstack.go:408] Error initialising Routes support: router-id not set in cloud provider config
W0217 01:03:14.266982      12 core.go:111] --configure-cloud-routes is set, but cloud provider does not support routes. Will not configure cloud provider routes.
W0217 01:03:14.267038      12 controllermanager.go:317] Skipping "node-route-controller"
I0217 01:03:14.267047      12 controllermanager.go:310] Starting "cloud-node-controller"
I0217 01:03:14.268147      12 controllermanager.go:329] Started "cloud-node-controller"
I0217 01:03:14.269553      12 node_controller.go:176] Sending events to api server.
I0217 01:03:14.270363      12 node_controller.go:185] Waiting for informer caches to sync
I0217 01:03:14.276945      12 reflector.go:430] "Caches populated" type="*v1.Service" reflector="k8s.io/client-go@v0.33.3/tools/cache/reflector.go:285"
I0217 01:03:14.277277      12 reflector.go:430] "Caches populated" type="*v1.Node" reflector="k8s.io/client-go@v0.33.3/tools/cache/reflector.go:285"
I0217 01:03:14.279247      12 shared_informer.go:357] "Caches are synced" controller="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"
I0217 01:03:14.279254      12 shared_informer.go:357] "Caches are synced" controller="RequestHeaderAuthRequestController"
I0217 01:03:14.279444      12 shared_informer.go:357] "Caches are synced" controller="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"
I0217 01:03:14.279546      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 00:58:19 +0000 UTC to 2031-02-16 00:58:19 +0000 UTC (now=2026-02-17 01:03:14.279514705 +0000 UTC))"
I0217 01:03:14.279898      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771290192\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771290192\" (2026-02-17 00:03:12 +0000 UTC to 2027-02-17 00:03:12 +0000 UTC (now=2026-02-17 01:03:14.279888461 +0000 UTC))"
I0217 01:03:14.280098      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771290194\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771290193\" (2026-02-17 00:03:13 +0000 UTC to 2029-02-17 00:03:13 +0000 UTC (now=2026-02-17 01:03:14.280088804 +0000 UTC))"
I0217 01:03:14.280164      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 00:58:18 +0000 UTC to 2031-02-16 00:58:18 +0000 UTC (now=2026-02-17 01:03:14.280157535 +0000 UTC))"
I0217 01:03:14.280179      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 00:58:19 +0000 UTC to 2031-02-16 00:58:19 +0000 UTC (now=2026-02-17 01:03:14.280173995 +0000 UTC))"
I0217 01:03:14.280422      12 tlsconfig.go:203] "Loaded serving cert" certName="Generated self signed cert" certDetail="\"localhost@1771290192\" [serving] validServingFor=[127.0.0.1,127.0.0.1,localhost] issuer=\"localhost-ca@1771290192\" (2026-02-17 00:03:12 +0000 UTC to 2027-02-17 00:03:12 +0000 UTC (now=2026-02-17 01:03:14.280413228 +0000 UTC))"
I0217 01:03:14.280587      12 named_certificates.go:53] "Loaded SNI cert" index=0 certName="self-signed loopback" certDetail="\"apiserver-loopback-client@1771290194\" [serving] validServingFor=[apiserver-loopback-client] issuer=\"apiserver-loopback-client-ca@1771290193\" (2026-02-17 00:03:13 +0000 UTC to 2029-02-17 00:03:13 +0000 UTC (now=2026-02-17 01:03:14.280579491 +0000 UTC))"
I0217 01:03:14.313286      12 shared_informer.go:357] "Caches are synced" controller="service"
I0217 01:03:14.313348      12 controller.go:722] Syncing backends for all LB services.
I0217 01:03:14.313364      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 01:03:14.372521      12 node_controller.go:429] Initializing node kube-she4g-dd6vb-h2mr4 with cloud provider
I0217 01:03:14.372819      12 node_controller.go:271] Update 1 nodes status took 199.083µs.
I0217 01:03:14.942761      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 01:03:14.942809      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 01:03:14.942816      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 01:03:14.942822      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 01:03:14.942828      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 01:03:14.942835      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 01:03:15.005396      12 controller.go:722] Syncing backends for all LB services.
I0217 01:03:15.009825      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 01:03:15.082149      12 node_controller.go:474] Successfully initialized node kube-she4g-dd6vb-h2mr4 with cloud provider
I0217 01:03:15.084100      12 event.go:389] "Event occurred" object="kube-she4g-dd6vb-h2mr4" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 01:03:38.482377      12 controller.go:722] Syncing backends for all LB services.
I0217 01:03:38.482414      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 01:03:38.482445      12 node_controller.go:429] Initializing node kube-she4g-default-worker-rsjm4-mfjxm-fh4vm with cloud provider
I0217 01:03:38.941801      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 01:03:38.944191      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 01:03:38.944267      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 01:03:38.944281      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 01:03:38.944292      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 01:03:38.944566      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 01:03:38.974577      12 controller.go:722] Syncing backends for all LB services.
I0217 01:03:38.974689      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 01:03:39.140556      12 node_controller.go:474] Successfully initialized node kube-she4g-default-worker-rsjm4-mfjxm-fh4vm with cloud provider
I0217 01:03:39.141142      12 event.go:389] "Event occurred" object="kube-she4g-default-worker-rsjm4-mfjxm-fh4vm" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 01:03:42.043796      12 controller.go:722] Syncing backends for all LB services.
I0217 01:03:42.043846      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 01:03:42.056649      12 node_controller.go:429] Initializing node kube-she4g-default-worker-rsjm4-mfjxm-4pkhk with cloud provider
I0217 01:03:42.832260      12 node_controller.go:512] Adding node label from cloud provider: beta.kubernetes.io/instance-type=m1.large
I0217 01:03:42.832483      12 node_controller.go:513] Adding node label from cloud provider: node.kubernetes.io/instance-type=m1.large
I0217 01:03:42.832505      12 node_controller.go:524] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/zone=nova
I0217 01:03:42.832631      12 node_controller.go:525] Adding node label from cloud provider: topology.kubernetes.io/zone=nova
I0217 01:03:42.832650      12 node_controller.go:535] Adding node label from cloud provider: failure-domain.beta.kubernetes.io/region=RegionOne
I0217 01:03:42.832666      12 node_controller.go:536] Adding node label from cloud provider: topology.kubernetes.io/region=RegionOne
I0217 01:03:42.934051      12 controller.go:722] Syncing backends for all LB services.
I0217 01:03:42.934186      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 01:03:43.056332      12 node_controller.go:474] Successfully initialized node kube-she4g-default-worker-rsjm4-mfjxm-4pkhk with cloud provider
I0217 01:03:43.056507      12 event.go:389] "Event occurred" object="kube-she4g-default-worker-rsjm4-mfjxm-4pkhk" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"
I0217 01:08:15.615943      12 node_controller.go:271] Update 3 nodes status took 1.242615009s.
I0217 01:13:16.751373      12 node_controller.go:271] Update 3 nodes status took 1.134829116s.
I0217 01:18:17.998729      12 node_controller.go:271] Update 3 nodes status took 1.246421185s.
I0217 01:23:19.199018      12 node_controller.go:271] Update 3 nodes status took 1.200138792s.
I0217 01:23:41.083458      12 controller.go:722] Syncing backends for all LB services.
I0217 01:23:41.083521      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 01:23:41.221231      12 controller.go:722] Syncing backends for all LB services.
I0217 01:23:41.221279      12 controller.go:726] Successfully updated 0 out of 0 load balancers to direct traffic to the updated set of nodes
I0217 01:28:20.781470      12 node_controller.go:271] Update 3 nodes status took 1.581608676s.
