{"level":"warn","ts":"2026-02-03T05:39:03.471424Z","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-03T05:39:03.471541Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52: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.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-03T05:39:03.471623Z","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-03T05:39:03.47164Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-03T05:39:03.471661Z","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-03T05:39:03.472868Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-02-03T05:39:03.47294Z","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":2,"max-cpu-available":2,"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.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-03T05:39:03.482632Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.490112ms"} {"level":"info","ts":"2026-02-03T05:39:03.499096Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-02-03T05:39:03.499145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T05:39:03.499165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-02-03T05:39:03.499175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T05:39:03.499186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-02-03T05:39:03.499214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-02-03T05:39:03.519057Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T05:39:03.529887Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T05:39:03.547041Z","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-03T05:39:03.555461Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T05:39:03.556133Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T05:39:03.556217Z","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-03T05:39:03.55624Z","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-03T05:39:03.556247Z","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-03T05:39:03.556377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-02-03T05:39:03.556426Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-03T05:39:03.582595Z","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-03T05:39:03.582758Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T05:39:03.582782Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T05:39:03.582862Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-03T05:39:03.582873Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-03T05:39:04.301123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T05:39:04.301161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T05:39:04.301184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-02-03T05:39:04.301194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-02-03T05:39:04.301202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-03T05:39:04.301214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-02-03T05:39:04.30122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-03T05:39:04.311131Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T05:39:04.319218Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T05:39:04.319602Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T05:39:04.320101Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T05:39:04.321741Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T05:39:04.321763Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T05:39:04.323557Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-02-03T05:39:04.323823Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T05:39:04.323888Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T05:39:04.323909Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T05:39:04.333391Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T05:39:19.4073Z","caller":"traceutil/trace.go:171","msg":"trace[1250480339] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"146.538033ms","start":"2026-02-03T05:39:19.260744Z","end":"2026-02-03T05:39:19.407282Z","steps":["trace[1250480339] 'process raft request' (duration: 146.155543ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:39:34.426879Z","caller":"traceutil/trace.go:171","msg":"trace[1178376131] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"420.037757ms","start":"2026-02-03T05:39:34.006826Z","end":"2026-02-03T05:39:34.426864Z","steps":["trace[1178376131] 'process raft request' (duration: 419.915164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:39:34.427264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:39:34.006817Z","time spent":"420.113548ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T05:39:34.42739Z","caller":"traceutil/trace.go:171","msg":"trace[1443057814] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:532; }","duration":"420.235461ms","start":"2026-02-03T05:39:34.007149Z","end":"2026-02-03T05:39:34.427385Z","steps":["trace[1443057814] 'read index received' (duration: 420.233031ms)","trace[1443057814] 'applied index is now lower than readState.Index' (duration: 2.1µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T05:39:34.427491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.161394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-03T05:39:34.427516Z","caller":"traceutil/trace.go:171","msg":"trace[1999067849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"329.219256ms","start":"2026-02-03T05:39:34.09829Z","end":"2026-02-03T05:39:34.42751Z","steps":["trace[1999067849] 'agreement among raft nodes before linearized reading' (duration: 329.129634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:39:34.42753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:39:34.098278Z","time spent":"329.249177ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T05:39:34.427654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.502587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T05:39:34.427671Z","caller":"traceutil/trace.go:171","msg":"trace[2107921828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"420.529127ms","start":"2026-02-03T05:39:34.007138Z","end":"2026-02-03T05:39:34.427667Z","steps":["trace[2107921828] 'agreement among raft nodes before linearized reading' (duration: 420.502097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:39:34.427683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:39:34.007134Z","time spent":"420.547138ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T05:39:34.427741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.673285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T05:39:34.427757Z","caller":"traceutil/trace.go:171","msg":"trace[625125912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"132.712376ms","start":"2026-02-03T05:39:34.295041Z","end":"2026-02-03T05:39:34.427754Z","steps":["trace[625125912] 'agreement among raft nodes before linearized reading' (duration: 132.689416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:39:40.383788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.900834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mbbf2\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-03T05:39:40.385061Z","caller":"traceutil/trace.go:171","msg":"trace[1481458986] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mbbf2; range_end:; response_count:1; response_revision:532; }","duration":"379.206913ms","start":"2026-02-03T05:39:40.005835Z","end":"2026-02-03T05:39:40.385042Z","steps":["trace[1481458986] 'range keys from in-memory index tree' (duration: 377.746378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:39:40.38534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:39:40.00582Z","time spent":"379.504661ms","remote":"127.0.0.1:47946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16676,"request content":"key:\"/registry/pods/kube-system/cilium-mbbf2\" "} {"level":"info","ts":"2026-02-03T05:39:40.792115Z","caller":"traceutil/trace.go:171","msg":"trace[1575644128] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:550; }","duration":"256.050212ms","start":"2026-02-03T05:39:40.536043Z","end":"2026-02-03T05:39:40.792093Z","steps":["trace[1575644128] 'read index received' (duration: 255.878088ms)","trace[1575644128] 'applied index is now lower than readState.Index' (duration: 171.544µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T05:39:40.79236Z","caller":"traceutil/trace.go:171","msg":"trace[2028116407] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"392.715553ms","start":"2026-02-03T05:39:40.399637Z","end":"2026-02-03T05:39:40.792352Z","steps":["trace[2028116407] 'process raft request' (duration: 392.360665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:39:40.792432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:39:40.399625Z","time spent":"392.753633ms","remote":"127.0.0.1:47946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T05:39:40.792666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.607845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T05:39:40.792732Z","caller":"traceutil/trace.go:171","msg":"trace[1108125324] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"256.716728ms","start":"2026-02-03T05:39:40.536001Z","end":"2026-02-03T05:39:40.792718Z","steps":["trace[1108125324] 'agreement among raft nodes before linearized reading' (duration: 256.545323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:39:40.792747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.3467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T05:39:40.792775Z","caller":"traceutil/trace.go:171","msg":"trace[187521525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:533; }","duration":"256.391641ms","start":"2026-02-03T05:39:40.536376Z","end":"2026-02-03T05:39:40.792768Z","steps":["trace[187521525] 'agreement among raft nodes before linearized reading' (duration: 256.299738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:39:40.792852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.516443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T05:39:40.792868Z","caller":"traceutil/trace.go:171","msg":"trace[87280813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:533; }","duration":"253.550004ms","start":"2026-02-03T05:39:40.539312Z","end":"2026-02-03T05:39:40.792862Z","steps":["trace[87280813] 'agreement among raft nodes before linearized reading' (duration: 253.506013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:39:43.4674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.122205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-03T05:39:43.467473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.196036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T05:39:43.467513Z","caller":"traceutil/trace.go:171","msg":"trace[921292638] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:575; }","duration":"127.375851ms","start":"2026-02-03T05:39:43.340107Z","end":"2026-02-03T05:39:43.467483Z","steps":["trace[921292638] 'range keys from in-memory index tree' (duration: 127.022162ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:39:43.467559Z","caller":"traceutil/trace.go:171","msg":"trace[1341933066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"169.305449ms","start":"2026-02-03T05:39:43.298215Z","end":"2026-02-03T05:39:43.467521Z","steps":["trace[1341933066] 'range keys from in-memory index tree' (duration: 169.124775ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:39:52.966461Z","caller":"traceutil/trace.go:171","msg":"trace[643523555] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"108.068985ms","start":"2026-02-03T05:39:52.858369Z","end":"2026-02-03T05:39:52.966438Z","steps":["trace[643523555] 'process raft request' (duration: 107.891221ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:39:56.054514Z","caller":"traceutil/trace.go:171","msg":"trace[1661002825] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"303.486809ms","start":"2026-02-03T05:39:55.751012Z","end":"2026-02-03T05:39:56.054499Z","steps":["trace[1661002825] 'process raft request' (duration: 303.404157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:39:56.054611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:39:55.751003Z","time spent":"303.558832ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T05:39:56.672678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.130659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T05:39:56.672747Z","caller":"traceutil/trace.go:171","msg":"trace[181330298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:705; }","duration":"375.234271ms","start":"2026-02-03T05:39:56.297497Z","end":"2026-02-03T05:39:56.672731Z","steps":["trace[181330298] 'range keys from in-memory index tree' (duration: 375.076848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:39:56.672785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:39:56.297483Z","time spent":"375.291822ms","remote":"127.0.0.1:47766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T05:39:57.273645Z","caller":"traceutil/trace.go:171","msg":"trace[87767078] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"259.195078ms","start":"2026-02-03T05:39:57.014427Z","end":"2026-02-03T05:39:57.273622Z","steps":["trace[87767078] 'read index received' (duration: 259.167717ms)","trace[87767078] 'applied index is now lower than readState.Index' (duration: 26.351µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T05:39:57.273767Z","caller":"traceutil/trace.go:171","msg":"trace[1463485377] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"263.106589ms","start":"2026-02-03T05:39:57.010634Z","end":"2026-02-03T05:39:57.27374Z","steps":["trace[1463485377] 'process raft request' (duration: 262.864604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:39:57.273993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.549936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T05:39:57.274045Z","caller":"traceutil/trace.go:171","msg":"trace[812911733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:706; }","duration":"259.626567ms","start":"2026-02-03T05:39:57.014403Z","end":"2026-02-03T05:39:57.274029Z","steps":["trace[812911733] 'agreement among raft nodes before linearized reading' (duration: 259.485444ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:39:57.576534Z","caller":"traceutil/trace.go:171","msg":"trace[755406551] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"109.463819ms","start":"2026-02-03T05:39:57.467058Z","end":"2026-02-03T05:39:57.576522Z","steps":["trace[755406551] 'process raft request' (duration: 109.442559ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:39:57.576728Z","caller":"traceutil/trace.go:171","msg":"trace[1729645986] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"479.892897ms","start":"2026-02-03T05:39:57.09683Z","end":"2026-02-03T05:39:57.576723Z","steps":["trace[1729645986] 'process raft request' (duration: 479.016396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:39:57.576781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:39:57.09681Z","time spent":"479.929487ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T05:39:57.576836Z","caller":"traceutil/trace.go:171","msg":"trace[735206632] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:728; }","duration":"303.120471ms","start":"2026-02-03T05:39:57.273711Z","end":"2026-02-03T05:39:57.576832Z","steps":["trace[735206632] 'read index received' (duration: 302.143139ms)","trace[735206632] 'applied index is now lower than readState.Index' (duration: 976.872µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T05:39:57.576877Z","caller":"traceutil/trace.go:171","msg":"trace[172317215] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"298.700638ms","start":"2026-02-03T05:39:57.278173Z","end":"2026-02-03T05:39:57.576873Z","steps":["trace[172317215] 'process raft request' (duration: 298.307249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:39:57.577051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.558329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-03T05:39:57.577066Z","caller":"traceutil/trace.go:171","msg":"trace[1753199792] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:709; }","duration":"440.595661ms","start":"2026-02-03T05:39:57.136466Z","end":"2026-02-03T05:39:57.577062Z","steps":["trace[1753199792] 'agreement among raft nodes before linearized reading' (duration: 440.530399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:39:57.577079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:39:57.136453Z","time spent":"440.623621ms","remote":"127.0.0.1:47800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.52\" "} {"level":"warn","ts":"2026-02-03T05:39:57.577165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.698351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-03T05:39:57.577183Z","caller":"traceutil/trace.go:171","msg":"trace[682997403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:709; }","duration":"549.746642ms","start":"2026-02-03T05:39:57.027433Z","end":"2026-02-03T05:39:57.577179Z","steps":["trace[682997403] 'agreement among raft nodes before linearized reading' (duration: 549.707271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:39:57.577201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:39:57.027409Z","time spent":"549.785564ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-03T05:39:57.577268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.81487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T05:39:57.57728Z","caller":"traceutil/trace.go:171","msg":"trace[1174890642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:709; }","duration":"562.832851ms","start":"2026-02-03T05:39:57.014444Z","end":"2026-02-03T05:39:57.577277Z","steps":["trace[1174890642] 'agreement among raft nodes before linearized reading' (duration: 562.799241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:39:57.577291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:39:57.01444Z","time spent":"562.848832ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T05:39:57.57735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.393363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T05:39:57.577362Z","caller":"traceutil/trace.go:171","msg":"trace[1258338540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:709; }","duration":"279.429203ms","start":"2026-02-03T05:39:57.297928Z","end":"2026-02-03T05:39:57.577357Z","steps":["trace[1258338540] 'agreement among raft nodes before linearized reading' (duration: 279.409933ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:40:13.436456Z","caller":"traceutil/trace.go:171","msg":"trace[59660338] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"106.630812ms","start":"2026-02-03T05:40:13.329801Z","end":"2026-02-03T05:40:13.436432Z","steps":["trace[59660338] 'process raft request' (duration: 106.400177ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:40:14.311367Z","caller":"traceutil/trace.go:171","msg":"trace[334879937] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"153.423335ms","start":"2026-02-03T05:40:14.157923Z","end":"2026-02-03T05:40:14.311347Z","steps":["trace[334879937] 'process raft request' (duration: 153.178769ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:40:17.292825Z","caller":"traceutil/trace.go:171","msg":"trace[1096120431] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"115.450499ms","start":"2026-02-03T05:40:17.177339Z","end":"2026-02-03T05:40:17.292789Z","steps":["trace[1096120431] 'process raft request' (duration: 108.778662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:18.229932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.231876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T05:40:18.230015Z","caller":"traceutil/trace.go:171","msg":"trace[1533669954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:770; }","duration":"503.343819ms","start":"2026-02-03T05:40:17.726651Z","end":"2026-02-03T05:40:18.229995Z","steps":["trace[1533669954] 'range keys from in-memory index tree' (duration: 503.105643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:18.230059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:40:17.726645Z","time spent":"503.40485ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T05:40:18.230199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.096471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T05:40:18.230258Z","caller":"traceutil/trace.go:171","msg":"trace[2144136329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:770; }","duration":"587.189674ms","start":"2026-02-03T05:40:17.643053Z","end":"2026-02-03T05:40:18.230243Z","steps":["trace[2144136329] 'range keys from in-memory index tree' (duration: 586.969858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:18.230293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:40:17.643039Z","time spent":"587.245836ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T05:40:18.230333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.669102ms","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-03T05:40:18.230367Z","caller":"traceutil/trace.go:171","msg":"trace[1133140024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:770; }","duration":"538.727143ms","start":"2026-02-03T05:40:17.691629Z","end":"2026-02-03T05:40:18.230356Z","steps":["trace[1133140024] 'range keys from in-memory index tree' (duration: 538.572888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:18.230395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:40:17.691619Z","time spent":"538.769124ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-03T05:40:18.533528Z","caller":"traceutil/trace.go:171","msg":"trace[1838500487] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"333.950328ms","start":"2026-02-03T05:40:18.19956Z","end":"2026-02-03T05:40:18.53351Z","steps":["trace[1838500487] 'process raft request' (duration: 333.727913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:18.533644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:40:18.199542Z","time spent":"334.04057ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T05:40:18.731798Z","caller":"traceutil/trace.go:171","msg":"trace[1776182896] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:798; }","duration":"385.945673ms","start":"2026-02-03T05:40:18.34578Z","end":"2026-02-03T05:40:18.731726Z","steps":["trace[1776182896] 'read index received' (duration: 187.745563ms)","trace[1776182896] 'applied index is now lower than readState.Index' (duration: 198.1992ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T05:40:18.732023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.219729ms","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-03T05:40:18.732038Z","caller":"traceutil/trace.go:171","msg":"trace[2145967671] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"494.85825ms","start":"2026-02-03T05:40:18.237155Z","end":"2026-02-03T05:40:18.732013Z","steps":["trace[2145967671] 'process raft request' (duration: 494.402778ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:40:18.732102Z","caller":"traceutil/trace.go:171","msg":"trace[173681927] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"493.953609ms","start":"2026-02-03T05:40:18.238137Z","end":"2026-02-03T05:40:18.732091Z","steps":["trace[173681927] 'process raft request' (duration: 493.545988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:18.732158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:40:18.237147Z","time spent":"494.946952ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T05:40:18.732186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:40:18.238131Z","time spent":"494.000549ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T05:40:18.732059Z","caller":"traceutil/trace.go:171","msg":"trace[1049210207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:774; }","duration":"386.306362ms","start":"2026-02-03T05:40:18.345742Z","end":"2026-02-03T05:40:18.732048Z","steps":["trace[1049210207] 'agreement among raft nodes before linearized reading' (duration: 386.143588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:18.732303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:40:18.345728Z","time spent":"386.568718ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-03T05:40:18.73238Z","caller":"traceutil/trace.go:171","msg":"trace[238549455] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"496.947489ms","start":"2026-02-03T05:40:18.235422Z","end":"2026-02-03T05:40:18.732369Z","steps":["trace[238549455] 'process raft request' (duration: 428.863534ms)","trace[238549455] 'compare' (duration: 67.124482ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T05:40:18.732427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:40:18.23541Z","time spent":"496.99147ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T05:40:21.744824Z","caller":"traceutil/trace.go:171","msg":"trace[2037466041] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"196.085645ms","start":"2026-02-03T05:40:21.548714Z","end":"2026-02-03T05:40:21.744799Z","steps":["trace[2037466041] 'process raft request' (duration: 195.949491ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:40:22.982067Z","caller":"traceutil/trace.go:171","msg":"trace[562008594] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"193.73309ms","start":"2026-02-03T05:40:22.788312Z","end":"2026-02-03T05:40:22.982045Z","steps":["trace[562008594] 'process raft request' (duration: 193.693089ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:40:22.982284Z","caller":"traceutil/trace.go:171","msg":"trace[1127331602] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"194.981809ms","start":"2026-02-03T05:40:22.78728Z","end":"2026-02-03T05:40:22.982262Z","steps":["trace[1127331602] 'process raft request' (duration: 194.604391ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:40:36.127269Z","caller":"traceutil/trace.go:171","msg":"trace[1127548683] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"272.781218ms","start":"2026-02-03T05:40:35.854466Z","end":"2026-02-03T05:40:36.127247Z","steps":["trace[1127548683] 'process raft request' (duration: 272.599684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:36.4698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.387717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T05:40:36.469878Z","caller":"traceutil/trace.go:171","msg":"trace[474848525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:873; }","duration":"174.50778ms","start":"2026-02-03T05:40:36.295357Z","end":"2026-02-03T05:40:36.469865Z","steps":["trace[474848525] 'range keys from in-memory index tree' (duration: 174.315696ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:40:38.316977Z","caller":"traceutil/trace.go:171","msg":"trace[1252345595] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"182.970268ms","start":"2026-02-03T05:40:38.133992Z","end":"2026-02-03T05:40:38.316963Z","steps":["trace[1252345595] 'process raft request' (duration: 182.702122ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:40:39.180226Z","caller":"traceutil/trace.go:171","msg":"trace[1431658057] linearizableReadLoop","detail":"{readStateIndex:914; appliedIndex:913; }","duration":"262.679532ms","start":"2026-02-03T05:40:38.917532Z","end":"2026-02-03T05:40:39.180211Z","steps":["trace[1431658057] 'read index received' (duration: 236.206253ms)","trace[1431658057] 'applied index is now lower than readState.Index' (duration: 26.472619ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T05:40:39.180415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.872797ms","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-03T05:40:39.180466Z","caller":"traceutil/trace.go:171","msg":"trace[2044386564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:881; }","duration":"262.958649ms","start":"2026-02-03T05:40:38.917496Z","end":"2026-02-03T05:40:39.180454Z","steps":["trace[2044386564] 'agreement among raft nodes before linearized reading' (duration: 262.815726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:39.180539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.913854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-03T05:40:39.18056Z","caller":"traceutil/trace.go:171","msg":"trace[214486798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:881; }","duration":"140.968506ms","start":"2026-02-03T05:40:39.039585Z","end":"2026-02-03T05:40:39.180554Z","steps":["trace[214486798] 'agreement among raft nodes before linearized reading' (duration: 140.850874ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:40:39.180613Z","caller":"traceutil/trace.go:171","msg":"trace[1659623762] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"300.936976ms","start":"2026-02-03T05:40:38.879667Z","end":"2026-02-03T05:40:39.180604Z","steps":["trace[1659623762] 'process raft request' (duration: 274.12167ms)","trace[1659623762] 'compare' (duration: 26.346136ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T05:40:39.180667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:40:38.879655Z","time spent":"300.973417ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T05:40:39.180684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.903617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-03T05:40:39.180698Z","caller":"traceutil/trace.go:171","msg":"trace[610743765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:881; }","duration":"129.942488ms","start":"2026-02-03T05:40:39.050752Z","end":"2026-02-03T05:40:39.180694Z","steps":["trace[610743765] 'agreement among raft nodes before linearized reading' (duration: 129.902288ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:40:41.386707Z","caller":"traceutil/trace.go:171","msg":"trace[1366687995] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"199.74639ms","start":"2026-02-03T05:40:41.186938Z","end":"2026-02-03T05:40:41.386684Z","steps":["trace[1366687995] 'read index received' (duration: 199.529155ms)","trace[1366687995] 'applied index is now lower than readState.Index' (duration: 216.015µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T05:40:41.386815Z","caller":"traceutil/trace.go:171","msg":"trace[503144869] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"232.068726ms","start":"2026-02-03T05:40:41.154736Z","end":"2026-02-03T05:40:41.386805Z","steps":["trace[503144869] 'process raft request' (duration: 231.81007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:41.387016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.929157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-03T05:40:41.387075Z","caller":"traceutil/trace.go:171","msg":"trace[1224002565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:886; }","duration":"170.995758ms","start":"2026-02-03T05:40:41.216068Z","end":"2026-02-03T05:40:41.387064Z","steps":["trace[1224002565] 'agreement among raft nodes before linearized reading' (duration: 170.857975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:41.387134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.204932ms","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-03T05:40:41.387145Z","caller":"traceutil/trace.go:171","msg":"trace[235886718] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:886; }","duration":"200.248093ms","start":"2026-02-03T05:40:41.186893Z","end":"2026-02-03T05:40:41.387141Z","steps":["trace[235886718] 'agreement among raft nodes before linearized reading' (duration: 200.212942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:41.387227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.654267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-03T05:40:41.387237Z","caller":"traceutil/trace.go:171","msg":"trace[651939543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:886; }","duration":"169.674908ms","start":"2026-02-03T05:40:41.217559Z","end":"2026-02-03T05:40:41.387234Z","steps":["trace[651939543] 'agreement among raft nodes before linearized reading' (duration: 169.642917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:41.387289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.256671ms","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-03T05:40:41.387299Z","caller":"traceutil/trace.go:171","msg":"trace[251533698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:886; }","duration":"170.288441ms","start":"2026-02-03T05:40:41.217007Z","end":"2026-02-03T05:40:41.387296Z","steps":["trace[251533698] 'agreement among raft nodes before linearized reading' (duration: 170.257981ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:40:42.401569Z","caller":"traceutil/trace.go:171","msg":"trace[652081402] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"103.883169ms","start":"2026-02-03T05:40:42.297663Z","end":"2026-02-03T05:40:42.401546Z","steps":["trace[652081402] 'read index received' (duration: 11.577921ms)","trace[652081402] 'applied index is now lower than readState.Index' (duration: 92.304328ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T05:40:42.401766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.096534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T05:40:42.401785Z","caller":"traceutil/trace.go:171","msg":"trace[666823640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"104.147625ms","start":"2026-02-03T05:40:42.297632Z","end":"2026-02-03T05:40:42.401779Z","steps":["trace[666823640] 'agreement among raft nodes before linearized reading' (duration: 104.102934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:47.443718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:40:47.143313Z","time spent":"300.402094ms","remote":"127.0.0.1:47800","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-03T05:40:49.694693Z","caller":"traceutil/trace.go:171","msg":"trace[87220225] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"147.376025ms","start":"2026-02-03T05:40:49.5473Z","end":"2026-02-03T05:40:49.694676Z","steps":["trace[87220225] 'read index received' (duration: 147.255142ms)","trace[87220225] 'applied index is now lower than readState.Index' (duration: 120.423µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T05:40:49.694885Z","caller":"traceutil/trace.go:171","msg":"trace[814578089] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"158.302891ms","start":"2026-02-03T05:40:49.536575Z","end":"2026-02-03T05:40:49.694878Z","steps":["trace[814578089] 'process raft request' (duration: 158.016974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:49.695042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.715053ms","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-03T05:40:49.695059Z","caller":"traceutil/trace.go:171","msg":"trace[1182880937] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:910; }","duration":"147.763914ms","start":"2026-02-03T05:40:49.547289Z","end":"2026-02-03T05:40:49.695053Z","steps":["trace[1182880937] 'agreement among raft nodes before linearized reading' (duration: 147.680502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:49.695142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.200131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T05:40:49.695154Z","caller":"traceutil/trace.go:171","msg":"trace[1838937471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:910; }","duration":"147.220972ms","start":"2026-02-03T05:40:49.54793Z","end":"2026-02-03T05:40:49.695151Z","steps":["trace[1838937471] 'agreement among raft nodes before linearized reading' (duration: 147.186391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:50.249552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.809549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T05:40:50.249691Z","caller":"traceutil/trace.go:171","msg":"trace[1066002538] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"550.168284ms","start":"2026-02-03T05:40:49.699512Z","end":"2026-02-03T05:40:50.24968Z","steps":["trace[1066002538] 'process raft request' (duration: 550.116733ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:40:50.249696Z","caller":"traceutil/trace.go:171","msg":"trace[1740334543] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"550.8609ms","start":"2026-02-03T05:40:49.698816Z","end":"2026-02-03T05:40:50.249677Z","steps":["trace[1740334543] 'process raft request' (duration: 271.792715ms)","trace[1740334543] 'compare' (duration: 278.594434ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T05:40:50.249723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:40:49.6995Z","time spent":"550.208346ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T05:40:50.249785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T05:40:49.698798Z","time spent":"550.939193ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T05:40:50.643343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.301663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T05:40:50.643417Z","caller":"traceutil/trace.go:171","msg":"trace[206583108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:912; }","duration":"179.439976ms","start":"2026-02-03T05:40:50.46396Z","end":"2026-02-03T05:40:50.6434Z","steps":["trace[206583108] 'range keys from in-memory index tree' (duration: 179.144799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T05:40:54.484128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.214738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T05:40:54.484192Z","caller":"traceutil/trace.go:171","msg":"trace[1521772919] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"164.991008ms","start":"2026-02-03T05:40:54.319191Z","end":"2026-02-03T05:40:54.484182Z","steps":["trace[1521772919] 'read index received' (duration: 45.597856ms)","trace[1521772919] 'applied index is now lower than readState.Index' (duration: 119.392222ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T05:40:54.484235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.800438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T05:40:54.484247Z","caller":"traceutil/trace.go:171","msg":"trace[2107135311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:923; }","duration":"186.821359ms","start":"2026-02-03T05:40:54.297421Z","end":"2026-02-03T05:40:54.484242Z","steps":["trace[2107135311] 'agreement among raft nodes before linearized reading' (duration: 186.786219ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:40:54.484466Z","caller":"traceutil/trace.go:171","msg":"trace[1037572992] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"207.86216ms","start":"2026-02-03T05:40:54.276598Z","end":"2026-02-03T05:40:54.48446Z","steps":["trace[1037572992] 'process raft request' (duration: 88.250943ms)","trace[1037572992] 'compare' (duration: 119.139317ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T05:40:54.811476Z","caller":"traceutil/trace.go:171","msg":"trace[853569322] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"139.032592ms","start":"2026-02-03T05:40:54.672395Z","end":"2026-02-03T05:40:54.811427Z","steps":["trace[853569322] 'process raft request' (duration: 138.885448ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:40:59.126555Z","caller":"traceutil/trace.go:171","msg":"trace[788455250] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"279.042824ms","start":"2026-02-03T05:40:58.847489Z","end":"2026-02-03T05:40:59.126531Z","steps":["trace[788455250] 'process raft request' (duration: 278.86808ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:41:05.891337Z","caller":"traceutil/trace.go:171","msg":"trace[252639380] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"116.348011ms","start":"2026-02-03T05:41:05.774599Z","end":"2026-02-03T05:41:05.890947Z","steps":["trace[252639380] 'process raft request' (duration: 115.714306ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:41:10.662393Z","caller":"traceutil/trace.go:171","msg":"trace[21072508] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"116.747119ms","start":"2026-02-03T05:41:10.54562Z","end":"2026-02-03T05:41:10.662367Z","steps":["trace[21072508] 'process raft request' (duration: 91.299794ms)","trace[21072508] 'compare' (duration: 25.356293ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T05:41:10.925124Z","caller":"traceutil/trace.go:171","msg":"trace[1981629504] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"219.202895ms","start":"2026-02-03T05:41:10.705865Z","end":"2026-02-03T05:41:10.925068Z","steps":["trace[1981629504] 'process raft request' (duration: 148.941183ms)","trace[1981629504] 'compare' (duration: 70.15349ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T05:44:08.547477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.164227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T05:44:08.559409Z","caller":"traceutil/trace.go:171","msg":"trace[647433097] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1784; }","duration":"140.263258ms","start":"2026-02-03T05:44:08.407265Z","end":"2026-02-03T05:44:08.547528Z","steps":["trace[647433097] 'count revisions from in-memory index tree' (duration: 140.114395ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:44:14.409371Z","caller":"traceutil/trace.go:171","msg":"trace[255384570] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"148.600504ms","start":"2026-02-03T05:44:14.260751Z","end":"2026-02-03T05:44:14.409351Z","steps":["trace[255384570] 'process raft request' (duration: 148.092683ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T05:44:14.659251Z","caller":"traceutil/trace.go:171","msg":"trace[1699304181] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"244.402461ms","start":"2026-02-03T05:44:14.414832Z","end":"2026-02-03T05:44:14.659234Z","steps":["trace[1699304181] 'process raft request' (duration: 233.737061ms)"],"step_count":1}