{"level":"warn","ts":"2026-02-05T05:24:54.490584Z","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-05T05:24:54.490653Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.206: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.206:2380","--initial-cluster=instance=https://162.253.55.206:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.206:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.206: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-05T05:24:54.490713Z","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-05T05:24:54.490721Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-02-05T05:24:54.490736Z","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-05T05:24:54.491479Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"]} {"level":"info","ts":"2026-02-05T05:24:54.491706Z","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.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.206: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-05T05:24:54.498726Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.821228ms"} {"level":"info","ts":"2026-02-05T05:24:54.53069Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cfc9fec265e3384a","cluster-id":"9866fe2b0d49ed88"} {"level":"info","ts":"2026-02-05T05:24:54.530805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T05:24:54.530883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 0"} {"level":"info","ts":"2026-02-05T05:24:54.530963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cfc9fec265e3384a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T05:24:54.53102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 1"} {"level":"info","ts":"2026-02-05T05:24:54.531096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"warn","ts":"2026-02-05T05:24:54.538487Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T05:24:54.542247Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T05:24:54.54505Z","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-05T05:24:54.547928Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cfc9fec265e3384a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T05:24:54.548217Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cfc9fec265e3384a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T05:24:54.554191Z","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-05T05:24:54.555171Z","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-05T05:24:54.555238Z","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-05T05:24:54.555011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"info","ts":"2026-02-05T05:24:54.558635Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","added-peer-id":"cfc9fec265e3384a","added-peer-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-02-05T05:24:54.573639Z","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-05T05:24:54.574117Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-02-05T05:24:54.574738Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-02-05T05:24:54.576432Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cfc9fec265e3384a","initial-advertise-peer-urls":["https://162.253.55.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T05:24:54.577115Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T05:24:55.13283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T05:24:55.132877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T05:24:55.132894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgPreVoteResp from cfc9fec265e3384a at term 1"} {"level":"info","ts":"2026-02-05T05:24:55.132905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became candidate at term 2"} {"level":"info","ts":"2026-02-05T05:24:55.132914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgVoteResp from cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-02-05T05:24:55.132921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became leader at term 2"} {"level":"info","ts":"2026-02-05T05:24:55.132928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cfc9fec265e3384a elected leader cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-02-05T05:24:55.139363Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cfc9fec265e3384a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.206:2379]}","request-path":"/0/members/cfc9fec265e3384a/attributes","cluster-id":"9866fe2b0d49ed88","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T05:24:55.139387Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T05:24:55.141408Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:24:55.141482Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T05:24:55.145764Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T05:24:55.146093Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:24:55.146162Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:24:55.146175Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:24:55.160384Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.206:2379"} {"level":"info","ts":"2026-02-05T05:24:55.162714Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T05:24:55.162727Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T05:25:01.47498Z","caller":"traceutil/trace.go:171","msg":"trace[1324349997] transaction","detail":"{read_only:false; number_of_response:0; response_revision:280; }","duration":"118.162524ms","start":"2026-02-05T05:25:01.3568Z","end":"2026-02-05T05:25:01.474962Z","steps":["trace[1324349997] 'process raft request' (duration: 118.093652ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:25:01.475349Z","caller":"traceutil/trace.go:171","msg":"trace[188912434] transaction","detail":"{read_only:false; number_of_response:0; response_revision:280; }","duration":"118.415ms","start":"2026-02-05T05:25:01.356929Z","end":"2026-02-05T05:25:01.475344Z","steps":["trace[188912434] 'process raft request' (duration: 118.336788ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:25:01.475678Z","caller":"traceutil/trace.go:171","msg":"trace[1002177759] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:291; }","duration":"102.5122ms","start":"2026-02-05T05:25:01.373159Z","end":"2026-02-05T05:25:01.475671Z","steps":["trace[1002177759] 'read index received' (duration: 64.52686ms)","trace[1002177759] 'applied index is now lower than readState.Index' (duration: 37.98485ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:25:01.475932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.761457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:25:01.475964Z","caller":"traceutil/trace.go:171","msg":"trace[1646228676] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:0; response_revision:281; }","duration":"102.831808ms","start":"2026-02-05T05:25:01.373125Z","end":"2026-02-05T05:25:01.475957Z","steps":["trace[1646228676] 'agreement among raft nodes before linearized reading' (duration: 102.780186ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:25:01.60479Z","caller":"traceutil/trace.go:171","msg":"trace[350520265] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"117.233595ms","start":"2026-02-05T05:25:01.487516Z","end":"2026-02-05T05:25:01.60475Z","steps":["trace[350520265] 'process raft request' (duration: 59.729118ms)","trace[350520265] 'compare' (duration: 56.725189ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:25:01.768397Z","caller":"traceutil/trace.go:171","msg":"trace[1891205369] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"111.906381ms","start":"2026-02-05T05:25:01.656477Z","end":"2026-02-05T05:25:01.768383Z","steps":["trace[1891205369] 'process raft request' (duration: 102.983663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:01.769674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.899604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:25:01.76972Z","caller":"traceutil/trace.go:171","msg":"trace[155831195] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:287; }","duration":"106.974356ms","start":"2026-02-05T05:25:01.662732Z","end":"2026-02-05T05:25:01.769707Z","steps":["trace[155831195] 'agreement among raft nodes before linearized reading' (duration: 106.887144ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:25:01.770388Z","caller":"traceutil/trace.go:171","msg":"trace[1312069220] linearizableReadLoop","detail":"{readStateIndex:300; appliedIndex:299; }","duration":"105.357498ms","start":"2026-02-05T05:25:01.66275Z","end":"2026-02-05T05:25:01.768107Z","steps":["trace[1312069220] 'read index received' (duration: 96.652736ms)","trace[1312069220] 'applied index is now lower than readState.Index' (duration: 8.703832ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:25:08.693933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:08.289511Z","time spent":"404.417847ms","remote":"127.0.0.1:34404","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-05T05:25:14.428369Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226128312043146,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T05:25:14.516381Z","caller":"traceutil/trace.go:171","msg":"trace[1527957514] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"599.559821ms","start":"2026-02-05T05:25:13.916804Z","end":"2026-02-05T05:25:14.516363Z","steps":["trace[1527957514] 'read index received' (duration: 567.291291ms)","trace[1527957514] 'applied index is now lower than readState.Index' (duration: 32.26748ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:25:14.516484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.677404ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:25:14.516564Z","caller":"traceutil/trace.go:171","msg":"trace[181727203] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:410; }","duration":"599.757776ms","start":"2026-02-05T05:25:13.916787Z","end":"2026-02-05T05:25:14.516544Z","steps":["trace[181727203] 'agreement among raft nodes before linearized reading' (duration: 599.665544ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:25:30.868547Z","caller":"traceutil/trace.go:171","msg":"trace[922718137] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"313.846383ms","start":"2026-02-05T05:25:30.554682Z","end":"2026-02-05T05:25:30.868529Z","steps":["trace[922718137] 'process raft request' (duration: 313.688389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:30.869705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:30.554665Z","time spent":"313.945146ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:25:31.323713Z","caller":"traceutil/trace.go:171","msg":"trace[433773607] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"122.979118ms","start":"2026-02-05T05:25:31.200718Z","end":"2026-02-05T05:25:31.323697Z","steps":["trace[433773607] 'read index received' (duration: 122.838294ms)","trace[433773607] 'applied index is now lower than readState.Index' (duration: 140.154µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:25:31.323823Z","caller":"traceutil/trace.go:171","msg":"trace[253997130] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"190.944166ms","start":"2026-02-05T05:25:31.132873Z","end":"2026-02-05T05:25:31.323818Z","steps":["trace[253997130] 'process raft request' (duration: 190.724531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:31.324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.268964ms","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-05T05:25:31.324049Z","caller":"traceutil/trace.go:171","msg":"trace[722231947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:487; }","duration":"123.353156ms","start":"2026-02-05T05:25:31.200682Z","end":"2026-02-05T05:25:31.324035Z","steps":["trace[722231947] 'agreement among raft nodes before linearized reading' (duration: 123.287505ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:25:33.180812Z","caller":"traceutil/trace.go:171","msg":"trace[673298434] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"103.625587ms","start":"2026-02-05T05:25:33.077165Z","end":"2026-02-05T05:25:33.18079Z","steps":["trace[673298434] 'process raft request' (duration: 103.510905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:36.720887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.886337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:25:36.720962Z","caller":"traceutil/trace.go:171","msg":"trace[899132427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"243.99954ms","start":"2026-02-05T05:25:36.476944Z","end":"2026-02-05T05:25:36.720943Z","steps":["trace[899132427] 'range keys from in-memory index tree' (duration: 243.802825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:36.721053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.796953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lwzmk\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-05T05:25:36.72108Z","caller":"traceutil/trace.go:171","msg":"trace[53241647] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lwzmk; range_end:; response_count:1; response_revision:500; }","duration":"236.837724ms","start":"2026-02-05T05:25:36.484235Z","end":"2026-02-05T05:25:36.721072Z","steps":["trace[53241647] 'range keys from in-memory index tree' (duration: 236.705481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:36.721182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.859748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-05T05:25:36.721215Z","caller":"traceutil/trace.go:171","msg":"trace[663303443] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:500; }","duration":"976.918339ms","start":"2026-02-05T05:25:35.744285Z","end":"2026-02-05T05:25:36.721203Z","steps":["trace[663303443] 'range keys from in-memory index tree' (duration: 976.791376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:36.721245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:35.744256Z","time spent":"976.979821ms","remote":"127.0.0.1:34512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-05T05:25:36.958141Z","caller":"traceutil/trace.go:171","msg":"trace[1818123777] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"223.326579ms","start":"2026-02-05T05:25:36.734797Z","end":"2026-02-05T05:25:36.958124Z","steps":["trace[1818123777] 'process raft request' (duration: 222.95854ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:25:41.735228Z","caller":"traceutil/trace.go:171","msg":"trace[744463506] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"112.856762ms","start":"2026-02-05T05:25:41.622347Z","end":"2026-02-05T05:25:41.735204Z","steps":["trace[744463506] 'process raft request' (duration: 74.613193ms)","trace[744463506] 'compare' (duration: 37.887821ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:25:51.500841Z","caller":"traceutil/trace.go:171","msg":"trace[1610508175] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"250.228278ms","start":"2026-02-05T05:25:51.250586Z","end":"2026-02-05T05:25:51.500814Z","steps":["trace[1610508175] 'process raft request' (duration: 250.093525ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:25:51.503244Z","caller":"traceutil/trace.go:171","msg":"trace[1448423733] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:697; }","duration":"221.025058ms","start":"2026-02-05T05:25:51.282205Z","end":"2026-02-05T05:25:51.50323Z","steps":["trace[1448423733] 'read index received' (duration: 220.555717ms)","trace[1448423733] 'applied index is now lower than readState.Index' (duration: 468.28µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:25:51.503764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.340664ms","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-05T05:25:51.504918Z","caller":"traceutil/trace.go:171","msg":"trace[2010297373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:672; }","duration":"222.732065ms","start":"2026-02-05T05:25:51.282173Z","end":"2026-02-05T05:25:51.504905Z","steps":["trace[2010297373] 'agreement among raft nodes before linearized reading' (duration: 221.219972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:51.826171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.487563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:25:51.826322Z","caller":"traceutil/trace.go:171","msg":"trace[563878221] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:697; }","duration":"218.251566ms","start":"2026-02-05T05:25:51.608061Z","end":"2026-02-05T05:25:51.826313Z","steps":["trace[563878221] 'read index received' (duration: 3.472617ms)","trace[563878221] 'applied index is now lower than readState.Index' (duration: 214.778449ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:25:51.826405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.344399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-4d9sc\" ","response":"range_response_count:1 size:4821"} {"level":"info","ts":"2026-02-05T05:25:51.826428Z","caller":"traceutil/trace.go:171","msg":"trace[165599643] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-4d9sc; range_end:; response_count:1; response_revision:678; }","duration":"218.368939ms","start":"2026-02-05T05:25:51.608055Z","end":"2026-02-05T05:25:51.826424Z","steps":["trace[165599643] 'agreement among raft nodes before linearized reading' (duration: 218.282017ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:25:51.8266Z","caller":"traceutil/trace.go:171","msg":"trace[278995275] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"310.385076ms","start":"2026-02-05T05:25:51.516209Z","end":"2026-02-05T05:25:51.826594Z","steps":["trace[278995275] 'process raft request' (duration: 95.361451ms)","trace[278995275] 'compare' (duration: 214.281158ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:25:51.826629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:51.516197Z","time spent":"310.415647ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:25:51.826695Z","caller":"traceutil/trace.go:171","msg":"trace[1519826892] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"310.364876ms","start":"2026-02-05T05:25:51.516324Z","end":"2026-02-05T05:25:51.826689Z","steps":["trace[1519826892] 'process raft request' (duration: 309.902506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:51.826717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:51.516318Z","time spent":"310.385986ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:25:51.82677Z","caller":"traceutil/trace.go:171","msg":"trace[1492431330] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"307.493162ms","start":"2026-02-05T05:25:51.519272Z","end":"2026-02-05T05:25:51.826765Z","steps":["trace[1492431330] 'process raft request' (duration: 306.97968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:51.826794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:51.519259Z","time spent":"307.521732ms","remote":"127.0.0.1:34666","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-05T05:25:51.826855Z","caller":"traceutil/trace.go:171","msg":"trace[734807808] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"307.498681ms","start":"2026-02-05T05:25:51.519353Z","end":"2026-02-05T05:25:51.826851Z","steps":["trace[734807808] 'process raft request' (duration: 306.912819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:51.826876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:51.519347Z","time spent":"307.517443ms","remote":"127.0.0.1:34666","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-05T05:25:51.826928Z","caller":"traceutil/trace.go:171","msg":"trace[1373275507] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"306.686303ms","start":"2026-02-05T05:25:51.520238Z","end":"2026-02-05T05:25:51.826924Z","steps":["trace[1373275507] 'process raft request' (duration: 306.041809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:51.826947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:51.520181Z","time spent":"306.755944ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:25:51.827001Z","caller":"traceutil/trace.go:171","msg":"trace[545629786] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"218.817679ms","start":"2026-02-05T05:25:51.608179Z","end":"2026-02-05T05:25:51.826996Z","steps":["trace[545629786] 'process raft request' (duration: 218.112883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:52.700805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.868803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:25:52.70085Z","caller":"traceutil/trace.go:171","msg":"trace[1708901305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:678; }","duration":"224.966715ms","start":"2026-02-05T05:25:52.475873Z","end":"2026-02-05T05:25:52.70084Z","steps":["trace[1708901305] 'range keys from in-memory index tree' (duration: 224.796091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:52.701006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.109056ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:25:52.70104Z","caller":"traceutil/trace.go:171","msg":"trace[1973049753] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:678; }","duration":"131.156277ms","start":"2026-02-05T05:25:52.569879Z","end":"2026-02-05T05:25:52.701036Z","steps":["trace[1973049753] 'range keys from in-memory index tree' (duration: 131.102016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:54.482993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.577192ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-05T05:25:54.483075Z","caller":"traceutil/trace.go:171","msg":"trace[1403395170] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"648.206841ms","start":"2026-02-05T05:25:53.834853Z","end":"2026-02-05T05:25:54.48306Z","steps":["trace[1403395170] 'read index received' (duration: 74.562µs)","trace[1403395170] 'applied index is now lower than readState.Index' (duration: 648.131159ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:25:54.483324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.461197ms","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":"warn","ts":"2026-02-05T05:25:54.483425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.451547ms","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-05T05:25:54.483475Z","caller":"traceutil/trace.go:171","msg":"trace[688361113] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:678; }","duration":"648.55597ms","start":"2026-02-05T05:25:53.834907Z","end":"2026-02-05T05:25:54.483463Z","steps":["trace[688361113] 'agreement among raft nodes before linearized reading' (duration: 648.396435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:54.483503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:53.834901Z","time spent":"648.594701ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T05:25:54.483838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.412044ms","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-05T05:25:54.483861Z","caller":"traceutil/trace.go:171","msg":"trace[281736722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:678; }","duration":"647.447405ms","start":"2026-02-05T05:25:53.836407Z","end":"2026-02-05T05:25:54.483855Z","steps":["trace[281736722] 'agreement among raft nodes before linearized reading' (duration: 647.387883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:54.483879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:53.836401Z","time spent":"647.473105ms","remote":"127.0.0.1:34666","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-05T05:25:54.483983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.11855ms","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-05T05:25:54.484Z","caller":"traceutil/trace.go:171","msg":"trace[1310728363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:678; }","duration":"648.168122ms","start":"2026-02-05T05:25:53.835827Z","end":"2026-02-05T05:25:54.483995Z","steps":["trace[1310728363] 'agreement among raft nodes before linearized reading' (duration: 648.121491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:54.484019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:53.835817Z","time spent":"648.198092ms","remote":"127.0.0.1:34666","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":"warn","ts":"2026-02-05T05:25:54.48412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.149163ms","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-05T05:25:54.484137Z","caller":"traceutil/trace.go:171","msg":"trace[77009062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:678; }","duration":"649.177924ms","start":"2026-02-05T05:25:53.834954Z","end":"2026-02-05T05:25:54.484132Z","steps":["trace[77009062] 'agreement among raft nodes before linearized reading' (duration: 649.133403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:54.484152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:53.834947Z","time spent":"649.201824ms","remote":"127.0.0.1:34666","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":"info","ts":"2026-02-05T05:25:54.483345Z","caller":"traceutil/trace.go:171","msg":"trace[1546893812] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:678; }","duration":"648.490348ms","start":"2026-02-05T05:25:53.834848Z","end":"2026-02-05T05:25:54.483338Z","steps":["trace[1546893812] 'agreement among raft nodes before linearized reading' (duration: 648.380785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:25:54.487102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:25:53.834793Z","time spent":"652.295442ms","remote":"127.0.0.1:34666","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-05T05:25:56.629338Z","caller":"traceutil/trace.go:171","msg":"trace[1181884895] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"111.752587ms","start":"2026-02-05T05:25:56.517569Z","end":"2026-02-05T05:25:56.629321Z","steps":["trace[1181884895] 'process raft request' (duration: 111.466349ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:08.967813Z","caller":"traceutil/trace.go:171","msg":"trace[1640778881] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:754; }","duration":"211.069446ms","start":"2026-02-05T05:26:08.756726Z","end":"2026-02-05T05:26:08.967795Z","steps":["trace[1640778881] 'read index received' (duration: 68.259358ms)","trace[1640778881] 'applied index is now lower than readState.Index' (duration: 142.809378ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:08.967893Z","caller":"traceutil/trace.go:171","msg":"trace[598626068] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"230.192812ms","start":"2026-02-05T05:26:08.737693Z","end":"2026-02-05T05:26:08.967886Z","steps":["trace[598626068] 'process raft request' (duration: 151.610693ms)","trace[598626068] 'compare' (duration: 78.332903ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:08.968043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.248367ms","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-05T05:26:08.96809Z","caller":"traceutil/trace.go:171","msg":"trace[700356642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:727; }","duration":"143.331659ms","start":"2026-02-05T05:26:08.824743Z","end":"2026-02-05T05:26:08.968075Z","steps":["trace[700356642] 'agreement among raft nodes before linearized reading' (duration: 143.195386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:08.968136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.405633ms","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-05T05:26:08.968159Z","caller":"traceutil/trace.go:171","msg":"trace[661990013] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:727; }","duration":"211.453644ms","start":"2026-02-05T05:26:08.756698Z","end":"2026-02-05T05:26:08.968152Z","steps":["trace[661990013] 'agreement among raft nodes before linearized reading' (duration: 211.384353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:08.968234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.519388ms","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-05T05:26:08.968248Z","caller":"traceutil/trace.go:171","msg":"trace[1739535780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:727; }","duration":"141.548128ms","start":"2026-02-05T05:26:08.826696Z","end":"2026-02-05T05:26:08.968244Z","steps":["trace[1739535780] 'agreement among raft nodes before linearized reading' (duration: 141.505428ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:11.309181Z","caller":"traceutil/trace.go:171","msg":"trace[1022594835] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:759; }","duration":"350.032948ms","start":"2026-02-05T05:26:10.959128Z","end":"2026-02-05T05:26:11.309161Z","steps":["trace[1022594835] 'read index received' (duration: 349.870134ms)","trace[1022594835] 'applied index is now lower than readState.Index' (duration: 162.094µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:11.309433Z","caller":"traceutil/trace.go:171","msg":"trace[1869421160] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"479.312775ms","start":"2026-02-05T05:26:10.830112Z","end":"2026-02-05T05:26:11.309425Z","steps":["trace[1869421160] 'process raft request' (duration: 478.949937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:11.309508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:10.830103Z","time spent":"479.353905ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:26:11.309656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.525349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T05:26:11.309676Z","caller":"traceutil/trace.go:171","msg":"trace[1699517410] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:731; }","duration":"350.57654ms","start":"2026-02-05T05:26:10.959094Z","end":"2026-02-05T05:26:11.30967Z","steps":["trace[1699517410] 'agreement among raft nodes before linearized reading' (duration: 350.540449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:11.309694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:10.959076Z","time spent":"350.613711ms","remote":"127.0.0.1:34600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:26:11.309867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.358108ms","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-05T05:26:11.309891Z","caller":"traceutil/trace.go:171","msg":"trace[1068714825] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:731; }","duration":"338.39433ms","start":"2026-02-05T05:26:10.971491Z","end":"2026-02-05T05:26:11.309885Z","steps":["trace[1068714825] 'agreement among raft nodes before linearized reading' (duration: 338.317588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:11.30993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:10.971484Z","time spent":"338.44068ms","remote":"127.0.0.1:34666","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":"warn","ts":"2026-02-05T05:26:11.310097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.368633ms","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-05T05:26:11.31012Z","caller":"traceutil/trace.go:171","msg":"trace[1124887485] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:731; }","duration":"322.403704ms","start":"2026-02-05T05:26:10.987711Z","end":"2026-02-05T05:26:11.310115Z","steps":["trace[1124887485] 'agreement among raft nodes before linearized reading' (duration: 322.347642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:11.310137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:10.987709Z","time spent":"322.424264ms","remote":"127.0.0.1:34666","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":"warn","ts":"2026-02-05T05:26:11.310203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.500405ms","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-05T05:26:11.310221Z","caller":"traceutil/trace.go:171","msg":"trace[1725284357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:731; }","duration":"322.525286ms","start":"2026-02-05T05:26:10.987691Z","end":"2026-02-05T05:26:11.310217Z","steps":["trace[1725284357] 'agreement among raft nodes before linearized reading' (duration: 322.478465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:11.31024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:10.987686Z","time spent":"322.550016ms","remote":"127.0.0.1:34666","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-05T05:26:11.3103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.3118ms","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-05T05:26:11.310317Z","caller":"traceutil/trace.go:171","msg":"trace[1162475327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:731; }","duration":"330.34247ms","start":"2026-02-05T05:26:10.979971Z","end":"2026-02-05T05:26:11.310313Z","steps":["trace[1162475327] 'agreement among raft nodes before linearized reading' (duration: 330.300799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:11.310332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:10.979964Z","time spent":"330.36473ms","remote":"127.0.0.1:34666","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-05T05:26:15.5993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.501798905s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-05T05:26:15.599368Z","caller":"traceutil/trace.go:171","msg":"trace[1543671931] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:770; }","duration":"1.974470381s","start":"2026-02-05T05:26:13.624885Z","end":"2026-02-05T05:26:15.599355Z","steps":["trace[1543671931] 'read index received' (duration: 472.530033ms)","trace[1543671931] 'applied index is now lower than readState.Index' (duration: 1.501939008s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:15.599812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.695628406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:15.599834Z","caller":"traceutil/trace.go:171","msg":"trace[134588848] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:741; }","duration":"1.695687028s","start":"2026-02-05T05:26:13.90414Z","end":"2026-02-05T05:26:15.599827Z","steps":["trace[134588848] 'agreement among raft nodes before linearized reading' (duration: 1.695639107s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:15.599856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:13.904124Z","time spent":"1.695726529s","remote":"127.0.0.1:34780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:26:15.59987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.555806ms","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-05T05:26:15.599895Z","caller":"traceutil/trace.go:171","msg":"trace[1058586711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:741; }","duration":"166.582366ms","start":"2026-02-05T05:26:15.433305Z","end":"2026-02-05T05:26:15.599888Z","steps":["trace[1058586711] 'agreement among raft nodes before linearized reading' (duration: 166.474524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:15.599995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.135725ms","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":"warn","ts":"2026-02-05T05:26:15.599998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.975109256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:15.600012Z","caller":"traceutil/trace.go:171","msg":"trace[1910183411] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:741; }","duration":"174.178515ms","start":"2026-02-05T05:26:15.425829Z","end":"2026-02-05T05:26:15.600007Z","steps":["trace[1910183411] 'agreement among raft nodes before linearized reading' (duration: 174.126604ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:15.600017Z","caller":"traceutil/trace.go:171","msg":"trace[943925174] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:741; }","duration":"1.975151116s","start":"2026-02-05T05:26:13.624859Z","end":"2026-02-05T05:26:15.60001Z","steps":["trace[943925174] 'agreement among raft nodes before linearized reading' (duration: 1.975121125s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:15.600037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:13.624845Z","time spent":"1.975187287s","remote":"127.0.0.1:34684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:26:15.600108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.603701ms","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-05T05:26:15.600124Z","caller":"traceutil/trace.go:171","msg":"trace[122606289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:741; }","duration":"252.652282ms","start":"2026-02-05T05:26:15.347467Z","end":"2026-02-05T05:26:15.600119Z","steps":["trace[122606289] 'agreement among raft nodes before linearized reading' (duration: 252.606651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:15.600171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.449515ms","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-05T05:26:15.600217Z","caller":"traceutil/trace.go:171","msg":"trace[1793323314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:741; }","duration":"166.505285ms","start":"2026-02-05T05:26:15.433706Z","end":"2026-02-05T05:26:15.600211Z","steps":["trace[1793323314] 'agreement among raft nodes before linearized reading' (duration: 166.429474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:15.600244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.155587ms","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-05T05:26:15.600261Z","caller":"traceutil/trace.go:171","msg":"trace[1736469509] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:741; }","duration":"277.200157ms","start":"2026-02-05T05:26:15.323056Z","end":"2026-02-05T05:26:15.600256Z","steps":["trace[1736469509] 'agreement among raft nodes before linearized reading' (duration: 277.152086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:15.60031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124162453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:15.600323Z","caller":"traceutil/trace.go:171","msg":"trace[1572724042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"1.124198394s","start":"2026-02-05T05:26:14.476121Z","end":"2026-02-05T05:26:15.600319Z","steps":["trace[1572724042] 'agreement among raft nodes before linearized reading' (duration: 1.124175413s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:15.600337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:14.47609Z","time spent":"1.124244414s","remote":"127.0.0.1:34366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:26:15.600125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.572233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:15.600461Z","caller":"traceutil/trace.go:171","msg":"trace[2121602199] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:741; }","duration":"156.907192ms","start":"2026-02-05T05:26:15.443547Z","end":"2026-02-05T05:26:15.600454Z","steps":["trace[2121602199] 'agreement among raft nodes before linearized reading' (duration: 156.563634ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:15.841114Z","caller":"traceutil/trace.go:171","msg":"trace[1263678626] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"232.030973ms","start":"2026-02-05T05:26:15.609068Z","end":"2026-02-05T05:26:15.841099Z","steps":["trace[1263678626] 'process raft request' (duration: 231.922141ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:16.127093Z","caller":"traceutil/trace.go:171","msg":"trace[8205324] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"517.947704ms","start":"2026-02-05T05:26:15.609116Z","end":"2026-02-05T05:26:16.127063Z","steps":["trace[8205324] 'process raft request' (duration: 517.73796ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:16.127096Z","caller":"traceutil/trace.go:171","msg":"trace[1250944146] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:772; }","duration":"514.332193ms","start":"2026-02-05T05:26:15.612743Z","end":"2026-02-05T05:26:16.127075Z","steps":["trace[1250944146] 'read index received' (duration: 228.377881ms)","trace[1250944146] 'applied index is now lower than readState.Index' (duration: 285.953512ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:16.127198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:15.609104Z","time spent":"518.040216ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:26:16.127261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.492667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:16.127291Z","caller":"traceutil/trace.go:171","msg":"trace[1554534310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"514.555649ms","start":"2026-02-05T05:26:15.612727Z","end":"2026-02-05T05:26:16.127283Z","steps":["trace[1554534310] 'agreement among raft nodes before linearized reading' (duration: 514.461877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:16.127337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:15.612721Z","time spent":"514.59627ms","remote":"127.0.0.1:34366","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-05T05:26:16.127541Z","caller":"traceutil/trace.go:171","msg":"trace[1344931482] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"518.371683ms","start":"2026-02-05T05:26:15.60916Z","end":"2026-02-05T05:26:16.127531Z","steps":["trace[1344931482] 'process raft request' (duration: 517.824302ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:16.127654Z","caller":"traceutil/trace.go:171","msg":"trace[1699811053] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"517.530235ms","start":"2026-02-05T05:26:15.610115Z","end":"2026-02-05T05:26:16.127646Z","steps":["trace[1699811053] 'process raft request' (duration: 516.925912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:16.127656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:15.609154Z","time spent":"518.409554ms","remote":"127.0.0.1:34666","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-05T05:26:16.127698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:15.610111Z","time spent":"517.564836ms","remote":"127.0.0.1:34666","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-05T05:26:16.127753Z","caller":"traceutil/trace.go:171","msg":"trace[447259543] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"517.675408ms","start":"2026-02-05T05:26:15.610072Z","end":"2026-02-05T05:26:16.127747Z","steps":["trace[447259543] 'process raft request' (duration: 516.945072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:16.127781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:15.610063Z","time spent":"517.701139ms","remote":"127.0.0.1:34666","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-05T05:26:20.755743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.070125ms","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-05T05:26:20.755813Z","caller":"traceutil/trace.go:171","msg":"trace[1816518001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"588.223567ms","start":"2026-02-05T05:26:20.167573Z","end":"2026-02-05T05:26:20.755797Z","steps":["trace[1816518001] 'range keys from in-memory index tree' (duration: 587.83595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:20.755856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:20.167551Z","time spent":"588.291959ms","remote":"127.0.0.1:34666","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-05T05:26:20.756064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.111965ms","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-05T05:26:20.756091Z","caller":"traceutil/trace.go:171","msg":"trace[699172745] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:754; }","duration":"579.139496ms","start":"2026-02-05T05:26:20.176943Z","end":"2026-02-05T05:26:20.756083Z","steps":["trace[699172745] 'range keys from in-memory index tree' (duration: 579.029144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:20.756116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:20.17691Z","time spent":"579.200477ms","remote":"127.0.0.1:34666","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-05T05:26:20.756268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.438489ms","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-05T05:26:20.756298Z","caller":"traceutil/trace.go:171","msg":"trace[696591993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"578.487351ms","start":"2026-02-05T05:26:20.177802Z","end":"2026-02-05T05:26:20.756289Z","steps":["trace[696591993] 'range keys from in-memory index tree' (duration: 578.357398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:20.756323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:20.177792Z","time spent":"578.524932ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:26:20.756463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.297557ms","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-05T05:26:20.756489Z","caller":"traceutil/trace.go:171","msg":"trace[162641293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:754; }","duration":"578.339317ms","start":"2026-02-05T05:26:20.17814Z","end":"2026-02-05T05:26:20.75648Z","steps":["trace[162641293] 'range keys from in-memory index tree' (duration: 578.229395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:20.756519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:20.178132Z","time spent":"578.381378ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T05:26:21.161134Z","caller":"traceutil/trace.go:171","msg":"trace[1385588010] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:786; }","duration":"212.984618ms","start":"2026-02-05T05:26:20.948129Z","end":"2026-02-05T05:26:21.161114Z","steps":["trace[1385588010] 'read index received' (duration: 212.631869ms)","trace[1385588010] 'applied index is now lower than readState.Index' (duration: 352.009µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:21.161252Z","caller":"traceutil/trace.go:171","msg":"trace[840330000] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"396.964742ms","start":"2026-02-05T05:26:20.764277Z","end":"2026-02-05T05:26:21.161242Z","steps":["trace[840330000] 'process raft request' (duration: 396.579213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:21.161303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.145173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-05T05:26:21.161355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:20.764262Z","time spent":"397.034423ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:21.161365Z","caller":"traceutil/trace.go:171","msg":"trace[1800163425] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:758; }","duration":"213.237124ms","start":"2026-02-05T05:26:20.948088Z","end":"2026-02-05T05:26:21.161325Z","steps":["trace[1800163425] 'agreement among raft nodes before linearized reading' (duration: 213.142312ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:21.161547Z","caller":"traceutil/trace.go:171","msg":"trace[1421072367] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"396.833579ms","start":"2026-02-05T05:26:20.764704Z","end":"2026-02-05T05:26:21.161537Z","steps":["trace[1421072367] 'process raft request' (duration: 396.327008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:21.16163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:20.764691Z","time spent":"396.87687ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:21.16165Z","caller":"traceutil/trace.go:171","msg":"trace[921127977] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"395.274795ms","start":"2026-02-05T05:26:20.766359Z","end":"2026-02-05T05:26:21.161634Z","steps":["trace[921127977] 'process raft request' (duration: 394.713402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:21.16168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:20.766343Z","time spent":"395.319995ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:26:21.836821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.699522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T05:26:21.836946Z","caller":"traceutil/trace.go:171","msg":"trace[963464029] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:758; }","duration":"336.874246ms","start":"2026-02-05T05:26:21.500057Z","end":"2026-02-05T05:26:21.836931Z","steps":["trace[963464029] 'count revisions from in-memory index tree' (duration: 336.635351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:21.837018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:21.500045Z","time spent":"336.944538ms","remote":"127.0.0.1:34746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":66,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:26:21.837351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.813413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:21.837588Z","caller":"traceutil/trace.go:171","msg":"trace[343172279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"363.081329ms","start":"2026-02-05T05:26:21.474495Z","end":"2026-02-05T05:26:21.837576Z","steps":["trace[343172279] 'range keys from in-memory index tree' (duration: 362.749531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:21.837654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:21.474474Z","time spent":"363.172002ms","remote":"127.0.0.1:34366","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-05T05:26:22.29959Z","caller":"traceutil/trace.go:171","msg":"trace[1864498871] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"110.954499ms","start":"2026-02-05T05:26:22.188614Z","end":"2026-02-05T05:26:22.299568Z","steps":["trace[1864498871] 'read index received' (duration: 110.778486ms)","trace[1864498871] 'applied index is now lower than readState.Index' (duration: 175.213µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:22.299765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.126503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T05:26:22.299837Z","caller":"traceutil/trace.go:171","msg":"trace[2088595772] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:759; }","duration":"111.252945ms","start":"2026-02-05T05:26:22.18857Z","end":"2026-02-05T05:26:22.299823Z","steps":["trace[2088595772] 'agreement among raft nodes before linearized reading' (duration: 111.125642ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:22.299943Z","caller":"traceutil/trace.go:171","msg":"trace[878933974] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"306.418277ms","start":"2026-02-05T05:26:21.993515Z","end":"2026-02-05T05:26:22.299934Z","steps":["trace[878933974] 'process raft request' (duration: 305.933637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:22.299998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:21.9935Z","time spent":"306.462568ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:22.462577Z","caller":"traceutil/trace.go:171","msg":"trace[801304031] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"111.87751ms","start":"2026-02-05T05:26:22.350673Z","end":"2026-02-05T05:26:22.46255Z","steps":["trace[801304031] 'process raft request' (duration: 34.865917ms)","trace[801304031] 'compare' (duration: 76.773447ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:24.177362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.6076968s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:24.177458Z","caller":"traceutil/trace.go:171","msg":"trace[549816124] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:760; }","duration":"1.607810832s","start":"2026-02-05T05:26:22.569634Z","end":"2026-02-05T05:26:24.177445Z","steps":["trace[549816124] 'range keys from in-memory index tree' (duration: 1.60768426s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:24.177848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322855552s","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-05T05:26:24.177877Z","caller":"traceutil/trace.go:171","msg":"trace[533313889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:760; }","duration":"1.322926085s","start":"2026-02-05T05:26:22.854943Z","end":"2026-02-05T05:26:24.177869Z","steps":["trace[533313889] 'range keys from in-memory index tree' (duration: 1.322707899s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:24.177904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:22.854922Z","time spent":"1.322975356s","remote":"127.0.0.1:34666","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-05T05:26:24.17867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00904075s","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-05T05:26:24.178694Z","caller":"traceutil/trace.go:171","msg":"trace[1613117244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"1.009109861s","start":"2026-02-05T05:26:23.169577Z","end":"2026-02-05T05:26:24.178687Z","steps":["trace[1613117244] 'range keys from in-memory index tree' (duration: 1.008879276s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:24.17879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:23.169553Z","time spent":"1.009229854s","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:26:24.179009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008906236s","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-05T05:26:24.179029Z","caller":"traceutil/trace.go:171","msg":"trace[1790507755] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:760; }","duration":"1.008947128s","start":"2026-02-05T05:26:23.170076Z","end":"2026-02-05T05:26:24.179023Z","steps":["trace[1790507755] 'range keys from in-memory index tree' (duration: 1.008819084s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:24.179051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:23.170067Z","time spent":"1.008977368s","remote":"127.0.0.1:34666","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-05T05:26:24.17994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.405687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:24.179968Z","caller":"traceutil/trace.go:171","msg":"trace[2627756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"706.460607ms","start":"2026-02-05T05:26:23.473501Z","end":"2026-02-05T05:26:24.179962Z","steps":["trace[2627756] 'range keys from in-memory index tree' (duration: 706.354656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:24.179987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:23.473399Z","time spent":"706.583631ms","remote":"127.0.0.1:34372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:26:24.178205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009714255s","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-05T05:26:24.180451Z","caller":"traceutil/trace.go:171","msg":"trace[1439643929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:760; }","duration":"1.011946564s","start":"2026-02-05T05:26:23.16845Z","end":"2026-02-05T05:26:24.180397Z","steps":["trace[1439643929] 'range keys from in-memory index tree' (duration: 1.009619873s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:24.180544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:23.168431Z","time spent":"1.012071717s","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:26:24.982358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.237834ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-05T05:26:24.990826Z","caller":"traceutil/trace.go:171","msg":"trace[1614847619] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"804.980571ms","start":"2026-02-05T05:26:24.185823Z","end":"2026-02-05T05:26:24.990803Z","steps":["trace[1614847619] 'process raft request' (duration: 796.642865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:24.991065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:24.185808Z","time spent":"805.196836ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:25.06286Z","caller":"traceutil/trace.go:171","msg":"trace[352885992] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:791; }","duration":"758.770712ms","start":"2026-02-05T05:26:24.30407Z","end":"2026-02-05T05:26:25.06284Z","steps":["trace[352885992] 'read index received' (duration: 136.624139ms)","trace[352885992] 'applied index is now lower than readState.Index' (duration: 622.145343ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:25.063898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.819586ms","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-05T05:26:25.063996Z","caller":"traceutil/trace.go:171","msg":"trace[493938509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:764; }","duration":"759.961059ms","start":"2026-02-05T05:26:24.304021Z","end":"2026-02-05T05:26:25.063982Z","steps":["trace[493938509] 'agreement among raft nodes before linearized reading' (duration: 759.753605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:25.064177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:24.304001Z","time spent":"760.162243ms","remote":"127.0.0.1:34666","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":"warn","ts":"2026-02-05T05:26:25.064281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.058436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:25.064332Z","caller":"traceutil/trace.go:171","msg":"trace[861602178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"589.159068ms","start":"2026-02-05T05:26:24.475161Z","end":"2026-02-05T05:26:25.06432Z","steps":["trace[861602178] 'agreement among raft nodes before linearized reading' (duration: 589.038106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:25.064364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:24.475143Z","time spent":"589.213369ms","remote":"127.0.0.1:34366","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-05T05:26:25.063049Z","caller":"traceutil/trace.go:171","msg":"trace[1699590666] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"875.884598ms","start":"2026-02-05T05:26:24.187135Z","end":"2026-02-05T05:26:25.06302Z","steps":["trace[1699590666] 'process raft request' (duration: 875.609682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:25.064624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:24.18713Z","time spent":"877.428793ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:26:25.064707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.984725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:25.064731Z","caller":"traceutil/trace.go:171","msg":"trace[1596306763] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:764; }","duration":"460.031115ms","start":"2026-02-05T05:26:24.604693Z","end":"2026-02-05T05:26:25.064724Z","steps":["trace[1596306763] 'agreement among raft nodes before linearized reading' (duration: 459.990534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:25.064751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:24.604678Z","time spent":"460.067917ms","remote":"127.0.0.1:34636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-02-05T05:26:25.063109Z","caller":"traceutil/trace.go:171","msg":"trace[784294246] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"877.163248ms","start":"2026-02-05T05:26:24.185939Z","end":"2026-02-05T05:26:25.063102Z","steps":["trace[784294246] 'process raft request' (duration: 858.88143ms)","trace[784294246] 'compare' (duration: 17.700974ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:25.064908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:24.185934Z","time spent":"878.951987ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:25.063137Z","caller":"traceutil/trace.go:171","msg":"trace[1850012656] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"875.317486ms","start":"2026-02-05T05:26:24.187815Z","end":"2026-02-05T05:26:25.063132Z","steps":["trace[1850012656] 'process raft request' (duration: 874.974468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:25.065044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:24.187809Z","time spent":"877.213658ms","remote":"127.0.0.1:34666","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-05T05:26:25.231084Z","caller":"traceutil/trace.go:171","msg":"trace[1159724470] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"156.320567ms","start":"2026-02-05T05:26:25.074724Z","end":"2026-02-05T05:26:25.231044Z","steps":["trace[1159724470] 'process raft request' (duration: 141.687902ms)","trace[1159724470] 'compare' (duration: 14.475562ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:39.543649Z","caller":"traceutil/trace.go:171","msg":"trace[1540284759] linearizableReadLoop","detail":"{readStateIndex:889; appliedIndex:888; }","duration":"421.404256ms","start":"2026-02-05T05:26:39.122229Z","end":"2026-02-05T05:26:39.543634Z","steps":["trace[1540284759] 'read index received' (duration: 421.269703ms)","trace[1540284759] 'applied index is now lower than readState.Index' (duration: 134.013µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:39.543842Z","caller":"traceutil/trace.go:171","msg":"trace[224633140] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"459.132936ms","start":"2026-02-05T05:26:39.084703Z","end":"2026-02-05T05:26:39.543836Z","steps":["trace[224633140] 'process raft request' (duration: 458.832649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:39.543907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:39.084691Z","time spent":"459.171106ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:26:39.544065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.840576ms","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-05T05:26:39.544086Z","caller":"traceutil/trace.go:171","msg":"trace[1162148831] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:852; }","duration":"421.887517ms","start":"2026-02-05T05:26:39.122193Z","end":"2026-02-05T05:26:39.54408Z","steps":["trace[1162148831] 'agreement among raft nodes before linearized reading' (duration: 421.813436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:39.5441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:39.12216Z","time spent":"421.937689ms","remote":"127.0.0.1:34666","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-05T05:26:39.544185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.140151ms","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-05T05:26:39.544202Z","caller":"traceutil/trace.go:171","msg":"trace[2016972163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:852; }","duration":"408.181193ms","start":"2026-02-05T05:26:39.136016Z","end":"2026-02-05T05:26:39.544197Z","steps":["trace[2016972163] 'agreement among raft nodes before linearized reading' (duration: 408.141722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:39.544214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:39.136004Z","time spent":"408.208083ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:26:39.544367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.403952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:39.544384Z","caller":"traceutil/trace.go:171","msg":"trace[1797419499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:852; }","duration":"237.440344ms","start":"2026-02-05T05:26:39.30694Z","end":"2026-02-05T05:26:39.54438Z","steps":["trace[1797419499] 'agreement among raft nodes before linearized reading' (duration: 237.416433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:39.544468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.431718ms","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-05T05:26:39.544483Z","caller":"traceutil/trace.go:171","msg":"trace[452498451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:852; }","duration":"239.471249ms","start":"2026-02-05T05:26:39.305008Z","end":"2026-02-05T05:26:39.544479Z","steps":["trace[452498451] 'agreement among raft nodes before linearized reading' (duration: 239.432478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:39.544557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.923701ms","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-05T05:26:39.544573Z","caller":"traceutil/trace.go:171","msg":"trace[1894418475] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:852; }","duration":"382.961621ms","start":"2026-02-05T05:26:39.161607Z","end":"2026-02-05T05:26:39.544569Z","steps":["trace[1894418475] 'agreement among raft nodes before linearized reading' (duration: 382.92335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:39.544587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:39.161597Z","time spent":"382.987971ms","remote":"127.0.0.1:34666","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-05T05:26:39.925408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.87687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:26:39.925542Z","caller":"traceutil/trace.go:171","msg":"trace[1932942857] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"374.035673ms","start":"2026-02-05T05:26:39.551496Z","end":"2026-02-05T05:26:39.925532Z","steps":["trace[1932942857] 'process raft request' (duration: 374.013812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:39.925579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:39.551494Z","time spent":"374.069263ms","remote":"127.0.0.1:34666","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-05T05:26:39.925752Z","caller":"traceutil/trace.go:171","msg":"trace[1214226040] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"374.274087ms","start":"2026-02-05T05:26:39.551473Z","end":"2026-02-05T05:26:39.925747Z","steps":["trace[1214226040] 'process raft request' (duration: 374.022792ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:39.925744Z","caller":"traceutil/trace.go:171","msg":"trace[571401522] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"374.302228ms","start":"2026-02-05T05:26:39.551415Z","end":"2026-02-05T05:26:39.925717Z","steps":["trace[571401522] 'process raft request' (duration: 374.057422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:39.925779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:39.55146Z","time spent":"374.302918ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:26:39.92587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:39.55141Z","time spent":"374.38954ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:26:39.925996Z","caller":"traceutil/trace.go:171","msg":"trace[1883633388] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"374.670307ms","start":"2026-02-05T05:26:39.551316Z","end":"2026-02-05T05:26:39.925987Z","steps":["trace[1883633388] 'process raft request' (duration: 75.163063ms)","trace[1883633388] 'compare' (duration: 298.788418ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:26:39.92603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:39.551302Z","time spent":"374.707777ms","remote":"127.0.0.1:34666","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-05T05:26:48.813028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.383971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:48.813074Z","caller":"traceutil/trace.go:171","msg":"trace[1481307791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:878; }","duration":"339.469233ms","start":"2026-02-05T05:26:48.473593Z","end":"2026-02-05T05:26:48.813062Z","steps":["trace[1481307791] 'range keys from in-memory index tree' (duration: 339.289939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:48.813096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:48.473574Z","time spent":"339.516414ms","remote":"127.0.0.1:34366","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-05T05:26:50.466655Z","caller":"traceutil/trace.go:171","msg":"trace[749858237] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:919; }","duration":"454.026611ms","start":"2026-02-05T05:26:50.012611Z","end":"2026-02-05T05:26:50.466637Z","steps":["trace[749858237] 'read index received' (duration: 453.893528ms)","trace[749858237] 'applied index is now lower than readState.Index' (duration: 132.153µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:50.466749Z","caller":"traceutil/trace.go:171","msg":"trace[941754423] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"455.589917ms","start":"2026-02-05T05:26:50.011153Z","end":"2026-02-05T05:26:50.466743Z","steps":["trace[941754423] 'process raft request' (duration: 455.364862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:50.466839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:50.011144Z","time spent":"455.625327ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:26:50.466935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.183171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-05T05:26:50.466985Z","caller":"traceutil/trace.go:171","msg":"trace[85328275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:881; }","duration":"452.251093ms","start":"2026-02-05T05:26:50.014721Z","end":"2026-02-05T05:26:50.466972Z","steps":["trace[85328275] 'agreement among raft nodes before linearized reading' (duration: 452.11695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:50.467012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:50.014717Z","time spent":"452.289933ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T05:26:50.46719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.574113ms","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-05T05:26:50.467217Z","caller":"traceutil/trace.go:171","msg":"trace[177062156] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:881; }","duration":"454.610435ms","start":"2026-02-05T05:26:50.012599Z","end":"2026-02-05T05:26:50.46721Z","steps":["trace[177062156] 'agreement among raft nodes before linearized reading' (duration: 454.503143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:50.467238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:50.012595Z","time spent":"454.639325ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T05:26:50.467196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.633226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T05:26:50.467292Z","caller":"traceutil/trace.go:171","msg":"trace[1748376541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:881; }","duration":"450.738219ms","start":"2026-02-05T05:26:50.016548Z","end":"2026-02-05T05:26:50.467287Z","steps":["trace[1748376541] 'agreement among raft nodes before linearized reading' (duration: 450.608006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:50.467309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:50.016544Z","time spent":"450.760899ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T05:26:51.894528Z","caller":"traceutil/trace.go:171","msg":"trace[1841603535] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"144.146037ms","start":"2026-02-05T05:26:51.750367Z","end":"2026-02-05T05:26:51.894513Z","steps":["trace[1841603535] 'process raft request' (duration: 144.053085ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:53.338288Z","caller":"traceutil/trace.go:171","msg":"trace[1749479518] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"241.447532ms","start":"2026-02-05T05:26:53.096826Z","end":"2026-02-05T05:26:53.338273Z","steps":["trace[1749479518] 'read index received' (duration: 241.331559ms)","trace[1749479518] 'applied index is now lower than readState.Index' (duration: 115.333µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:53.338486Z","caller":"traceutil/trace.go:171","msg":"trace[1408198482] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"273.734921ms","start":"2026-02-05T05:26:53.064746Z","end":"2026-02-05T05:26:53.338481Z","steps":["trace[1408198482] 'process raft request' (duration: 273.448975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:53.33863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.79787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:53.338651Z","caller":"traceutil/trace.go:171","msg":"trace[1467780083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"241.849321ms","start":"2026-02-05T05:26:53.096796Z","end":"2026-02-05T05:26:53.338646Z","steps":["trace[1467780083] 'agreement among raft nodes before linearized reading' (duration: 241.80896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:53.33874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.950713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:53.338758Z","caller":"traceutil/trace.go:171","msg":"trace[2000897830] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:890; }","duration":"103.993203ms","start":"2026-02-05T05:26:53.23476Z","end":"2026-02-05T05:26:53.338753Z","steps":["trace[2000897830] 'agreement among raft nodes before linearized reading' (duration: 103.965123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:53.757121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.731178ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-05T05:26:54.737479Z","caller":"traceutil/trace.go:171","msg":"trace[230257062] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"212.445247ms","start":"2026-02-05T05:26:54.525015Z","end":"2026-02-05T05:26:54.737461Z","steps":["trace[230257062] 'process raft request' (duration: 212.339034ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:54.751543Z","caller":"traceutil/trace.go:171","msg":"trace[1951620799] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:933; }","duration":"194.245292ms","start":"2026-02-05T05:26:54.557282Z","end":"2026-02-05T05:26:54.751527Z","steps":["trace[1951620799] 'read index received' (duration: 181.617641ms)","trace[1951620799] 'applied index is now lower than readState.Index' (duration: 12.626881ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:54.751566Z","caller":"traceutil/trace.go:171","msg":"trace[173843040] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"225.778623ms","start":"2026-02-05T05:26:54.525768Z","end":"2026-02-05T05:26:54.751547Z","steps":["trace[173843040] 'process raft request' (duration: 225.65381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:54.751682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.379625ms","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-05T05:26:54.751704Z","caller":"traceutil/trace.go:171","msg":"trace[1335771747] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:895; }","duration":"194.448986ms","start":"2026-02-05T05:26:54.557247Z","end":"2026-02-05T05:26:54.751696Z","steps":["trace[1335771747] 'agreement among raft nodes before linearized reading' (duration: 194.335804ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:54.751868Z","caller":"traceutil/trace.go:171","msg":"trace[190655133] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"226.034088ms","start":"2026-02-05T05:26:54.525828Z","end":"2026-02-05T05:26:54.751863Z","steps":["trace[190655133] 'process raft request' (duration: 225.67767ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:56.589529Z","caller":"traceutil/trace.go:171","msg":"trace[865001414] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"115.037809ms","start":"2026-02-05T05:26:56.474473Z","end":"2026-02-05T05:26:56.589511Z","steps":["trace[865001414] 'read index received' (duration: 114.885206ms)","trace[865001414] 'applied index is now lower than readState.Index' (duration: 151.723µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:56.589625Z","caller":"traceutil/trace.go:171","msg":"trace[978252633] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"615.089765ms","start":"2026-02-05T05:26:55.974527Z","end":"2026-02-05T05:26:56.589617Z","steps":["trace[978252633] 'process raft request' (duration: 614.88372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:56.58975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:55.974517Z","time spent":"615.146826ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:26:56.589765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.288026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:26:56.58984Z","caller":"traceutil/trace.go:171","msg":"trace[1772959098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"115.385188ms","start":"2026-02-05T05:26:56.474448Z","end":"2026-02-05T05:26:56.589834Z","steps":["trace[1772959098] 'agreement among raft nodes before linearized reading' (duration: 115.302056ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:26:58.896488Z","caller":"traceutil/trace.go:171","msg":"trace[1779319628] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:942; }","duration":"266.162222ms","start":"2026-02-05T05:26:58.630298Z","end":"2026-02-05T05:26:58.89646Z","steps":["trace[1779319628] 'read index received' (duration: 265.724893ms)","trace[1779319628] 'applied index is now lower than readState.Index' (duration: 436.249µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:26:58.896629Z","caller":"traceutil/trace.go:171","msg":"trace[1429418589] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"299.670788ms","start":"2026-02-05T05:26:58.596944Z","end":"2026-02-05T05:26:58.896615Z","steps":["trace[1429418589] 'process raft request' (duration: 299.124636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:58.897001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.685084ms","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-05T05:26:58.897077Z","caller":"traceutil/trace.go:171","msg":"trace[1006651029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:902; }","duration":"266.797305ms","start":"2026-02-05T05:26:58.630257Z","end":"2026-02-05T05:26:58.897054Z","steps":["trace[1006651029] 'agreement among raft nodes before linearized reading' (duration: 266.619352ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:01.19844Z","caller":"traceutil/trace.go:171","msg":"trace[414307999] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:948; }","duration":"213.536282ms","start":"2026-02-05T05:27:00.984887Z","end":"2026-02-05T05:27:01.198424Z","steps":["trace[414307999] 'read index received' (duration: 213.395459ms)","trace[414307999] 'applied index is now lower than readState.Index' (duration: 140.283µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:01.198678Z","caller":"traceutil/trace.go:171","msg":"trace[1924081286] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"288.135051ms","start":"2026-02-05T05:27:00.910534Z","end":"2026-02-05T05:27:01.198669Z","steps":["trace[1924081286] 'process raft request' (duration: 287.798464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:01.198854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.953391ms","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-05T05:27:01.198873Z","caller":"traceutil/trace.go:171","msg":"trace[111660746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:908; }","duration":"213.984421ms","start":"2026-02-05T05:27:00.984882Z","end":"2026-02-05T05:27:01.198867Z","steps":["trace[111660746] 'agreement among raft nodes before linearized reading' (duration: 213.890499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:01.198993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.773696ms","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-05T05:27:01.199007Z","caller":"traceutil/trace.go:171","msg":"trace[1509252285] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:908; }","duration":"182.826447ms","start":"2026-02-05T05:27:01.016176Z","end":"2026-02-05T05:27:01.199003Z","steps":["trace[1509252285] 'agreement among raft nodes before linearized reading' (duration: 182.759426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:01.199121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.762016ms","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-05T05:27:01.199134Z","caller":"traceutil/trace.go:171","msg":"trace[1610089495] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:908; }","duration":"182.810467ms","start":"2026-02-05T05:27:01.01632Z","end":"2026-02-05T05:27:01.19913Z","steps":["trace[1610089495] 'agreement among raft nodes before linearized reading' (duration: 182.772406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:01.199242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.971591ms","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-05T05:27:01.199255Z","caller":"traceutil/trace.go:171","msg":"trace[127950163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:908; }","duration":"183.003401ms","start":"2026-02-05T05:27:01.016247Z","end":"2026-02-05T05:27:01.199251Z","steps":["trace[127950163] 'agreement among raft nodes before linearized reading' (duration: 182.96444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:02.299877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.224759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:27:02.29995Z","caller":"traceutil/trace.go:171","msg":"trace[1305622198] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"1.095949214s","start":"2026-02-05T05:27:01.203988Z","end":"2026-02-05T05:27:02.299937Z","steps":["trace[1305622198] 'process raft request' (duration: 720.583082ms)","trace[1305622198] 'compare' (duration: 374.857051ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:02.299992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:01.203975Z","time spent":"1.095997826s","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:02.360065Z","caller":"traceutil/trace.go:171","msg":"trace[1061316452] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:949; }","duration":"887.270181ms","start":"2026-02-05T05:27:01.472774Z","end":"2026-02-05T05:27:02.360044Z","steps":["trace[1061316452] 'read index received' (duration: 451.808152ms)","trace[1061316452] 'applied index is now lower than readState.Index' (duration: 435.461109ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:02.360317Z","caller":"traceutil/trace.go:171","msg":"trace[254914565] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"1.156137852s","start":"2026-02-05T05:27:01.20417Z","end":"2026-02-05T05:27:02.360307Z","steps":["trace[254914565] 'process raft request' (duration: 1.155715483s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:02.361063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:01.204163Z","time spent":"1.156182263s","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:02.361252Z","caller":"traceutil/trace.go:171","msg":"trace[1855635537] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"1.156371019s","start":"2026-02-05T05:27:01.204871Z","end":"2026-02-05T05:27:02.361242Z","steps":["trace[1855635537] 'process raft request' (duration: 1.1551067s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:02.361316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:01.204865Z","time spent":"1.15642099s","remote":"127.0.0.1:34666","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-05T05:27:02.361443Z","caller":"traceutil/trace.go:171","msg":"trace[2009740990] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"1.155303735s","start":"2026-02-05T05:27:01.206131Z","end":"2026-02-05T05:27:02.361434Z","steps":["trace[2009740990] 'process raft request' (duration: 1.153882613s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:02.36151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:01.206125Z","time spent":"1.155347027s","remote":"127.0.0.1:34666","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-05T05:27:02.36163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.861086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:02.361667Z","caller":"traceutil/trace.go:171","msg":"trace[1508306781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:912; }","duration":"888.924278ms","start":"2026-02-05T05:27:01.472733Z","end":"2026-02-05T05:27:02.361657Z","steps":["trace[1508306781] 'agreement among raft nodes before linearized reading' (duration: 888.868827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:02.361719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:01.472702Z","time spent":"889.00773ms","remote":"127.0.0.1:34372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:27:02.362355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.809953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T05:27:02.362386Z","caller":"traceutil/trace.go:171","msg":"trace[75038711] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:912; }","duration":"736.876085ms","start":"2026-02-05T05:27:01.625503Z","end":"2026-02-05T05:27:02.362379Z","steps":["trace[75038711] 'agreement among raft nodes before linearized reading' (duration: 736.482866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:02.362407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:01.625484Z","time spent":"736.916016ms","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":90,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-05T05:27:06.418302Z","caller":"traceutil/trace.go:171","msg":"trace[1295081440] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"119.378716ms","start":"2026-02-05T05:27:06.298909Z","end":"2026-02-05T05:27:06.418287Z","steps":["trace[1295081440] 'process raft request' (duration: 119.07613ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:07.408292Z","caller":"traceutil/trace.go:171","msg":"trace[1045969618] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"133.552231ms","start":"2026-02-05T05:27:07.27472Z","end":"2026-02-05T05:27:07.408272Z","steps":["trace[1045969618] 'process raft request' (duration: 133.440589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:18.82096Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226128312044470,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:27:19.356863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226128312044470,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:27:19.422178Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.096929845s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T05:27:19.546171Z","caller":"traceutil/trace.go:171","msg":"trace[1674587657] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"1.225593498s","start":"2026-02-05T05:27:18.320555Z","end":"2026-02-05T05:27:19.546149Z","steps":["trace[1674587657] 'read index received' (duration: 1.225293051s)","trace[1674587657] 'applied index is now lower than readState.Index' (duration: 299.517µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:19.54629Z","caller":"traceutil/trace.go:171","msg":"trace[768284218] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"1.488583529s","start":"2026-02-05T05:27:18.057695Z","end":"2026-02-05T05:27:19.546279Z","steps":["trace[768284218] 'process raft request' (duration: 1.488228331s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:19.546392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:18.057673Z","time spent":"1.48864425s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13254,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:19.546526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.225975936s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T05:27:19.546558Z","caller":"traceutil/trace.go:171","msg":"trace[1242051500] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1011; }","duration":"1.226040277s","start":"2026-02-05T05:27:18.320506Z","end":"2026-02-05T05:27:19.546546Z","steps":["trace[1242051500] 'agreement among raft nodes before linearized reading' (duration: 1.225990546s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:19.546586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:18.320484Z","time spent":"1.226094619s","remote":"127.0.0.1:52426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:27:19.546877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.224288379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.206\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T05:27:19.546905Z","caller":"traceutil/trace.go:171","msg":"trace[108678345] range","detail":"{range_begin:/registry/masterleases/162.253.55.206; range_end:; response_count:1; response_revision:1011; }","duration":"1.22434779s","start":"2026-02-05T05:27:18.322548Z","end":"2026-02-05T05:27:19.546896Z","steps":["trace[108678345] 'agreement among raft nodes before linearized reading' (duration: 1.224228587s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:19.54693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:18.322535Z","time spent":"1.224389072s","remote":"127.0.0.1:34404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.206\" "} {"level":"warn","ts":"2026-02-05T05:27:19.547285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.985501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-02-05T05:27:19.547318Z","caller":"traceutil/trace.go:171","msg":"trace[1142371623] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1011; }","duration":"374.058613ms","start":"2026-02-05T05:27:19.173251Z","end":"2026-02-05T05:27:19.54731Z","steps":["trace[1142371623] 'agreement among raft nodes before linearized reading' (duration: 373.979561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:19.547344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:19.173228Z","time spent":"374.110474ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-05T05:27:19.547499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.523778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-02-05T05:27:19.547524Z","caller":"traceutil/trace.go:171","msg":"trace[1616763960] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1011; }","duration":"425.578739ms","start":"2026-02-05T05:27:19.121937Z","end":"2026-02-05T05:27:19.547516Z","steps":["trace[1616763960] 'agreement among raft nodes before linearized reading' (duration: 425.506237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:19.547548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:19.121918Z","time spent":"425.62462ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-05T05:27:19.549815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.219737ms","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-05T05:27:19.549874Z","caller":"traceutil/trace.go:171","msg":"trace[1343681368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1011; }","duration":"926.301809ms","start":"2026-02-05T05:27:18.623564Z","end":"2026-02-05T05:27:19.549866Z","steps":["trace[1343681368] 'agreement among raft nodes before linearized reading' (duration: 926.114074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:19.549939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:18.623482Z","time spent":"926.449042ms","remote":"127.0.0.1:34666","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":"warn","ts":"2026-02-05T05:27:19.5502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.203362ms","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-05T05:27:19.550267Z","caller":"traceutil/trace.go:171","msg":"trace[1452478367] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1011; }","duration":"928.300184ms","start":"2026-02-05T05:27:18.621959Z","end":"2026-02-05T05:27:19.550259Z","steps":["trace[1452478367] 'agreement among raft nodes before linearized reading' (duration: 928.165831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:19.550911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:18.621942Z","time spent":"928.957829ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T05:27:19.551184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.090415ms","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-05T05:27:19.551248Z","caller":"traceutil/trace.go:171","msg":"trace[1717461739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1011; }","duration":"944.178087ms","start":"2026-02-05T05:27:18.60706Z","end":"2026-02-05T05:27:19.551238Z","steps":["trace[1717461739] 'agreement among raft nodes before linearized reading' (duration: 944.060814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:19.551286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:18.607047Z","time spent":"944.231818ms","remote":"127.0.0.1:34666","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-05T05:27:19.55147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051155606s","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-05T05:27:19.551526Z","caller":"traceutil/trace.go:171","msg":"trace[1442405102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"1.051267959s","start":"2026-02-05T05:27:18.500251Z","end":"2026-02-05T05:27:19.551519Z","steps":["trace[1442405102] 'agreement among raft nodes before linearized reading' (duration: 1.051172477s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:19.551565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:18.500234Z","time spent":"1.05132484s","remote":"127.0.0.1:34666","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":"warn","ts":"2026-02-05T05:27:19.551759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078644868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:19.551816Z","caller":"traceutil/trace.go:171","msg":"trace[711705461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"1.07872735s","start":"2026-02-05T05:27:18.47308Z","end":"2026-02-05T05:27:19.551807Z","steps":["trace[711705461] 'agreement among raft nodes before linearized reading' (duration: 1.078659219s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:19.551853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:18.473064Z","time spent":"1.078781151s","remote":"127.0.0.1:34366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:27:20.065326Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226128312044473,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:27:20.566184Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226128312044473,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:27:21.154543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.359879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:27:21.154607Z","caller":"traceutil/trace.go:171","msg":"trace[1554069772] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"1.595085199s","start":"2026-02-05T05:27:19.55951Z","end":"2026-02-05T05:27:21.154595Z","steps":["trace[1554069772] 'process raft request' (duration: 1.389625078s)","trace[1554069772] 'compare' (duration: 205.190665ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:21.154649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:19.559496Z","time spent":"1.59513911s","remote":"127.0.0.1:34682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1524,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:21.197351Z","caller":"traceutil/trace.go:171","msg":"trace[281374930] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"1.635771775s","start":"2026-02-05T05:27:19.561564Z","end":"2026-02-05T05:27:21.197336Z","steps":["trace[281374930] 'process raft request' (duration: 1.635648402s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:21.197632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:19.561546Z","time spent":"1.63604808s","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:21.198004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:19.561897Z","time spent":"1.636104982s","remote":"127.0.0.1:34404","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-05T05:27:21.200619Z","caller":"traceutil/trace.go:171","msg":"trace[1901442823] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1055; }","duration":"1.63872822s","start":"2026-02-05T05:27:19.561879Z","end":"2026-02-05T05:27:21.200607Z","steps":["trace[1901442823] 'read index received' (duration: 1.387443299s)","trace[1901442823] 'applied index is now lower than readState.Index' (duration: 251.284221ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:21.200932Z","caller":"traceutil/trace.go:171","msg":"trace[1733859577] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"1.637080373s","start":"2026-02-05T05:27:19.563842Z","end":"2026-02-05T05:27:21.200922Z","steps":["trace[1733859577] 'process raft request' (duration: 1.634047686s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:21.201093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:19.563835Z","time spent":"1.637199086s","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:21.201487Z","caller":"traceutil/trace.go:171","msg":"trace[71022285] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"1.637509922s","start":"2026-02-05T05:27:19.563877Z","end":"2026-02-05T05:27:21.201386Z","steps":["trace[71022285] 'process raft request' (duration: 1.63647389s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:21.201689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:19.563873Z","time spent":"1.637739437s","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:21.201973Z","caller":"traceutil/trace.go:171","msg":"trace[1942804309] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"1.636216134s","start":"2026-02-05T05:27:19.565748Z","end":"2026-02-05T05:27:21.201964Z","steps":["trace[1942804309] 'process raft request' (duration: 1.63465964s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:21.202201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:19.565742Z","time spent":"1.636432169s","remote":"127.0.0.1:34666","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-05T05:27:21.202417Z","caller":"traceutil/trace.go:171","msg":"trace[848303884] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"1.636624244s","start":"2026-02-05T05:27:19.565785Z","end":"2026-02-05T05:27:21.202409Z","steps":["trace[848303884] 'process raft request' (duration: 1.634649061s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:21.202685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:19.565781Z","time spent":"1.63687853s","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:21.202843Z","caller":"traceutil/trace.go:171","msg":"trace[2110721615] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"1.636166413s","start":"2026-02-05T05:27:19.566671Z","end":"2026-02-05T05:27:21.202837Z","steps":["trace[2110721615] 'process raft request' (duration: 1.63379074s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:21.202981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:19.566663Z","time spent":"1.636296416s","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:21.203187Z","caller":"traceutil/trace.go:171","msg":"trace[1426650442] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"1.635789734s","start":"2026-02-05T05:27:19.567391Z","end":"2026-02-05T05:27:21.20318Z","steps":["trace[1426650442] 'process raft request' (duration: 1.633097015s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:21.203369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:19.567382Z","time spent":"1.635967219s","remote":"127.0.0.1:34896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10772,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:21.20352Z","caller":"traceutil/trace.go:171","msg":"trace[931331519] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"1.634694191s","start":"2026-02-05T05:27:19.56882Z","end":"2026-02-05T05:27:21.203514Z","steps":["trace[931331519] 'process raft request' (duration: 1.631703894s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:21.203673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:19.568814Z","time spent":"1.634840274s","remote":"127.0.0.1:34666","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-05T05:27:21.203822Z","caller":"traceutil/trace.go:171","msg":"trace[1198774274] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"1.633219407s","start":"2026-02-05T05:27:19.570597Z","end":"2026-02-05T05:27:21.203816Z","steps":["trace[1198774274] 'process raft request' (duration: 1.629951085s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:21.203977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:19.57059Z","time spent":"1.63336771s","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:21.204131Z","caller":"traceutil/trace.go:171","msg":"trace[1118375000] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"1.55019779s","start":"2026-02-05T05:27:19.653927Z","end":"2026-02-05T05:27:21.204125Z","steps":["trace[1118375000] 'process raft request' (duration: 1.546645822s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:21.204276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:19.653897Z","time spent":"1.550360134s","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:21.204429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.642548175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:21.204519Z","caller":"traceutil/trace.go:171","msg":"trace[1454421076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"1.642673878s","start":"2026-02-05T05:27:19.561839Z","end":"2026-02-05T05:27:21.204513Z","steps":["trace[1454421076] 'agreement among raft nodes before linearized reading' (duration: 1.642572655s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:21.204611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:19.561832Z","time spent":"1.64277144s","remote":"127.0.0.1:34372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:27:21.204796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.074565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:21.204925Z","caller":"traceutil/trace.go:171","msg":"trace[926780664] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1022; }","duration":"277.236097ms","start":"2026-02-05T05:27:20.92768Z","end":"2026-02-05T05:27:21.204916Z","steps":["trace[926780664] 'agreement among raft nodes before linearized reading' (duration: 277.095284ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:52.030975Z","caller":"traceutil/trace.go:171","msg":"trace[1880859294] linearizableReadLoop","detail":"{readStateIndex:1455; appliedIndex:1453; }","duration":"106.27866ms","start":"2026-02-05T05:28:51.924681Z","end":"2026-02-05T05:28:52.03096Z","steps":["trace[1880859294] 'read index received' (duration: 275.186µs)","trace[1880859294] 'applied index is now lower than readState.Index' (duration: 106.002824ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:52.03124Z","caller":"traceutil/trace.go:171","msg":"trace[1896641033] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"110.901782ms","start":"2026-02-05T05:28:51.920331Z","end":"2026-02-05T05:28:52.031233Z","steps":["trace[1896641033] 'process raft request' (duration: 78.161793ms)","trace[1896641033] 'compare' (duration: 32.358361ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:52.031321Z","caller":"traceutil/trace.go:171","msg":"trace[1224884081] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"109.126423ms","start":"2026-02-05T05:28:51.92219Z","end":"2026-02-05T05:28:52.031317Z","steps":["trace[1224884081] 'process raft request' (duration: 108.739355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:52.031422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.73717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-05T05:28:52.031437Z","caller":"traceutil/trace.go:171","msg":"trace[1845495111] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1392; }","duration":"106.793352ms","start":"2026-02-05T05:28:51.924639Z","end":"2026-02-05T05:28:52.031432Z","steps":["trace[1845495111] 'agreement among raft nodes before linearized reading' (duration: 106.71956ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:24.007849Z","caller":"traceutil/trace.go:171","msg":"trace[2055807807] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"134.915632ms","start":"2026-02-05T05:29:23.872906Z","end":"2026-02-05T05:29:24.007821Z","steps":["trace[2055807807] 'process raft request' (duration: 134.807229ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:26.488833Z","caller":"traceutil/trace.go:171","msg":"trace[258246551] transaction","detail":"{read_only:false; response_revision:1532; number_of_response:1; }","duration":"123.232073ms","start":"2026-02-05T05:29:26.365586Z","end":"2026-02-05T05:29:26.488818Z","steps":["trace[258246551] 'process raft request' (duration: 99.56874ms)","trace[258246551] 'compare' (duration: 23.346717ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:29:36.677725Z","caller":"traceutil/trace.go:171","msg":"trace[1354106682] transaction","detail":"{read_only:false; response_revision:1571; number_of_response:1; }","duration":"122.705932ms","start":"2026-02-05T05:29:36.554997Z","end":"2026-02-05T05:29:36.677703Z","steps":["trace[1354106682] 'process raft request' (duration: 61.908018ms)","trace[1354106682] 'compare' (duration: 60.713022ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:29:36.699084Z","caller":"traceutil/trace.go:171","msg":"trace[806351484] transaction","detail":"{read_only:false; response_revision:1572; number_of_response:1; }","duration":"143.236645ms","start":"2026-02-05T05:29:36.555831Z","end":"2026-02-05T05:29:36.699067Z","steps":["trace[806351484] 'process raft request' (duration: 143.022981ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:48.373612Z","caller":"traceutil/trace.go:171","msg":"trace[743224084] transaction","detail":"{read_only:false; response_revision:1619; number_of_response:1; }","duration":"120.848591ms","start":"2026-02-05T05:29:48.252739Z","end":"2026-02-05T05:29:48.373587Z","steps":["trace[743224084] 'process raft request' (duration: 120.739388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:37.783213Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226128312046625,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:30:38.26485Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.033159408s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T05:30:38.357359Z","caller":"traceutil/trace.go:171","msg":"trace[16064063] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1933; }","duration":"1.080088225s","start":"2026-02-05T05:30:37.277058Z","end":"2026-02-05T05:30:38.357147Z","steps":["trace[16064063] 'read index received' (duration: 987.96258ms)","trace[16064063] 'applied index is now lower than readState.Index' (duration: 92.124595ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:30:38.357624Z","caller":"traceutil/trace.go:171","msg":"trace[2112693771] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"1.12599317s","start":"2026-02-05T05:30:37.231615Z","end":"2026-02-05T05:30:38.357608Z","steps":["trace[2112693771] 'process raft request' (duration: 1.033395884s)","trace[2112693771] 'compare' (duration: 91.937241ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:30:38.357739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:37.231588Z","time spent":"1.126070401s","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:30:38.358057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080994017s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-02-05T05:30:38.358098Z","caller":"traceutil/trace.go:171","msg":"trace[570836013] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1849; }","duration":"1.08118628s","start":"2026-02-05T05:30:37.276895Z","end":"2026-02-05T05:30:38.358082Z","steps":["trace[570836013] 'agreement among raft nodes before linearized reading' (duration: 1.081057168s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:38.358137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:37.276871Z","time spent":"1.081256252s","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-05T05:30:38.365536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021806388s","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-05T05:30:38.365602Z","caller":"traceutil/trace.go:171","msg":"trace[443184027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1850; }","duration":"1.02192076s","start":"2026-02-05T05:30:37.343668Z","end":"2026-02-05T05:30:38.365589Z","steps":["trace[443184027] 'agreement among raft nodes before linearized reading' (duration: 1.021739866s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:38.36563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:37.343647Z","time spent":"1.021978361s","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:30:38.365905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.404167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-05T05:30:38.36593Z","caller":"traceutil/trace.go:171","msg":"trace[310225224] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1850; }","duration":"756.487679ms","start":"2026-02-05T05:30:37.609435Z","end":"2026-02-05T05:30:38.365923Z","steps":["trace[310225224] 'agreement among raft nodes before linearized reading' (duration: 756.405167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:38.36595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:37.609414Z","time spent":"756.53202ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":551,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-05T05:30:38.365966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.212526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:30:38.366017Z","caller":"traceutil/trace.go:171","msg":"trace[1350926355] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1850; }","duration":"523.277478ms","start":"2026-02-05T05:30:37.84271Z","end":"2026-02-05T05:30:38.365988Z","steps":["trace[1350926355] 'agreement among raft nodes before linearized reading' (duration: 523.128664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:38.366044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:37.842696Z","time spent":"523.340709ms","remote":"127.0.0.1:34650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:30:38.366111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.469942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:30:38.36648Z","caller":"traceutil/trace.go:171","msg":"trace[157542742] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"176.564359ms","start":"2026-02-05T05:30:38.189907Z","end":"2026-02-05T05:30:38.366471Z","steps":["trace[157542742] 'process raft request' (duration: 175.391223ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:38.366131Z","caller":"traceutil/trace.go:171","msg":"trace[957962340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1850; }","duration":"892.553024ms","start":"2026-02-05T05:30:37.473572Z","end":"2026-02-05T05:30:38.366126Z","steps":["trace[957962340] 'agreement among raft nodes before linearized reading' (duration: 892.519673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:38.367132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:37.473549Z","time spent":"893.566926ms","remote":"127.0.0.1:34366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:30:38.367157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.488343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:30:38.367179Z","caller":"traceutil/trace.go:171","msg":"trace[1997385480] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1850; }","duration":"107.624317ms","start":"2026-02-05T05:30:38.259549Z","end":"2026-02-05T05:30:38.367173Z","steps":["trace[1997385480] 'agreement among raft nodes before linearized reading' (duration: 107.588156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:38.367289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.645664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-05T05:30:38.367309Z","caller":"traceutil/trace.go:171","msg":"trace[1721633936] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1850; }","duration":"987.719025ms","start":"2026-02-05T05:30:37.379584Z","end":"2026-02-05T05:30:38.367303Z","steps":["trace[1721633936] 'agreement among raft nodes before linearized reading' (duration: 987.657614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:38.367326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:37.379567Z","time spent":"987.755587ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T05:31:11.381364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.676043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:741"} {"level":"info","ts":"2026-02-05T05:31:11.381439Z","caller":"traceutil/trace.go:171","msg":"trace[863094684] range","detail":"{range_begin:/registry/rolebindings/kube-public/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:1995; }","duration":"133.785325ms","start":"2026-02-05T05:31:11.247638Z","end":"2026-02-05T05:31:11.381423Z","steps":["trace[863094684] 'range keys from in-memory index tree' (duration: 133.245653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:31:11.381876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.827972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/kubeadm:bootstrap-signer-clusterinfo\" ","response":"range_response_count:1 size:496"} {"level":"info","ts":"2026-02-05T05:31:11.388283Z","caller":"traceutil/trace.go:171","msg":"trace[1165990878] range","detail":"{range_begin:/registry/rolebindings/kube-public/kubeadm:bootstrap-signer-clusterinfo; range_end:; response_count:1; response_revision:1995; }","duration":"172.270975ms","start":"2026-02-05T05:31:11.215997Z","end":"2026-02-05T05:31:11.388268Z","steps":["trace[1165990878] 'range keys from in-memory index tree' (duration: 164.870261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:31:11.624704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.497523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:741"} {"level":"info","ts":"2026-02-05T05:31:11.62484Z","caller":"traceutil/trace.go:171","msg":"trace[1776574956] range","detail":"{range_begin:/registry/rolebindings/kube-public/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:1996; }","duration":"140.652146ms","start":"2026-02-05T05:31:11.48417Z","end":"2026-02-05T05:31:11.624822Z","steps":["trace[1776574956] 'range keys from in-memory index tree' (duration: 140.442791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:31:11.625247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.52142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/kubeadm:bootstrap-signer-clusterinfo\" ","response":"range_response_count:1 size:371"} {"level":"info","ts":"2026-02-05T05:31:11.625376Z","caller":"traceutil/trace.go:171","msg":"trace[418760493] range","detail":"{range_begin:/registry/roles/kube-public/kubeadm:bootstrap-signer-clusterinfo; range_end:; response_count:1; response_revision:1996; }","duration":"114.664063ms","start":"2026-02-05T05:31:11.510697Z","end":"2026-02-05T05:31:11.625361Z","steps":["trace[418760493] 'range keys from in-memory index tree' (duration: 114.461538ms)"],"step_count":1}