{"level":"warn","ts":"2026-02-25T01:55:14.532075Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T01:55:14.53218Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.49:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.49:2380","--initial-cluster=instance=https://162.253.55.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.49:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-25T01:55:14.532265Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T01:55:14.532279Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-25T01:55:14.532303Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T01:55:14.533027Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"]} {"level":"info","ts":"2026-02-25T01:55:14.533103Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.49:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-25T01:55:14.53671Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.321621ms"} {"level":"info","ts":"2026-02-25T01:55:14.544654Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a9ba61e55fef5be","cluster-id":"9612db6e19ac6ce7"} {"level":"info","ts":"2026-02-25T01:55:14.545043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T01:55:14.545104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 0"} {"level":"info","ts":"2026-02-25T01:55:14.545121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a9ba61e55fef5be [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T01:55:14.545227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 1"} {"level":"info","ts":"2026-02-25T01:55:14.545305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"warn","ts":"2026-02-25T01:55:14.552133Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T01:55:14.553576Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T01:55:14.554861Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-25T01:55:14.557902Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a9ba61e55fef5be","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T01:55:14.558147Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a9ba61e55fef5be","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T01:55:14.558232Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T01:55:14.558376Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T01:55:14.558393Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T01:55:14.559406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"info","ts":"2026-02-25T01:55:14.559612Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","added-peer-id":"6a9ba61e55fef5be","added-peer-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-25T01:55:14.563531Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T01:55:14.56367Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-25T01:55:14.563735Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-25T01:55:14.563855Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a9ba61e55fef5be","initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T01:55:14.563925Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T01:55:15.44633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T01:55:15.446429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T01:55:15.446513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgPreVoteResp from 6a9ba61e55fef5be at term 1"} {"level":"info","ts":"2026-02-25T01:55:15.446563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became candidate at term 2"} {"level":"info","ts":"2026-02-25T01:55:15.446577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgVoteResp from 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-25T01:55:15.446596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became leader at term 2"} {"level":"info","ts":"2026-02-25T01:55:15.446619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a9ba61e55fef5be elected leader 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-25T01:55:15.44771Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a9ba61e55fef5be","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.49:2379]}","request-path":"/0/members/6a9ba61e55fef5be/attributes","cluster-id":"9612db6e19ac6ce7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T01:55:15.447749Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:55:15.447777Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:55:15.448Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:55:15.448132Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T01:55:15.448283Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T01:55:15.449079Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:55:15.449231Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:55:15.449281Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:55:15.451424Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.49:2379"} {"level":"info","ts":"2026-02-25T01:55:15.452133Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T01:55:32.139782Z","caller":"traceutil/trace.go:171","msg":"trace[1301696963] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"138.433013ms","start":"2026-02-25T01:55:32.001313Z","end":"2026-02-25T01:55:32.139746Z","steps":["trace[1301696963] 'read index received' (duration: 138.058734ms)","trace[1301696963] 'applied index is now lower than readState.Index' (duration: 372.729µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:55:32.13997Z","caller":"traceutil/trace.go:171","msg":"trace[913584231] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"320.940149ms","start":"2026-02-25T01:55:31.818991Z","end":"2026-02-25T01:55:32.139931Z","steps":["trace[913584231] 'process raft request' (duration: 320.483498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:55:32.140012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.676348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-25T01:55:32.140191Z","caller":"traceutil/trace.go:171","msg":"trace[1996557653] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:374; }","duration":"138.898224ms","start":"2026-02-25T01:55:32.00124Z","end":"2026-02-25T01:55:32.140138Z","steps":["trace[1996557653] 'agreement among raft nodes before linearized reading' (duration: 138.683019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:55:32.140655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:55:31.818958Z","time spent":"321.110894ms","remote":"127.0.0.1:49064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:55:36.262906Z","caller":"traceutil/trace.go:171","msg":"trace[400677826] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"102.976882ms","start":"2026-02-25T01:55:36.159907Z","end":"2026-02-25T01:55:36.262884Z","steps":["trace[400677826] 'process raft request' (duration: 102.847789ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:55:42.906218Z","caller":"traceutil/trace.go:171","msg":"trace[657698097] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"194.625549ms","start":"2026-02-25T01:55:42.711562Z","end":"2026-02-25T01:55:42.906188Z","steps":["trace[657698097] 'process raft request' (duration: 194.468725ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:55:42.982094Z","caller":"traceutil/trace.go:171","msg":"trace[338956887] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:442; }","duration":"168.40674ms","start":"2026-02-25T01:55:42.813666Z","end":"2026-02-25T01:55:42.982073Z","steps":["trace[338956887] 'read index received' (duration: 92.370847ms)","trace[338956887] 'applied index is now lower than readState.Index' (duration: 76.034423ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:55:42.982179Z","caller":"traceutil/trace.go:171","msg":"trace[948443194] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"268.408072ms","start":"2026-02-25T01:55:42.713744Z","end":"2026-02-25T01:55:42.982152Z","steps":["trace[948443194] 'process raft request' (duration: 267.610623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:55:42.982321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.618907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T01:55:42.982477Z","caller":"traceutil/trace.go:171","msg":"trace[2101499101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:429; }","duration":"168.856082ms","start":"2026-02-25T01:55:42.813586Z","end":"2026-02-25T01:55:42.982443Z","steps":["trace[2101499101] 'agreement among raft nodes before linearized reading' (duration: 168.566495ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:55:54.620229Z","caller":"traceutil/trace.go:171","msg":"trace[760594750] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"190.005344ms","start":"2026-02-25T01:55:54.430194Z","end":"2026-02-25T01:55:54.6202Z","steps":["trace[760594750] 'process raft request' (duration: 189.8226ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:55:54.813088Z","caller":"traceutil/trace.go:171","msg":"trace[968396861] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:580; }","duration":"259.328804ms","start":"2026-02-25T01:55:54.553731Z","end":"2026-02-25T01:55:54.81306Z","steps":["trace[968396861] 'read index received' (duration: 65.824061ms)","trace[968396861] 'applied index is now lower than readState.Index' (duration: 193.503383ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:55:54.813136Z","caller":"traceutil/trace.go:171","msg":"trace[631022796] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"371.490723ms","start":"2026-02-25T01:55:54.441614Z","end":"2026-02-25T01:55:54.813105Z","steps":["trace[631022796] 'process raft request' (duration: 371.275769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:55:54.813284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:55:54.441586Z","time spent":"371.629486ms","remote":"127.0.0.1:48966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:55:54.813317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.501468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:55:54.813304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.531469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:55:54.813371Z","caller":"traceutil/trace.go:171","msg":"trace[2065109815] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:564; }","duration":"259.591501ms","start":"2026-02-25T01:55:54.553765Z","end":"2026-02-25T01:55:54.813357Z","steps":["trace[2065109815] 'agreement among raft nodes before linearized reading' (duration: 259.482739ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:55:54.813399Z","caller":"traceutil/trace.go:171","msg":"trace[1920202722] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:564; }","duration":"259.657032ms","start":"2026-02-25T01:55:54.553725Z","end":"2026-02-25T01:55:54.813382Z","steps":["trace[1920202722] 'agreement among raft nodes before linearized reading' (duration: 259.465597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:55:54.813423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.244556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:55:54.813421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.07681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:55:54.81346Z","caller":"traceutil/trace.go:171","msg":"trace[34749145] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:564; }","duration":"228.287987ms","start":"2026-02-25T01:55:54.585156Z","end":"2026-02-25T01:55:54.813444Z","steps":["trace[34749145] 'agreement among raft nodes before linearized reading' (duration: 228.237876ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:55:54.813486Z","caller":"traceutil/trace.go:171","msg":"trace[173079603] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:564; }","duration":"245.177643ms","start":"2026-02-25T01:55:54.568293Z","end":"2026-02-25T01:55:54.81347Z","steps":["trace[173079603] 'agreement among raft nodes before linearized reading' (duration: 245.06334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:55:54.813332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.124086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:55:54.813541Z","caller":"traceutil/trace.go:171","msg":"trace[1176293170] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:564; }","duration":"211.387322ms","start":"2026-02-25T01:55:54.602139Z","end":"2026-02-25T01:55:54.813526Z","steps":["trace[1176293170] 'agreement among raft nodes before linearized reading' (duration: 211.117466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:55:54.813554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.01675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:55:54.8136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.643598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:55:54.81365Z","caller":"traceutil/trace.go:171","msg":"trace[694889268] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:564; }","duration":"258.698669ms","start":"2026-02-25T01:55:54.554937Z","end":"2026-02-25T01:55:54.813636Z","steps":["trace[694889268] 'agreement among raft nodes before linearized reading' (duration: 258.588927ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:55:54.813654Z","caller":"traceutil/trace.go:171","msg":"trace[88361277] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:564; }","duration":"228.121172ms","start":"2026-02-25T01:55:54.585498Z","end":"2026-02-25T01:55:54.813619Z","steps":["trace[88361277] 'agreement among raft nodes before linearized reading' (duration: 227.970459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:55:54.813577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.525332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:55:54.81373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.754096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:55:54.813729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.578064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:55:54.813681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.587444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:55:54.813769Z","caller":"traceutil/trace.go:171","msg":"trace[1881068827] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:564; }","duration":"210.819088ms","start":"2026-02-25T01:55:54.60293Z","end":"2026-02-25T01:55:54.813749Z","steps":["trace[1881068827] 'agreement among raft nodes before linearized reading' (duration: 210.772827ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:55:54.813776Z","caller":"traceutil/trace.go:171","msg":"trace[1782974206] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:564; }","duration":"228.634125ms","start":"2026-02-25T01:55:54.585128Z","end":"2026-02-25T01:55:54.813762Z","steps":["trace[1782974206] 'agreement among raft nodes before linearized reading' (duration: 228.429811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:55:54.813764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.502913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:55:54.813836Z","caller":"traceutil/trace.go:171","msg":"trace[1638939963] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:564; }","duration":"246.773878ms","start":"2026-02-25T01:55:54.567014Z","end":"2026-02-25T01:55:54.813788Z","steps":["trace[1638939963] 'agreement among raft nodes before linearized reading' (duration: 246.491391ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:55:54.813876Z","caller":"traceutil/trace.go:171","msg":"trace[1748007984] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:564; }","duration":"211.632016ms","start":"2026-02-25T01:55:54.60222Z","end":"2026-02-25T01:55:54.813852Z","steps":["trace[1748007984] 'agreement among raft nodes before linearized reading' (duration: 211.395511ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:55:54.813751Z","caller":"traceutil/trace.go:171","msg":"trace[1497417815] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:564; }","duration":"246.780488ms","start":"2026-02-25T01:55:54.566962Z","end":"2026-02-25T01:55:54.813743Z","steps":["trace[1497417815] 'agreement among raft nodes before linearized reading' (duration: 246.480041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:55:54.813955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.968408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:55:54.814069Z","caller":"traceutil/trace.go:171","msg":"trace[452847971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"192.120852ms","start":"2026-02-25T01:55:54.621926Z","end":"2026-02-25T01:55:54.814047Z","steps":["trace[452847971] 'agreement among raft nodes before linearized reading' (duration: 191.491327ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:55:55.098217Z","caller":"traceutil/trace.go:171","msg":"trace[1562727134] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"278.615895ms","start":"2026-02-25T01:55:54.819578Z","end":"2026-02-25T01:55:55.098194Z","steps":["trace[1562727134] 'read index received' (duration: 178.591793ms)","trace[1562727134] 'applied index is now lower than readState.Index' (duration: 100.023172ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:55:55.098508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.478461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:55:55.098579Z","caller":"traceutil/trace.go:171","msg":"trace[1726687545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:565; }","duration":"139.609854ms","start":"2026-02-25T01:55:54.958953Z","end":"2026-02-25T01:55:55.098563Z","steps":["trace[1726687545] 'agreement among raft nodes before linearized reading' (duration: 139.40927ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:55:55.098628Z","caller":"traceutil/trace.go:171","msg":"trace[762527830] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"279.794521ms","start":"2026-02-25T01:55:54.818772Z","end":"2026-02-25T01:55:55.098567Z","steps":["trace[762527830] 'process raft request' (duration: 179.493753ms)","trace[762527830] 'compare' (duration: 99.827818ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:55:55.098853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.234219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:55:55.098971Z","caller":"traceutil/trace.go:171","msg":"trace[1930543488] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-webhook; range_end:; response_count:0; response_revision:565; }","duration":"279.390172ms","start":"2026-02-25T01:55:54.819544Z","end":"2026-02-25T01:55:55.098934Z","steps":["trace[1930543488] 'agreement among raft nodes before linearized reading' (duration: 278.799889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:55:55.996255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.659696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:55:55.996368Z","caller":"traceutil/trace.go:171","msg":"trace[726159349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"374.83578ms","start":"2026-02-25T01:55:55.621509Z","end":"2026-02-25T01:55:55.996345Z","steps":["trace[726159349] 'range keys from in-memory index tree' (duration: 374.555384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:55:55.996323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.52916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cert-manager-controller-approve:cert-manager-io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:55:55.996451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:55:55.621479Z","time spent":"374.945092ms","remote":"127.0.0.1:48746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:55:55.996459Z","caller":"traceutil/trace.go:171","msg":"trace[1190487012] range","detail":"{range_begin:/registry/clusterrolebindings/cert-manager-controller-approve:cert-manager-io; range_end:; response_count:0; response_revision:568; }","duration":"797.695324ms","start":"2026-02-25T01:55:55.19872Z","end":"2026-02-25T01:55:55.996415Z","steps":["trace[1190487012] 'range keys from in-memory index tree' (duration: 797.450289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:55:55.996544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:55:55.198645Z","time spent":"797.882248ms","remote":"127.0.0.1:49156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/cert-manager-controller-approve:cert-manager-io\" "} {"level":"info","ts":"2026-02-25T01:55:56.152627Z","caller":"traceutil/trace.go:171","msg":"trace[641821197] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"109.798714ms","start":"2026-02-25T01:55:56.042802Z","end":"2026-02-25T01:55:56.1526Z","steps":["trace[641821197] 'process raft request' (duration: 109.649741ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:55:56.420051Z","caller":"traceutil/trace.go:171","msg":"trace[485854001] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"263.339406ms","start":"2026-02-25T01:55:56.156678Z","end":"2026-02-25T01:55:56.420017Z","steps":["trace[485854001] 'process raft request' (duration: 263.220073ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:55:56.451699Z","caller":"traceutil/trace.go:171","msg":"trace[661711002] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"294.729782ms","start":"2026-02-25T01:55:56.156949Z","end":"2026-02-25T01:55:56.451679Z","steps":["trace[661711002] 'process raft request' (duration: 294.489727ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:55:56.451989Z","caller":"traceutil/trace.go:171","msg":"trace[734314157] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"208.603558ms","start":"2026-02-25T01:55:56.243359Z","end":"2026-02-25T01:55:56.451962Z","steps":["trace[734314157] 'process raft request' (duration: 208.377953ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:56:20.885138Z","caller":"traceutil/trace.go:171","msg":"trace[678873843] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"108.225278ms","start":"2026-02-25T01:56:20.77689Z","end":"2026-02-25T01:56:20.885115Z","steps":["trace[678873843] 'process raft request' (duration: 108.094995ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:57:31.849958Z","caller":"traceutil/trace.go:171","msg":"trace[864998410] linearizableReadLoop","detail":"{readStateIndex:1624; appliedIndex:1623; }","duration":"185.727206ms","start":"2026-02-25T01:57:31.664208Z","end":"2026-02-25T01:57:31.849935Z","steps":["trace[864998410] 'read index received' (duration: 185.548802ms)","trace[864998410] 'applied index is now lower than readState.Index' (duration: 177.374µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:57:31.850042Z","caller":"traceutil/trace.go:171","msg":"trace[713267566] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"185.995502ms","start":"2026-02-25T01:57:31.664003Z","end":"2026-02-25T01:57:31.849998Z","steps":["trace[713267566] 'process raft request' (duration: 185.778298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:57:31.850206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.975221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T01:57:31.850246Z","caller":"traceutil/trace.go:171","msg":"trace[2125175941] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1559; }","duration":"186.051422ms","start":"2026-02-25T01:57:31.664184Z","end":"2026-02-25T01:57:31.850235Z","steps":["trace[2125175941] 'agreement among raft nodes before linearized reading' (duration: 185.896469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:57:31.850375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.744404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T01:57:31.850391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.041766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3907"} {"level":"warn","ts":"2026-02-25T01:57:31.850523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.183552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:57:31.850541Z","caller":"traceutil/trace.go:171","msg":"trace[1069167770] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1559; }","duration":"173.181619ms","start":"2026-02-25T01:57:31.677275Z","end":"2026-02-25T01:57:31.850457Z","steps":["trace[1069167770] 'agreement among raft nodes before linearized reading' (duration: 172.979155ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:57:31.850471Z","caller":"traceutil/trace.go:171","msg":"trace[89961186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1559; }","duration":"179.878857ms","start":"2026-02-25T01:57:31.670569Z","end":"2026-02-25T01:57:31.850448Z","steps":["trace[89961186] 'agreement among raft nodes before linearized reading' (duration: 179.611801ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:57:31.850574Z","caller":"traceutil/trace.go:171","msg":"trace[1834178197] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1559; }","duration":"179.255194ms","start":"2026-02-25T01:57:31.671306Z","end":"2026-02-25T01:57:31.850561Z","steps":["trace[1834178197] 'agreement among raft nodes before linearized reading' (duration: 179.10448ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:57:31.962294Z","caller":"traceutil/trace.go:171","msg":"trace[175939112] transaction","detail":"{read_only:false; response_revision:1562; number_of_response:1; }","duration":"106.629371ms","start":"2026-02-25T01:57:31.85563Z","end":"2026-02-25T01:57:31.96226Z","steps":["trace[175939112] 'process raft request' (duration: 106.535039ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:57:31.96233Z","caller":"traceutil/trace.go:171","msg":"trace[167399194] transaction","detail":"{read_only:false; response_revision:1561; number_of_response:1; }","duration":"107.961271ms","start":"2026-02-25T01:57:31.854318Z","end":"2026-02-25T01:57:31.962279Z","steps":["trace[167399194] 'process raft request' (duration: 105.976087ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:05:15.479294Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2129} {"level":"info","ts":"2026-02-25T02:05:15.533777Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2129,"took":"53.733586ms","hash":4083167271,"current-db-size-bytes":14540800,"current-db-size":"14 MB","current-db-size-in-use-bytes":4255744,"current-db-size-in-use":"4.3 MB"} {"level":"info","ts":"2026-02-25T02:05:15.533878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4083167271,"revision":2129,"compact-revision":-1} {"level":"info","ts":"2026-02-25T02:10:15.488679Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3058} {"level":"info","ts":"2026-02-25T02:10:15.508422Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3058,"took":"18.923456ms","hash":3001688704,"current-db-size-bytes":14540800,"current-db-size":"14 MB","current-db-size-in-use-bytes":4657152,"current-db-size-in-use":"4.7 MB"} {"level":"info","ts":"2026-02-25T02:10:15.508488Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3001688704,"revision":3058,"compact-revision":2129}