2026-02-16 19:50:13.926 [INFO][1] typha/cmdwrapper.go 56: Starting /usr/bin/kube-controllers
2026-02-16 19:50:13.959 [INFO][15] 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:50:13.961 [INFO][15] kube-controllers/main.go 119: Ensuring Calico datastore is initialized
2026-02-16 19:50:14.012 [INFO][15] kube-controllers/main.go 144: Calico datastore is initialized
2026-02-16 19:50:14.012 [INFO][15] kube-controllers/main.go 183: Getting initial config snapshot from datastore
2026-02-16 19:50:14.037 [INFO][15] kube-controllers/main.go 186: Got initial config snapshot
2026-02-16 19:50:14.037 [INFO][15] kube-controllers/main.go 200: Starting status report routine
2026-02-16 19:50:14.037 [INFO][15] kube-controllers/main.go 209: Starting Prometheus metrics server on port 9094
2026-02-16 19:50:14.037 [INFO][15] kube-controllers/main.go 518: Starting informer informer=&cache.sharedIndexInformer{indexer:(*cache.cache)(0xc00012f170), controller:cache.Controller(nil), processor:(*cache.sharedProcessor)(0xc0000b2870), cacheMutationDetector:cache.dummyMutationDetector{}, listerWatcher:(*cache.ListWatch)(0xc00065be90), objectType:(*v1.Pod)(0xc000676488), 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:50:14.037 [INFO][15] kube-controllers/main.go 518: Starting informer informer=&cache.sharedIndexInformer{indexer:(*cache.cache)(0xc00012f1a0), controller:cache.Controller(nil), processor:(*cache.sharedProcessor)(0xc0000b28c0), cacheMutationDetector:cache.dummyMutationDetector{}, listerWatcher:(*cache.ListWatch)(0xc000678120), objectType:(*v1.Node)(0xc00067a308), 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:50:14.037 [INFO][15] kube-controllers/main.go 518: Starting informer informer=&cache.sharedIndexInformer{indexer:(*cache.cache)(0xc00012f1b8), controller:cache.Controller(nil), processor:(*cache.sharedProcessor)(0xc0000b2910), cacheMutationDetector:cache.dummyMutationDetector{}, listerWatcher:(*cache.ListWatch)(0xc0006782a0), objectType:(*v1.Service)(0xc000656a08), 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:50:14.037 [INFO][15] kube-controllers/main.go 518: Starting informer informer=&cache.sharedIndexInformer{indexer:(*cache.cache)(0xc00012f1e8), controller:cache.Controller(nil), processor:(*cache.sharedProcessor)(0xc0000b2960), cacheMutationDetector:cache.dummyMutationDetector{}, listerWatcher:(*cache.ListWatch)(0xc0006784b0), objectType:(*v1.Namespace)(0xc000647ce0), 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:50:14.037 [INFO][15] kube-controllers/main.go 524: Starting controller ControllerType="Node"
2026-02-16 19:50:14.037 [INFO][15] kube-controllers/main.go 524: Starting controller ControllerType="LoadBalancer"
2026-02-16 19:50:14.037 [INFO][15] kube-controllers/watchersyncer.go 118: Start called
2026-02-16 19:50:14.037 [INFO][15] kube-controllers/watchersyncer.go 158: Sending status update Status=wait-for-ready
2026-02-16 19:50:14.037 [INFO][15] kube-controllers/syncer.go 94: Node controller syncer status updated: wait-for-ready
2026-02-16 19:50:14.037 [INFO][15] kube-controllers/watchersyncer.go 177: Starting main event processing loop
2026-02-16 19:50:14.037 [INFO][15] kube-controllers/watchercache.go 243: Full resync is required ListRoot=".../v3/pc.org/hostendpoints"
2026-02-16 19:50:14.038 [INFO][15] kube-controllers/controller.go 168: Starting Node controller
I0216 19:50:14.038534      15 shared_informer.go:350] "Waiting for caches to sync" controller="nodes"
2026-02-16 19:50:14.038 [INFO][15] kube-controllers/watchercache.go 243: Full resync is required ListRoot=".../v3/pc.org/nodes"
2026-02-16 19:50:14.040 [INFO][15] kube-controllers/watchercache.go 243: Full resync is required ListRoot=".../v3/pc.org/clusterinformations"
2026-02-16 19:50:14.040 [INFO][15] kube-controllers/watchercache.go 243: Full resync is required ListRoot=".../ipam/v2/assignment/"
2026-02-16 19:50:14.041 [INFO][15] kube-controllers/resources.go 349: Main client watcher loop
2026-02-16 19:50:14.042 [INFO][15] kube-controllers/watchercache.go 243: Full resync is required ListRoot=".../v3/pc.org/ippools"
2026-02-16 19:50:14.044 [INFO][15] kube-controllers/watchercache.go 447: Sending synced update ListRoot=".../v3/pc.org/hostendpoints"
2026-02-16 19:50:14.044 [INFO][15] kube-controllers/watchersyncer.go 243: Received InSync event from one of the watcher caches
2026-02-16 19:50:14.045 [INFO][15] kube-controllers/watchersyncer.go 158: Sending status update Status=resync
2026-02-16 19:50:14.045 [INFO][15] kube-controllers/syncer.go 94: Node controller syncer status updated: resync
2026-02-16 19:50:14.048 [INFO][15] kube-controllers/watchercache.go 447: Sending synced update ListRoot=".../v3/pc.org/ippools"
2026-02-16 19:50:14.048 [INFO][15] kube-controllers/watchersyncer.go 243: Received InSync event from one of the watcher caches
2026-02-16 19:50:14.049 [INFO][15] kube-controllers/watchercache.go 447: Sending synced update ListRoot=".../v3/pc.org/nodes"
2026-02-16 19:50:14.050 [INFO][15] kube-controllers/watchercache.go 447: Sending synced update ListRoot=".../ipam/v2/assignment/"
2026-02-16 19:50:14.050 [INFO][15] kube-controllers/watchersyncer.go 243: Received InSync event from one of the watcher caches
2026-02-16 19:50:14.050 [INFO][15] kube-controllers/watchersyncer.go 243: Received InSync event from one of the watcher caches
2026-02-16 19:50:14.050 [INFO][15] kube-controllers/watchercache.go 447: Sending synced update ListRoot=".../v3/pc.org/clusterinformations"
2026-02-16 19:50:14.051 [INFO][15] kube-controllers/watchersyncer.go 243: Received InSync event from one of the watcher caches
2026-02-16 19:50:14.051 [INFO][15] kube-controllers/watchersyncer.go 255: All watchers have sync'd data - sending data and final sync
2026-02-16 19:50:14.051 [INFO][15] kube-controllers/watchersyncer.go 158: Sending status update Status=in-sync
2026-02-16 19:50:14.051 [INFO][15] kube-controllers/syncer.go 94: Node controller syncer status updated: in-sync
2026-02-16 19:50:14.139 [INFO][15] kube-controllers/loadbalancer_controller.go 237: Will run periodic IPAM sync every 5m0s
2026-02-16 19:50:14.139 [INFO][15] kube-controllers/ipam.go 2387: Upgrading any IPAM affinities from older versions.... node="load-balancer"
I0216 19:50:14.140784      15 shared_informer.go:357] "Caches are synced" controller="nodes"
I0216 19:50:14.140867      15 shared_informer.go:350] "Waiting for caches to sync" controller="pods"
I0216 19:50:14.140889      15 shared_informer.go:357] "Caches are synced" controller="pods"
2026-02-16 19:50:14.140 [INFO][15] kube-controllers/hostendpoints.go 149: Will run periodic HostEndpoint sync every 5m0s
2026-02-16 19:50:14.140 [INFO][15] kube-controllers/ipam.go 312: Will run periodic IPAM sync every 7m30s
2026-02-16 19:50:14.140 [INFO][15] kube-controllers/hostendpoints.go 186: Syncer is InSync, kicking sync channel status=in-sync
2026-02-16 19:50:14.141 [INFO][15] kube-controllers/hostendpoints.go 259: Syncing all HostEndpoints
2026-02-16 19:50:14.173 [INFO][15] kube-controllers/ipam.go 2427: No affinities needed to be upgraded.
2026-02-16 19:50:15.141 [INFO][15] kube-controllers/ipam.go 379: Syncer is InSync, kicking sync channel status=in-sync
2026-02-16 19:50:15.175 [INFO][15] kube-controllers/loadbalancer_controller.go 271: Syncer is InSync, kicking sync channel status=in-sync
2026-02-16 19:55:14.140 [INFO][15] kube-controllers/loadbalancer_controller.go 252: Running periodic IPAM sync of Service LoadBalancer
2026-02-16 19:55:14.141 [INFO][15] kube-controllers/hostendpoints.go 156: Running periodic HostEndpoint sync
2026-02-16 19:55:14.142 [INFO][15] kube-controllers/hostendpoints.go 259: Syncing all HostEndpoints
2026-02-16 19:57:44.142 [INFO][15] kube-controllers/ipam.go 298: Marking IPAM for full resync reason="periodic sync"
2026-02-16 19:57:44.142 [INFO][15] kube-controllers/ipam.go 795: Performing a full scan of IPAM allocations to check for leaks and redundant affinities
2026-02-16 20:00:14.139 [INFO][15] kube-controllers/loadbalancer_controller.go 252: Running periodic IPAM sync of Service LoadBalancer
2026-02-16 20:00:14.141 [INFO][15] kube-controllers/hostendpoints.go 156: Running periodic HostEndpoint sync
2026-02-16 20:00:14.141 [INFO][15] kube-controllers/hostendpoints.go 259: Syncing all HostEndpoints
