2026-02-16 19:49:48.566 [INFO][1] typha/cmdwrapper.go 56: Starting /usr/bin/kube-controllers
2026-02-16 19:49:48.635 [INFO][14] 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:48.636 [INFO][14] kube-controllers/main.go 119: Ensuring Calico datastore is initialized
2026-02-16 19:49:48.817 [INFO][14] kube-controllers/main.go 144: Calico datastore is initialized
2026-02-16 19:49:48.818 [INFO][14] kube-controllers/main.go 183: Getting initial config snapshot from datastore
2026-02-16 19:49:48.902 [INFO][14] kube-controllers/main.go 186: Got initial config snapshot
2026-02-16 19:49:48.903 [INFO][14] kube-controllers/main.go 200: Starting status report routine
2026-02-16 19:49:48.904 [INFO][14] kube-controllers/main.go 209: Starting Prometheus metrics server on port 9094
2026-02-16 19:49:48.903 [INFO][14] kube-controllers/resources.go 349: Main client watcher loop
2026-02-16 19:49:48.904 [INFO][14] kube-controllers/main.go 518: Starting informer informer=&cache.sharedIndexInformer{indexer:(*cache.cache)(0xc000530528), controller:cache.Controller(nil), processor:(*cache.sharedProcessor)(0xc000466e10), cacheMutationDetector:cache.dummyMutationDetector{}, listerWatcher:(*cache.ListWatch)(0xc000361050), objectType:(*v1.Pod)(0xc00024a908), 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:48.904 [INFO][14] kube-controllers/main.go 518: Starting informer informer=&cache.sharedIndexInformer{indexer:(*cache.cache)(0xc000530558), controller:cache.Controller(nil), processor:(*cache.sharedProcessor)(0xc000466e60), cacheMutationDetector:cache.dummyMutationDetector{}, listerWatcher:(*cache.ListWatch)(0xc00014eab0), objectType:(*v1.Node)(0xc0002eb208), 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:48.904 [INFO][14] kube-controllers/main.go 518: Starting informer informer=&cache.sharedIndexInformer{indexer:(*cache.cache)(0xc000530570), controller:cache.Controller(nil), processor:(*cache.sharedProcessor)(0xc000466eb0), cacheMutationDetector:cache.dummyMutationDetector{}, listerWatcher:(*cache.ListWatch)(0xc00014ec90), objectType:(*v1.Service)(0xc000303408), 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:48.904 [INFO][14] kube-controllers/main.go 518: Starting informer informer=&cache.sharedIndexInformer{indexer:(*cache.cache)(0xc0005305a0), controller:cache.Controller(nil), processor:(*cache.sharedProcessor)(0xc000466f00), cacheMutationDetector:cache.dummyMutationDetector{}, listerWatcher:(*cache.ListWatch)(0xc00014f050), objectType:(*v1.Namespace)(0xc0003a18c0), 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:48.904 [INFO][14] kube-controllers/main.go 524: Starting controller ControllerType="Node"
2026-02-16 19:49:48.904 [INFO][14] kube-controllers/main.go 524: Starting controller ControllerType="LoadBalancer"
2026-02-16 19:49:48.904 [INFO][14] kube-controllers/watchersyncer.go 118: Start called
2026-02-16 19:49:48.904 [INFO][14] kube-controllers/watchersyncer.go 158: Sending status update Status=wait-for-ready
2026-02-16 19:49:48.904 [INFO][14] kube-controllers/syncer.go 94: Node controller syncer status updated: wait-for-ready
2026-02-16 19:49:48.904 [INFO][14] kube-controllers/watchersyncer.go 177: Starting main event processing loop
2026-02-16 19:49:48.904 [INFO][14] kube-controllers/watchercache.go 243: Full resync is required ListRoot=".../v3/pc.org/hostendpoints"
2026-02-16 19:49:48.904 [INFO][14] kube-controllers/controller.go 168: Starting Node controller
I0216 19:49:48.904862      14 shared_informer.go:350] "Waiting for caches to sync" controller="nodes"
2026-02-16 19:49:48.905 [INFO][14] kube-controllers/watchercache.go 243: Full resync is required ListRoot=".../v3/pc.org/clusterinformations"
2026-02-16 19:49:48.905 [INFO][14] kube-controllers/watchercache.go 243: Full resync is required ListRoot=".../v3/pc.org/nodes"
2026-02-16 19:49:48.905 [INFO][14] kube-controllers/watchercache.go 243: Full resync is required ListRoot=".../ipam/v2/assignment/"
2026-02-16 19:49:48.905 [INFO][14] kube-controllers/watchercache.go 243: Full resync is required ListRoot=".../v3/pc.org/ippools"
2026-02-16 19:49:48.912 [INFO][14] kube-controllers/watchercache.go 447: Sending synced update ListRoot=".../v3/pc.org/clusterinformations"
2026-02-16 19:49:48.912 [INFO][14] kube-controllers/watchercache.go 447: Sending synced update ListRoot=".../ipam/v2/assignment/"
2026-02-16 19:49:48.915 [INFO][14] kube-controllers/watchercache.go 447: Sending synced update ListRoot=".../v3/pc.org/nodes"
2026-02-16 19:49:48.918 [INFO][14] kube-controllers/watchercache.go 447: Sending synced update ListRoot=".../v3/pc.org/hostendpoints"
2026-02-16 19:49:48.920 [INFO][14] kube-controllers/watchersyncer.go 158: Sending status update Status=resync
2026-02-16 19:49:48.920 [INFO][14] kube-controllers/syncer.go 94: Node controller syncer status updated: resync
2026-02-16 19:49:48.920 [INFO][14] kube-controllers/watchersyncer.go 243: Received InSync event from one of the watcher caches
2026-02-16 19:49:48.920 [INFO][14] kube-controllers/watchersyncer.go 243: Received InSync event from one of the watcher caches
2026-02-16 19:49:48.920 [INFO][14] kube-controllers/watchersyncer.go 243: Received InSync event from one of the watcher caches
2026-02-16 19:49:48.920 [INFO][14] kube-controllers/watchersyncer.go 243: Received InSync event from one of the watcher caches
2026-02-16 19:49:48.920 [INFO][14] kube-controllers/watchercache.go 447: Sending synced update ListRoot=".../v3/pc.org/ippools"
2026-02-16 19:49:48.920 [INFO][14] kube-controllers/watchersyncer.go 243: Received InSync event from one of the watcher caches
2026-02-16 19:49:48.920 [INFO][14] kube-controllers/watchersyncer.go 255: All watchers have sync'd data - sending data and final sync
2026-02-16 19:49:48.920 [INFO][14] kube-controllers/watchersyncer.go 158: Sending status update Status=in-sync
2026-02-16 19:49:48.920 [INFO][14] kube-controllers/syncer.go 94: Node controller syncer status updated: in-sync
I0216 19:49:49.005779      14 shared_informer.go:357] "Caches are synced" controller="nodes"
I0216 19:49:49.005854      14 shared_informer.go:350] "Waiting for caches to sync" controller="pods"
I0216 19:49:49.005868      14 shared_informer.go:357] "Caches are synced" controller="pods"
2026-02-16 19:49:49.005 [INFO][14] kube-controllers/hostendpoints.go 149: Will run periodic HostEndpoint sync every 5m0s
2026-02-16 19:49:49.005 [INFO][14] kube-controllers/hostendpoints.go 186: Syncer is InSync, kicking sync channel status=in-sync
2026-02-16 19:49:49.005 [INFO][14] kube-controllers/hostendpoints.go 259: Syncing all HostEndpoints
2026-02-16 19:49:49.006 [INFO][14] kube-controllers/ipam.go 312: Will run periodic IPAM sync every 7m30s
2026-02-16 19:49:49.006 [INFO][14] kube-controllers/loadbalancer_controller.go 237: Will run periodic IPAM sync every 5m0s
2026-02-16 19:49:49.006 [INFO][14] kube-controllers/ipam.go 2387: Upgrading any IPAM affinities from older versions.... node="load-balancer"
2026-02-16 19:49:49.324 [INFO][14] kube-controllers/ipam.go 2427: No affinities needed to be upgraded.
2026-02-16 19:49:50.007 [INFO][14] kube-controllers/ipam.go 379: Syncer is InSync, kicking sync channel status=in-sync
2026-02-16 19:49:50.326 [INFO][14] kube-controllers/loadbalancer_controller.go 271: Syncer is InSync, kicking sync channel status=in-sync
2026-02-16 19:54:49.006 [INFO][14] kube-controllers/hostendpoints.go 156: Running periodic HostEndpoint sync
2026-02-16 19:54:49.006 [INFO][14] kube-controllers/hostendpoints.go 259: Syncing all HostEndpoints
2026-02-16 19:54:49.006 [INFO][14] kube-controllers/loadbalancer_controller.go 252: Running periodic IPAM sync of Service LoadBalancer
2026-02-16 19:57:19.006 [INFO][14] kube-controllers/ipam.go 298: Marking IPAM for full resync reason="periodic sync"
2026-02-16 19:57:19.006 [INFO][14] kube-controllers/ipam.go 795: Performing a full scan of IPAM allocations to check for leaks and redundant affinities
2026-02-16 19:59:49.007 [INFO][14] kube-controllers/loadbalancer_controller.go 252: Running periodic IPAM sync of Service LoadBalancer
2026-02-16 19:59:49.007 [INFO][14] kube-controllers/hostendpoints.go 156: Running periodic HostEndpoint sync
2026-02-16 19:59:49.007 [INFO][14] kube-controllers/hostendpoints.go 259: Syncing all HostEndpoints
