2026-02-16 19:49:19.400 [INFO][1] typha/cmdwrapper.go 56: Starting /usr/bin/kube-controllers
2026-02-16 19:49:19.440 [INFO][16] kube-controllers/main.go 95: Loaded configuration from environment config=&config.Config{LogLevel:"info", WorkloadEndpointWorkers:1, ProfileWorkers:1, PolicyWorkers:1, NodeWorkers:1, Kubeconfig:"", DatastoreType:"kubernetes"}
2026-02-16 19:49:19.441 [INFO][16] kube-controllers/main.go 119: Ensuring Calico datastore is initialized
2026-02-16 19:49:19.485 [INFO][16] kube-controllers/main.go 144: Calico datastore is initialized
2026-02-16 19:49:19.485 [INFO][16] kube-controllers/main.go 183: Getting initial config snapshot from datastore
2026-02-16 19:49:19.508 [INFO][16] kube-controllers/main.go 186: Got initial config snapshot
2026-02-16 19:49:19.509 [INFO][16] kube-controllers/main.go 200: Starting status report routine
2026-02-16 19:49:19.509 [INFO][16] kube-controllers/main.go 209: Starting Prometheus metrics server on port 9094
2026-02-16 19:49:19.509 [INFO][16] kube-controllers/main.go 518: Starting informer informer=&cache.sharedIndexInformer{indexer:(*cache.cache)(0xc0004ae480), controller:cache.Controller(nil), processor:(*cache.sharedProcessor)(0xc000450230), cacheMutationDetector:cache.dummyMutationDetector{}, listerWatcher:(*cache.ListWatch)(0xc0003b93e0), objectType:(*v1.Pod)(0xc0005fa008), objectDescription:"", resyncCheckPeriod:0, defaultEventHandlerResyncPeriod:0, clock:(*clock.RealClock)(0x3f97380), started:false, stopped:false, startedLock:sync.Mutex{_:sync.noCopy{}, mu:sync.Mutex{state:0, sema:0x0}}, blockDeltas:sync.Mutex{_:sync.noCopy{}, mu:sync.Mutex{state:0, sema:0x0}}, watchErrorHandler:(cache.WatchErrorHandlerWithContext)(nil), transform:(cache.TransformFunc)(0x1f197e0)}
2026-02-16 19:49:19.510 [INFO][16] kube-controllers/main.go 518: Starting informer informer=&cache.sharedIndexInformer{indexer:(*cache.cache)(0xc0004ae4e0), controller:cache.Controller(nil), processor:(*cache.sharedProcessor)(0xc000450280), cacheMutationDetector:cache.dummyMutationDetector{}, listerWatcher:(*cache.ListWatch)(0xc0003b95f0), objectType:(*v1.Node)(0xc000051808), objectDescription:"", resyncCheckPeriod:0, defaultEventHandlerResyncPeriod:0, clock:(*clock.RealClock)(0x3f97380), started:false, stopped:false, startedLock:sync.Mutex{_:sync.noCopy{}, mu:sync.Mutex{state:0, sema:0x0}}, blockDeltas:sync.Mutex{_:sync.noCopy{}, mu:sync.Mutex{state:0, sema:0x0}}, watchErrorHandler:(cache.WatchErrorHandlerWithContext)(nil), transform:(cache.TransformFunc)(nil)}
2026-02-16 19:49:19.511 [INFO][16] kube-controllers/main.go 518: Starting informer informer=&cache.sharedIndexInformer{indexer:(*cache.cache)(0xc0004ae510), controller:cache.Controller(nil), processor:(*cache.sharedProcessor)(0xc0004502d0), cacheMutationDetector:cache.dummyMutationDetector{}, listerWatcher:(*cache.ListWatch)(0xc0003b9770), objectType:(*v1.Service)(0xc000198a08), objectDescription:"", resyncCheckPeriod:0, defaultEventHandlerResyncPeriod:0, clock:(*clock.RealClock)(0x3f97380), started:false, stopped:false, startedLock:sync.Mutex{_:sync.noCopy{}, mu:sync.Mutex{state:0, sema:0x0}}, blockDeltas:sync.Mutex{_:sync.noCopy{}, mu:sync.Mutex{state:0, sema:0x0}}, watchErrorHandler:(cache.WatchErrorHandlerWithContext)(nil), transform:(cache.TransformFunc)(nil)}
2026-02-16 19:49:19.511 [INFO][16] kube-controllers/main.go 518: Starting informer informer=&cache.sharedIndexInformer{indexer:(*cache.cache)(0xc0004ae5d0), controller:cache.Controller(nil), processor:(*cache.sharedProcessor)(0xc000450320), cacheMutationDetector:cache.dummyMutationDetector{}, listerWatcher:(*cache.ListWatch)(0xc0003b9980), objectType:(*v1.Namespace)(0xc000132f20), objectDescription:"", resyncCheckPeriod:0, defaultEventHandlerResyncPeriod:0, clock:(*clock.RealClock)(0x3f97380), started:false, stopped:false, startedLock:sync.Mutex{_:sync.noCopy{}, mu:sync.Mutex{state:0, sema:0x0}}, blockDeltas:sync.Mutex{_:sync.noCopy{}, mu:sync.Mutex{state:0, sema:0x0}}, watchErrorHandler:(cache.WatchErrorHandlerWithContext)(nil), transform:(cache.TransformFunc)(nil)}
2026-02-16 19:49:19.511 [INFO][16] kube-controllers/main.go 524: Starting controller ControllerType="Node"
2026-02-16 19:49:19.511 [INFO][16] kube-controllers/main.go 524: Starting controller ControllerType="LoadBalancer"
2026-02-16 19:49:19.512 [INFO][16] kube-controllers/watchersyncer.go 118: Start called
2026-02-16 19:49:19.513 [INFO][16] kube-controllers/watchersyncer.go 158: Sending status update Status=wait-for-ready
2026-02-16 19:49:19.512 [INFO][16] kube-controllers/controller.go 168: Starting Node controller
2026-02-16 19:49:19.513 [INFO][16] kube-controllers/syncer.go 94: Node controller syncer status updated: wait-for-ready
2026-02-16 19:49:19.514 [INFO][16] kube-controllers/watchersyncer.go 177: Starting main event processing loop
2026-02-16 19:49:19.514 [INFO][16] kube-controllers/watchercache.go 243: Full resync is required ListRoot=".../v3/pc.org/hostendpoints"
2026-02-16 19:49:19.515 [INFO][16] kube-controllers/watchercache.go 243: Full resync is required ListRoot=".../v3/pc.org/clusterinformations"
I0216 19:49:19.514039      16 shared_informer.go:350] "Waiting for caches to sync" controller="nodes"
2026-02-16 19:49:19.515 [INFO][16] kube-controllers/watchercache.go 243: Full resync is required ListRoot=".../v3/pc.org/nodes"
2026-02-16 19:49:19.516 [INFO][16] kube-controllers/watchercache.go 243: Full resync is required ListRoot=".../ipam/v2/assignment/"
2026-02-16 19:49:19.517 [INFO][16] kube-controllers/watchercache.go 243: Full resync is required ListRoot=".../v3/pc.org/ippools"
2026-02-16 19:49:19.520 [INFO][16] kube-controllers/resources.go 349: Main client watcher loop
2026-02-16 19:49:19.521 [INFO][16] kube-controllers/watchercache.go 447: Sending synced update ListRoot=".../v3/pc.org/clusterinformations"
2026-02-16 19:49:19.521 [INFO][16] kube-controllers/watchersyncer.go 158: Sending status update Status=resync
2026-02-16 19:49:19.521 [INFO][16] kube-controllers/syncer.go 94: Node controller syncer status updated: resync
2026-02-16 19:49:19.521 [INFO][16] kube-controllers/watchersyncer.go 243: Received InSync event from one of the watcher caches
2026-02-16 19:49:19.523 [INFO][16] kube-controllers/watchercache.go 447: Sending synced update ListRoot=".../v3/pc.org/hostendpoints"
2026-02-16 19:49:19.524 [INFO][16] kube-controllers/watchersyncer.go 243: Received InSync event from one of the watcher caches
2026-02-16 19:49:19.525 [INFO][16] kube-controllers/watchercache.go 447: Sending synced update ListRoot=".../v3/pc.org/nodes"
2026-02-16 19:49:19.525 [INFO][16] kube-controllers/watchercache.go 447: Sending synced update ListRoot=".../ipam/v2/assignment/"
2026-02-16 19:49:19.526 [INFO][16] kube-controllers/watchersyncer.go 243: Received InSync event from one of the watcher caches
2026-02-16 19:49:19.526 [INFO][16] kube-controllers/watchersyncer.go 243: Received InSync event from one of the watcher caches
2026-02-16 19:49:19.527 [INFO][16] kube-controllers/watchercache.go 447: Sending synced update ListRoot=".../v3/pc.org/ippools"
2026-02-16 19:49:19.527 [INFO][16] kube-controllers/watchersyncer.go 243: Received InSync event from one of the watcher caches
2026-02-16 19:49:19.527 [INFO][16] kube-controllers/watchersyncer.go 255: All watchers have sync'd data - sending data and final sync
2026-02-16 19:49:19.527 [INFO][16] kube-controllers/watchersyncer.go 158: Sending status update Status=in-sync
2026-02-16 19:49:19.527 [INFO][16] kube-controllers/syncer.go 94: Node controller syncer status updated: in-sync
2026-02-16 19:49:19.614 [INFO][16] kube-controllers/loadbalancer_controller.go 237: Will run periodic IPAM sync every 5m0s
2026-02-16 19:49:19.614 [INFO][16] kube-controllers/ipam.go 2387: Upgrading any IPAM affinities from older versions.... node="load-balancer"
I0216 19:49:19.616194      16 shared_informer.go:357] "Caches are synced" controller="nodes"
I0216 19:49:19.616260      16 shared_informer.go:350] "Waiting for caches to sync" controller="pods"
I0216 19:49:19.616273      16 shared_informer.go:357] "Caches are synced" controller="pods"
2026-02-16 19:49:19.616 [INFO][16] kube-controllers/hostendpoints.go 149: Will run periodic HostEndpoint sync every 5m0s
2026-02-16 19:49:19.616 [INFO][16] kube-controllers/ipam.go 312: Will run periodic IPAM sync every 7m30s
2026-02-16 19:49:19.617 [INFO][16] kube-controllers/hostendpoints.go 186: Syncer is InSync, kicking sync channel status=in-sync
2026-02-16 19:49:19.618 [INFO][16] kube-controllers/hostendpoints.go 259: Syncing all HostEndpoints
2026-02-16 19:49:19.620 [INFO][16] kube-controllers/ipam.go 2427: No affinities needed to be upgraded.
2026-02-16 19:49:20.617 [INFO][16] kube-controllers/ipam.go 379: Syncer is InSync, kicking sync channel status=in-sync
2026-02-16 19:49:20.621 [INFO][16] kube-controllers/loadbalancer_controller.go 271: Syncer is InSync, kicking sync channel status=in-sync
2026-02-16 19:49:44.464 [INFO][16] kube-controllers/client.go 281: Unable to initialize adminnetworkpolicy Tier error=Post "https://10.254.0.1:443/apis/crd.projectcalico.org/v1/tiers": context deadline exceeded
2026-02-16 19:49:44.464 [INFO][16] kube-controllers/client.go 287: Unable to initialize baselineadminnetworkpolicy Tier error=client rate limiter Wait returned an error: context deadline exceeded
2026-02-16 19:49:44.464 [ERROR][16] kube-controllers/main.go 262: Failed to verify datastore error=Post "https://10.254.0.1:443/apis/crd.projectcalico.org/v1/tiers": context deadline exceeded
2026-02-16 19:49:46.510 [ERROR][16] kube-controllers/main.go 280: Received bad status code from apiserver error=an error on the server ("[+]ping ok\n[+]log ok\n[-]etcd failed: reason withheld\n[+]poststarthook/start-apiserver-admission-initializer ok\n[+]poststarthook/generic-apiserver-start-informers ok\n[+]poststarthook/priority-and-fairness-config-consumer ok\n[+]poststarthook/priority-and-fairness-filter ok\n[+]poststarthook/storage-object-count-tracker-hook ok\n[+]poststarthook/start-apiextensions-informers ok\n[+]poststarthook/start-apiextensions-controllers ok\n[+]poststarthook/crd-informer-synced ok\n[+]poststarthook/start-system-namespaces-controller ok\n[+]poststarthook/start-cluster-authentication-info-controller ok\n[+]poststarthook/start-kube-apiserver-identity-lease-controller ok\n[+]poststarthook/start-kube-apiserver-identity-lease-garbage-collector ok\n[+]poststarthook/start-legacy-token-tracking-controller ok\n[+]poststarthook/start-service-ip-repair-controllers ok\n[+]poststarthook/rbac/bootstrap-roles ok\n[+]poststarthook/scheduling/bootstrap-system-priority-classes ok\n[+]poststarthook/priority-and-fairness-config-producer ok\n[+]poststarthook/bootstrap-controller ok\n[+]poststarthook/aggregator-reload-proxy-client-cert ok\n[+]poststarthook/start-kube-aggregator-informers ok\n[+]poststarthook/apiservice-status-local-available-controller ok\n[+]poststarthook/apiservice-status-remote-available-controller ok\n[+]poststarthook/apiservice-registration-controller ok\n[+]poststarthook/apiservice-discovery-controller ok\n[+]poststarthook/kube-apiserver-autoregistration ok\n[+]autoregister-completion ok\n[+]poststarthook/apiservice-openapi-controller ok\n[+]poststarthook/apiservice-openapiv3-controller ok\nhealthz check failed") has prevented the request from succeeding status=500
2026-02-16 19:49:47.286 [INFO][16] kube-controllers/main.go 296: Health check is not ready, retrying in 2 seconds with new timeout: 8s
2026-02-16 19:49:57.288 [INFO][16] kube-controllers/client.go 287: Unable to initialize baselineadminnetworkpolicy Tier error=Post "https://10.254.0.1:443/apis/crd.projectcalico.org/v1/tiers": context deadline exceeded
2026-02-16 19:49:57.289 [ERROR][16] kube-controllers/main.go 262: Failed to verify datastore error=Post "https://10.254.0.1:443/apis/crd.projectcalico.org/v1/tiers": context deadline exceeded
2026-02-16 19:49:57.290 [INFO][16] kube-controllers/main.go 296: Health check is not ready, retrying in 2 seconds with new timeout: 16s
2026-02-16 19:54:19.615 [INFO][16] kube-controllers/loadbalancer_controller.go 252: Running periodic IPAM sync of Service LoadBalancer
2026-02-16 19:54:19.617 [INFO][16] kube-controllers/hostendpoints.go 156: Running periodic HostEndpoint sync
2026-02-16 19:54:19.617 [INFO][16] kube-controllers/hostendpoints.go 259: Syncing all HostEndpoints
2026-02-16 19:56:49.617 [INFO][16] kube-controllers/ipam.go 298: Marking IPAM for full resync reason="periodic sync"
2026-02-16 19:56:49.617 [INFO][16] kube-controllers/ipam.go 795: Performing a full scan of IPAM allocations to check for leaks and redundant affinities
2026-02-16 19:59:19.616 [INFO][16] kube-controllers/loadbalancer_controller.go 252: Running periodic IPAM sync of Service LoadBalancer
2026-02-16 19:59:19.617 [INFO][16] kube-controllers/hostendpoints.go 156: Running periodic HostEndpoint sync
2026-02-16 19:59:19.617 [INFO][16] kube-controllers/hostendpoints.go 259: Syncing all HostEndpoints
