{"level":"warn","ts":"2026-05-05T10:19:19.757493Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T10:19:19.757583Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.232: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://199.19.213.232:2380","--initial-cluster=instance=https://199.19.213.232:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.232:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.232: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-05-05T10:19:19.757664Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T10:19:19.757677Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.232:2380"]} {"level":"info","ts":"2026-05-05T10:19:19.757696Z","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-05-05T10:19:19.758358Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.232:2379"]} {"level":"info","ts":"2026-05-05T10:19:19.758431Z","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":8,"max-cpu-available":8,"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://199.19.213.232:2380"],"listen-peer-urls":["https://199.19.213.232:2380"],"advertise-client-urls":["https://199.19.213.232:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.232:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.232: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-05-05T10:19:19.765779Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.14378ms"} {"level":"info","ts":"2026-05-05T10:19:19.777951Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"20a2700e013b1fa5","cluster-id":"b7b35e617ebc7ac7"} {"level":"info","ts":"2026-05-05T10:19:19.778034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T10:19:19.778056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became follower at term 0"} {"level":"info","ts":"2026-05-05T10:19:19.778066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 20a2700e013b1fa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T10:19:19.778077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became follower at term 1"} {"level":"info","ts":"2026-05-05T10:19:19.778108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 switched to configuration voters=(2351565160893325221)"} {"level":"warn","ts":"2026-05-05T10:19:19.788998Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T10:19:19.793047Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T10:19:19.795526Z","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-05-05T10:19:19.797828Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"20a2700e013b1fa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T10:19:19.800112Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"20a2700e013b1fa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T10:19:19.800326Z","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-05-05T10:19:19.800376Z","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-05-05T10:19:19.800545Z","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-05-05T10:19:19.802369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 switched to configuration voters=(2351565160893325221)"} {"level":"info","ts":"2026-05-05T10:19:19.802495Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b7b35e617ebc7ac7","local-member-id":"20a2700e013b1fa5","added-peer-id":"20a2700e013b1fa5","added-peer-peer-urls":["https://199.19.213.232:2380"]} {"level":"info","ts":"2026-05-05T10:19:19.803758Z","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-05-05T10:19:19.803957Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"20a2700e013b1fa5","initial-advertise-peer-urls":["https://199.19.213.232:2380"],"listen-peer-urls":["https://199.19.213.232:2380"],"advertise-client-urls":["https://199.19.213.232:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.232:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T10:19:19.803993Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T10:19:19.80407Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.232:2380"} {"level":"info","ts":"2026-05-05T10:19:19.804087Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.232:2380"} {"level":"info","ts":"2026-05-05T10:19:20.578599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T10:19:20.578864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T10:19:20.579004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 received MsgPreVoteResp from 20a2700e013b1fa5 at term 1"} {"level":"info","ts":"2026-05-05T10:19:20.579141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became candidate at term 2"} {"level":"info","ts":"2026-05-05T10:19:20.57926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 received MsgVoteResp from 20a2700e013b1fa5 at term 2"} {"level":"info","ts":"2026-05-05T10:19:20.579376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became leader at term 2"} {"level":"info","ts":"2026-05-05T10:19:20.579489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 20a2700e013b1fa5 elected leader 20a2700e013b1fa5 at term 2"} {"level":"info","ts":"2026-05-05T10:19:20.773643Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:19:20.788177Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T10:19:20.788241Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T10:19:20.78819Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"20a2700e013b1fa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.232:2379]}","request-path":"/0/members/20a2700e013b1fa5/attributes","cluster-id":"b7b35e617ebc7ac7","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T10:19:20.788809Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T10:19:20.788934Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T10:19:20.79148Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T10:19:20.793219Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.232:2379"} {"level":"info","ts":"2026-05-05T10:19:20.960808Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b7b35e617ebc7ac7","local-member-id":"20a2700e013b1fa5","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:19:20.960976Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:19:20.961038Z","caller":"traceutil/trace.go:171","msg":"trace[935749438] linearizableReadLoop","detail":"{readStateIndex:4; appliedIndex:3; }","duration":"124.132794ms","start":"2026-05-05T10:19:20.836883Z","end":"2026-05-05T10:19:20.961016Z","steps":["trace[935749438] 'read index received' (duration: 123.854726ms)","trace[935749438] 'applied index is now lower than readState.Index' (duration: 276.728µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:19:20.961117Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-05T10:19:20.961251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.340439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T10:19:20.961295Z","caller":"traceutil/trace.go:171","msg":"trace[1188090190] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"124.40764ms","start":"2026-05-05T10:19:20.836875Z","end":"2026-05-05T10:19:20.961283Z","steps":["trace[1188090190] 'agreement among raft nodes before linearized reading' (duration: 124.264097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:20.961494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.532194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T10:19:20.961682Z","caller":"traceutil/trace.go:171","msg":"trace[1095569279] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"124.745249ms","start":"2026-05-05T10:19:20.836923Z","end":"2026-05-05T10:19:20.961668Z","steps":["trace[1095569279] 'agreement among raft nodes before linearized reading' (duration: 124.530734ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:29.412207Z","caller":"traceutil/trace.go:171","msg":"trace[2138917245] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"107.69047ms","start":"2026-05-05T10:19:29.304486Z","end":"2026-05-05T10:19:29.412177Z","steps":["trace[2138917245] 'process raft request' (duration: 107.422983ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:29.412358Z","caller":"traceutil/trace.go:171","msg":"trace[2076290995] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"102.527361ms","start":"2026-05-05T10:19:29.309559Z","end":"2026-05-05T10:19:29.412086Z","steps":["trace[2076290995] 'read index received' (duration: 102.266274ms)","trace[2076290995] 'applied index is now lower than readState.Index' (duration: 260.207µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:19:29.412409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.823558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-mb862\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-05-05T10:19:29.412496Z","caller":"traceutil/trace.go:171","msg":"trace[135558623] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-mb862; range_end:; response_count:1; response_revision:365; }","duration":"102.94346ms","start":"2026-05-05T10:19:29.309533Z","end":"2026-05-05T10:19:29.412477Z","steps":["trace[135558623] 'agreement among raft nodes before linearized reading' (duration: 102.771776ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:55.818057Z","caller":"traceutil/trace.go:171","msg":"trace[832242074] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"234.513407ms","start":"2026-05-05T10:19:55.583522Z","end":"2026-05-05T10:19:55.818036Z","steps":["trace[832242074] 'read index received' (duration: 233.935303ms)","trace[832242074] 'applied index is now lower than readState.Index' (duration: 577.314µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:19:55.818175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.63596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:19:55.818198Z","caller":"traceutil/trace.go:171","msg":"trace[1553190235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"234.728462ms","start":"2026-05-05T10:19:55.583461Z","end":"2026-05-05T10:19:55.81819Z","steps":["trace[1553190235] 'agreement among raft nodes before linearized reading' (duration: 234.668861ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:55.818179Z","caller":"traceutil/trace.go:171","msg":"trace[1274381898] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"302.626123ms","start":"2026-05-05T10:19:55.515474Z","end":"2026-05-05T10:19:55.8181Z","steps":["trace[1274381898] 'process raft request' (duration: 302.134931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:55.818693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:55.515448Z","time spent":"302.779677ms","remote":"127.0.0.1:33952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:19:56.269363Z","caller":"traceutil/trace.go:171","msg":"trace[147327768] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"437.172152ms","start":"2026-05-05T10:19:55.832165Z","end":"2026-05-05T10:19:56.269337Z","steps":["trace[147327768] 'process raft request' (duration: 437.018959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:56.269513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:55.832141Z","time spent":"437.311686ms","remote":"127.0.0.1:34038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:19:56.285667Z","caller":"traceutil/trace.go:171","msg":"trace[1629372084] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"416.434252ms","start":"2026-05-05T10:19:55.869204Z","end":"2026-05-05T10:19:56.285639Z","steps":["trace[1629372084] 'process raft request' (duration: 416.309039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:56.285778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:55.869178Z","time spent":"416.547735ms","remote":"127.0.0.1:34416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T10:19:57.017922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.069715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:19:57.018071Z","caller":"traceutil/trace.go:171","msg":"trace[1647022594] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"572.4516ms","start":"2026-05-05T10:19:56.445576Z","end":"2026-05-05T10:19:57.018027Z","steps":["trace[1647022594] 'read index received' (duration: 51.041µs)","trace[1647022594] 'applied index is now lower than readState.Index' (duration: 572.398579ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:19:57.018165Z","caller":"traceutil/trace.go:171","msg":"trace[516898069] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"709.995843ms","start":"2026-05-05T10:19:56.308152Z","end":"2026-05-05T10:19:57.018148Z","steps":["trace[516898069] 'process raft request' (duration: 47.405736ms)","trace[516898069] 'compare' (duration: 661.829778ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:19:57.018282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:56.308094Z","time spent":"710.106086ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:19:57.018353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.769688ms","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-05-05T10:19:57.018415Z","caller":"traceutil/trace.go:171","msg":"trace[1812932352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"572.87902ms","start":"2026-05-05T10:19:56.445518Z","end":"2026-05-05T10:19:57.018397Z","steps":["trace[1812932352] 'agreement among raft nodes before linearized reading' (duration: 572.684146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:57.018454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:56.445488Z","time spent":"572.958543ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T10:19:57.028775Z","caller":"traceutil/trace.go:171","msg":"trace[822075443] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"497.879304ms","start":"2026-05-05T10:19:56.530868Z","end":"2026-05-05T10:19:57.028747Z","steps":["trace[822075443] 'process raft request' (duration: 497.805172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:57.029066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:56.530798Z","time spent":"498.204043ms","remote":"127.0.0.1:34034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:19:57.466163Z","caller":"traceutil/trace.go:171","msg":"trace[2082646679] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"746.906351ms","start":"2026-05-05T10:19:56.719232Z","end":"2026-05-05T10:19:57.466138Z","steps":["trace[2082646679] 'process raft request' (duration: 739.354165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:57.466303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:56.719204Z","time spent":"747.027944ms","remote":"127.0.0.1:33952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:19:57.466378Z","caller":"traceutil/trace.go:171","msg":"trace[1119459932] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"448.248385ms","start":"2026-05-05T10:19:57.018106Z","end":"2026-05-05T10:19:57.466355Z","steps":["trace[1119459932] 'read index received' (duration: 10.654383ms)","trace[1119459932] 'applied index is now lower than readState.Index' (duration: 437.592422ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:19:57.46655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020203702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-05T10:19:57.46656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.96403ms","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-05-05T10:19:57.466596Z","caller":"traceutil/trace.go:171","msg":"trace[265481560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"1.020273253s","start":"2026-05-05T10:19:56.446309Z","end":"2026-05-05T10:19:57.466582Z","steps":["trace[265481560] 'agreement among raft nodes before linearized reading' (duration: 1.02011601s)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:57.466616Z","caller":"traceutil/trace.go:171","msg":"trace[1253255468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"912.070583ms","start":"2026-05-05T10:19:56.554527Z","end":"2026-05-05T10:19:57.466598Z","steps":["trace[1253255468] 'agreement among raft nodes before linearized reading' (duration: 911.908329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:57.466639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:56.446298Z","time spent":"1.020329505s","remote":"127.0.0.1:34134","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":"warn","ts":"2026-05-05T10:19:57.466648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:56.554498Z","time spent":"912.143165ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T10:19:57.466929Z","caller":"traceutil/trace.go:171","msg":"trace[671452771] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"444.37502ms","start":"2026-05-05T10:19:57.022541Z","end":"2026-05-05T10:19:57.466916Z","steps":["trace[671452771] 'process raft request' (duration: 443.540789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:57.466998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:57.022518Z","time spent":"444.443192ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:19:57.466664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.405825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:19:57.467696Z","caller":"traceutil/trace.go:171","msg":"trace[1243380256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"731.642205ms","start":"2026-05-05T10:19:56.736038Z","end":"2026-05-05T10:19:57.46768Z","steps":["trace[1243380256] 'agreement among raft nodes before linearized reading' (duration: 730.359604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:57.467762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:56.73602Z","time spent":"731.727387ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T10:19:59.206216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.709614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:19:59.206281Z","caller":"traceutil/trace.go:171","msg":"trace[1713294170] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:640; }","duration":"106.820467ms","start":"2026-05-05T10:19:59.099447Z","end":"2026-05-05T10:19:59.206268Z","steps":["trace[1713294170] 'count revisions from in-memory index tree' (duration: 106.638883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:59.206284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.984009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:19:59.206418Z","caller":"traceutil/trace.go:171","msg":"trace[1420748432] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:640; }","duration":"137.131903ms","start":"2026-05-05T10:19:59.069277Z","end":"2026-05-05T10:19:59.206302Z","steps":["trace[1420748432] 'range keys from in-memory index tree' (duration: 136.951538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:59.206445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.0453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:19:59.206466Z","caller":"traceutil/trace.go:171","msg":"trace[776052651] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:640; }","duration":"137.076002ms","start":"2026-05-05T10:19:59.069384Z","end":"2026-05-05T10:19:59.20646Z","steps":["trace[776052651] 'count revisions from in-memory index tree' (duration: 137.02256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:59.206674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.106208ms","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-05-05T10:19:59.206697Z","caller":"traceutil/trace.go:171","msg":"trace[2021650841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"184.164789ms","start":"2026-05-05T10:19:59.022526Z","end":"2026-05-05T10:19:59.206691Z","steps":["trace[2021650841] 'range keys from in-memory index tree' (duration: 183.965095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:59.20679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.183152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:19:59.206811Z","caller":"traceutil/trace.go:171","msg":"trace[2059559401] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:640; }","duration":"106.244693ms","start":"2026-05-05T10:19:59.100562Z","end":"2026-05-05T10:19:59.206806Z","steps":["trace[2059559401] 'range keys from in-memory index tree' (duration: 106.10527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:59.207534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.720439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:19:59.208135Z","caller":"traceutil/trace.go:171","msg":"trace[1331163827] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:640; }","duration":"108.463387ms","start":"2026-05-05T10:19:59.099656Z","end":"2026-05-05T10:19:59.208119Z","steps":["trace[1331163827] 'range keys from in-memory index tree' (duration: 106.683414ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:09.708146Z","caller":"traceutil/trace.go:171","msg":"trace[1434672365] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"106.304634ms","start":"2026-05-05T10:20:09.601814Z","end":"2026-05-05T10:20:09.708118Z","steps":["trace[1434672365] 'process raft request' (duration: 106.237513ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:09.708167Z","caller":"traceutil/trace.go:171","msg":"trace[1702766767] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"106.914261ms","start":"2026-05-05T10:20:09.601231Z","end":"2026-05-05T10:20:09.708145Z","steps":["trace[1702766767] 'process raft request' (duration: 105.729801ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:11.9478Z","caller":"traceutil/trace.go:171","msg":"trace[2129542887] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"231.841102ms","start":"2026-05-05T10:20:11.715934Z","end":"2026-05-05T10:20:11.947775Z","steps":["trace[2129542887] 'process raft request' (duration: 231.647018ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:12.203377Z","caller":"traceutil/trace.go:171","msg":"trace[2060337610] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"487.49843ms","start":"2026-05-05T10:20:11.715854Z","end":"2026-05-05T10:20:12.203352Z","steps":["trace[2060337610] 'process raft request' (duration: 486.051385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:12.203534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:20:11.715843Z","time spent":"487.615343ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:20:25.581017Z","caller":"traceutil/trace.go:171","msg":"trace[1076459064] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"182.884129ms","start":"2026-05-05T10:20:25.398114Z","end":"2026-05-05T10:20:25.580998Z","steps":["trace[1076459064] 'process raft request' (duration: 181.930034ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:26.688531Z","caller":"traceutil/trace.go:171","msg":"trace[1770606796] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"157.575665ms","start":"2026-05-05T10:20:26.530925Z","end":"2026-05-05T10:20:26.6885Z","steps":["trace[1770606796] 'process raft request' (duration: 157.386641ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:26.692231Z","caller":"traceutil/trace.go:171","msg":"trace[2003223620] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"160.679492ms","start":"2026-05-05T10:20:26.531508Z","end":"2026-05-05T10:20:26.692187Z","steps":["trace[2003223620] 'process raft request' (duration: 160.298203ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:26.692984Z","caller":"traceutil/trace.go:171","msg":"trace[221419102] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"161.36376ms","start":"2026-05-05T10:20:26.531593Z","end":"2026-05-05T10:20:26.692957Z","steps":["trace[221419102] 'process raft request' (duration: 160.501128ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:26.692968Z","caller":"traceutil/trace.go:171","msg":"trace[1204627555] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:949; }","duration":"160.367575ms","start":"2026-05-05T10:20:26.532569Z","end":"2026-05-05T10:20:26.692937Z","steps":["trace[1204627555] 'read index received' (duration: 155.840953ms)","trace[1204627555] 'applied index is now lower than readState.Index' (duration: 4.525032ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:20:26.693359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.727954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-grlt7\" ","response":"range_response_count:1 size:5072"} {"level":"info","ts":"2026-05-05T10:20:26.693612Z","caller":"traceutil/trace.go:171","msg":"trace[1479443374] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-grlt7; range_end:; response_count:1; response_revision:922; }","duration":"161.000701ms","start":"2026-05-05T10:20:26.532549Z","end":"2026-05-05T10:20:26.69355Z","steps":["trace[1479443374] 'agreement among raft nodes before linearized reading' (duration: 160.454948ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:46.933115Z","caller":"traceutil/trace.go:171","msg":"trace[2050486003] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"102.948747ms","start":"2026-05-05T10:20:46.830143Z","end":"2026-05-05T10:20:46.933092Z","steps":["trace[2050486003] 'read index received' (duration: 102.759104ms)","trace[2050486003] 'applied index is now lower than readState.Index' (duration: 188.993µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:20:46.933332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.170142ms","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-05-05T10:20:46.933355Z","caller":"traceutil/trace.go:171","msg":"trace[1494396690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:993; }","duration":"103.246414ms","start":"2026-05-05T10:20:46.830102Z","end":"2026-05-05T10:20:46.933348Z","steps":["trace[1494396690] 'agreement among raft nodes before linearized reading' (duration: 103.118841ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:46.933448Z","caller":"traceutil/trace.go:171","msg":"trace[1560768493] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"114.449297ms","start":"2026-05-05T10:20:46.818961Z","end":"2026-05-05T10:20:46.93341Z","steps":["trace[1560768493] 'process raft request' (duration: 114.006118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:47.598714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.996909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:20:47.598858Z","caller":"traceutil/trace.go:171","msg":"trace[1019893179] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"661.210911ms","start":"2026-05-05T10:20:46.937623Z","end":"2026-05-05T10:20:47.598834Z","steps":["trace[1019893179] 'process raft request' (duration: 532.807974ms)","trace[1019893179] 'compare' (duration: 127.858096ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:20:47.598943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:20:46.937606Z","time spent":"661.295413ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:20:47.603318Z","caller":"traceutil/trace.go:171","msg":"trace[894111754] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1029; }","duration":"312.607231ms","start":"2026-05-05T10:20:47.290687Z","end":"2026-05-05T10:20:47.603294Z","steps":["trace[894111754] 'read index received' (duration: 179.75406ms)","trace[894111754] 'applied index is now lower than readState.Index' (duration: 132.849661ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:20:47.603438Z","caller":"traceutil/trace.go:171","msg":"trace[1863433682] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"505.302118ms","start":"2026-05-05T10:20:47.098128Z","end":"2026-05-05T10:20:47.60343Z","steps":["trace[1863433682] 'process raft request' (duration: 505.033023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:47.603526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:20:47.098101Z","time spent":"505.35677ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:20:47.603723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.978051ms","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-05-05T10:20:47.603814Z","caller":"traceutil/trace.go:171","msg":"trace[819602773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:995; }","duration":"107.131374ms","start":"2026-05-05T10:20:47.496662Z","end":"2026-05-05T10:20:47.603794Z","steps":["trace[819602773] 'agreement among raft nodes before linearized reading' (duration: 106.845708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:47.603848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.117501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T10:20:47.603955Z","caller":"traceutil/trace.go:171","msg":"trace[1029635903] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:995; }","duration":"313.263834ms","start":"2026-05-05T10:20:47.290666Z","end":"2026-05-05T10:20:47.60393Z","steps":["trace[1029635903] 'agreement among raft nodes before linearized reading' (duration: 313.014028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:47.604016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:20:47.290592Z","time spent":"313.411347ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-05-05T10:21:11.454734Z","caller":"traceutil/trace.go:171","msg":"trace[1734449930] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"334.485236ms","start":"2026-05-05T10:21:11.120221Z","end":"2026-05-05T10:21:11.454706Z","steps":["trace[1734449930] 'read index received' (duration: 334.19975ms)","trace[1734449930] 'applied index is now lower than readState.Index' (duration: 284.316µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:11.454951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.71312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:21:11.454911Z","caller":"traceutil/trace.go:171","msg":"trace[2043292853] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"407.370566ms","start":"2026-05-05T10:21:11.047498Z","end":"2026-05-05T10:21:11.454868Z","steps":["trace[2043292853] 'process raft request' (duration: 407.048929ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:11.455Z","caller":"traceutil/trace.go:171","msg":"trace[1483605156] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1072; }","duration":"334.784311ms","start":"2026-05-05T10:21:11.120205Z","end":"2026-05-05T10:21:11.454989Z","steps":["trace[1483605156] 'agreement among raft nodes before linearized reading' (duration: 334.688599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:11.455038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:11.120135Z","time spent":"334.894104ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-05T10:21:11.455124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:11.047469Z","time spent":"407.561589ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:21:13.011127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.570969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:21:13.011226Z","caller":"traceutil/trace.go:171","msg":"trace[292655925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1077; }","duration":"428.737642ms","start":"2026-05-05T10:21:12.582467Z","end":"2026-05-05T10:21:13.011204Z","steps":["trace[292655925] 'range keys from in-memory index tree' (duration: 428.456956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:13.011296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:12.582442Z","time spent":"428.844514ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T10:21:14.192071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.853336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:21:14.192205Z","caller":"traceutil/trace.go:171","msg":"trace[2130716815] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"387.182425ms","start":"2026-05-05T10:21:13.805003Z","end":"2026-05-05T10:21:14.192186Z","steps":["trace[2130716815] 'read index received' (duration: 72.089864ms)","trace[2130716815] 'applied index is now lower than readState.Index' (duration: 315.091121ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:21:14.192325Z","caller":"traceutil/trace.go:171","msg":"trace[292986228] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"389.31576ms","start":"2026-05-05T10:21:13.802979Z","end":"2026-05-05T10:21:14.192295Z","steps":["trace[292986228] 'process raft request' (duration: 74.135937ms)","trace[292986228] 'compare' (duration: 314.657512ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:14.192731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.598038ms","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":"warn","ts":"2026-05-05T10:21:14.192804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.083802ms","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-05-05T10:21:14.192885Z","caller":"traceutil/trace.go:171","msg":"trace[1748970860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1081; }","duration":"256.214885ms","start":"2026-05-05T10:21:13.936653Z","end":"2026-05-05T10:21:14.192868Z","steps":["trace[1748970860] 'agreement among raft nodes before linearized reading' (duration: 255.766725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:14.192926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:13.802961Z","time spent":"389.402431ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:21:14.192937Z","caller":"traceutil/trace.go:171","msg":"trace[1143536853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1081; }","duration":"310.797572ms","start":"2026-05-05T10:21:13.882046Z","end":"2026-05-05T10:21:14.192844Z","steps":["trace[1143536853] 'agreement among raft nodes before linearized reading' (duration: 310.307281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:14.192962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.939212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-05T10:21:14.193082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:13.882024Z","time spent":"311.010286ms","remote":"127.0.0.1:34134","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":"info","ts":"2026-05-05T10:21:14.193081Z","caller":"traceutil/trace.go:171","msg":"trace[1272643032] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1081; }","duration":"388.094675ms","start":"2026-05-05T10:21:13.80496Z","end":"2026-05-05T10:21:14.193055Z","steps":["trace[1272643032] 'agreement among raft nodes before linearized reading' (duration: 387.44358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:14.193323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:13.804409Z","time spent":"388.891892ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-05-05T10:21:14.65673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.185824ms","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-05-05T10:21:14.657116Z","caller":"traceutil/trace.go:171","msg":"trace[1797364118] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1082; }","duration":"141.579712ms","start":"2026-05-05T10:21:14.51551Z","end":"2026-05-05T10:21:14.65709Z","steps":["trace[1797364118] 'range keys from in-memory index tree' (duration: 141.163394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:14.657362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.086431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:21:14.657553Z","caller":"traceutil/trace.go:171","msg":"trace[1357080044] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"248.810008ms","start":"2026-05-05T10:21:14.40872Z","end":"2026-05-05T10:21:14.65753Z","steps":["trace[1357080044] 'read index received' (duration: 2.378639ms)","trace[1357080044] 'applied index is now lower than readState.Index' (duration: 246.429609ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:21:14.657625Z","caller":"traceutil/trace.go:171","msg":"trace[619851721] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"459.014723ms","start":"2026-05-05T10:21:14.198558Z","end":"2026-05-05T10:21:14.657573Z","steps":["trace[619851721] 'process raft request' (duration: 212.560594ms)","trace[619851721] 'compare' (duration: 245.52313ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:14.657763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:14.19853Z","time spent":"459.156026ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:21:14.658043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.33344ms","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-05-05T10:21:14.658133Z","caller":"traceutil/trace.go:171","msg":"trace[753713648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1083; }","duration":"249.475663ms","start":"2026-05-05T10:21:14.408643Z","end":"2026-05-05T10:21:14.658118Z","steps":["trace[753713648] 'agreement among raft nodes before linearized reading' (duration: 249.192217ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:14.82243Z","caller":"traceutil/trace.go:171","msg":"trace[1328255231] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"160.337164ms","start":"2026-05-05T10:21:14.662058Z","end":"2026-05-05T10:21:14.822395Z","steps":["trace[1328255231] 'process raft request' (duration: 98.765799ms)","trace[1328255231] 'compare' (duration: 61.454612ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:21:26.734199Z","caller":"traceutil/trace.go:171","msg":"trace[1667565935] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1217; }","duration":"453.751674ms","start":"2026-05-05T10:21:26.280413Z","end":"2026-05-05T10:21:26.734165Z","steps":["trace[1667565935] 'read index received' (duration: 453.56686ms)","trace[1667565935] 'applied index is now lower than readState.Index' (duration: 181.644µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:21:26.734258Z","caller":"traceutil/trace.go:171","msg":"trace[1212986338] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"482.990313ms","start":"2026-05-05T10:21:26.251232Z","end":"2026-05-05T10:21:26.734222Z","steps":["trace[1212986338] 'process raft request' (duration: 482.806769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:26.734371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.618802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:21:26.734407Z","caller":"traceutil/trace.go:171","msg":"trace[788430374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1172; }","duration":"150.725804ms","start":"2026-05-05T10:21:26.583671Z","end":"2026-05-05T10:21:26.734397Z","steps":["trace[788430374] 'agreement among raft nodes before linearized reading' (duration: 150.633503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:26.734439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:26.251219Z","time spent":"483.121287ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:21:26.73443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.0108ms","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-05-05T10:21:26.734502Z","caller":"traceutil/trace.go:171","msg":"trace[734861638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1172; }","duration":"454.131292ms","start":"2026-05-05T10:21:26.280354Z","end":"2026-05-05T10:21:26.734486Z","steps":["trace[734861638] 'agreement among raft nodes before linearized reading' (duration: 453.914737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:26.734527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:26.28033Z","time spent":"454.193314ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T10:21:27.061124Z","caller":"traceutil/trace.go:171","msg":"trace[1482496336] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"322.954805ms","start":"2026-05-05T10:21:26.738137Z","end":"2026-05-05T10:21:27.061092Z","steps":["trace[1482496336] 'process raft request' (duration: 307.046373ms)","trace[1482496336] 'compare' (duration: 15.484774ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:27.061281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:26.73812Z","time spent":"323.068767ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:21:27.063636Z","caller":"traceutil/trace.go:171","msg":"trace[197648436] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1218; }","duration":"189.269328ms","start":"2026-05-05T10:21:26.874329Z","end":"2026-05-05T10:21:27.063598Z","steps":["trace[197648436] 'read index received' (duration: 170.873594ms)","trace[197648436] 'applied index is now lower than readState.Index' (duration: 18.393574ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:27.0639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.555853ms","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-05-05T10:21:27.063963Z","caller":"traceutil/trace.go:171","msg":"trace[453896666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1174; }","duration":"189.690986ms","start":"2026-05-05T10:21:26.874261Z","end":"2026-05-05T10:21:27.063952Z","steps":["trace[453896666] 'agreement among raft nodes before linearized reading' (duration: 189.476422ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:27.064313Z","caller":"traceutil/trace.go:171","msg":"trace[1783950893] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"323.890786ms","start":"2026-05-05T10:21:26.7398Z","end":"2026-05-05T10:21:27.063691Z","steps":["trace[1783950893] 'process raft request' (duration: 323.60674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:27.064432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:26.739769Z","time spent":"324.587669ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:21:45.073602Z","caller":"traceutil/trace.go:171","msg":"trace[369387989] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"234.200547ms","start":"2026-05-05T10:21:44.839376Z","end":"2026-05-05T10:21:45.073577Z","steps":["trace[369387989] 'process raft request' (duration: 234.015513ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:45.750333Z","caller":"traceutil/trace.go:171","msg":"trace[737442276] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"116.225937ms","start":"2026-05-05T10:21:45.634089Z","end":"2026-05-05T10:21:45.750314Z","steps":["trace[737442276] 'process raft request' (duration: 116.125915ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:46.223446Z","caller":"traceutil/trace.go:171","msg":"trace[673714649] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"183.758603ms","start":"2026-05-05T10:21:46.039365Z","end":"2026-05-05T10:21:46.223124Z","steps":["trace[673714649] 'process raft request' (duration: 183.600369ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:47.368269Z","caller":"traceutil/trace.go:171","msg":"trace[1031479930] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"127.407307ms","start":"2026-05-05T10:21:47.240822Z","end":"2026-05-05T10:21:47.368229Z","steps":["trace[1031479930] 'read index received' (duration: 92.255425ms)","trace[1031479930] 'applied index is now lower than readState.Index' (duration: 35.150962ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:21:47.368403Z","caller":"traceutil/trace.go:171","msg":"trace[1303330460] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"137.40039ms","start":"2026-05-05T10:21:47.230831Z","end":"2026-05-05T10:21:47.368232Z","steps":["trace[1303330460] 'process raft request' (duration: 102.254919ms)","trace[1303330460] 'compare' (duration: 34.977827ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:47.368457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.619423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-05T10:21:47.368519Z","caller":"traceutil/trace.go:171","msg":"trace[1745360163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1267; }","duration":"127.724134ms","start":"2026-05-05T10:21:47.24078Z","end":"2026-05-05T10:21:47.368504Z","steps":["trace[1745360163] 'agreement among raft nodes before linearized reading' (duration: 127.573391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:59.711389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.221071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:21:59.711589Z","caller":"traceutil/trace.go:171","msg":"trace[203550181] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"735.635029ms","start":"2026-05-05T10:21:58.975931Z","end":"2026-05-05T10:21:59.711566Z","steps":["trace[203550181] 'process raft request' (duration: 138.585718ms)","trace[203550181] 'compare' (duration: 596.121408ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:59.711694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:58.975903Z","time spent":"735.734491ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:21:59.937524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.020208ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T10:21:59.937815Z","caller":"traceutil/trace.go:171","msg":"trace[1940132598] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1400; }","duration":"934.357685ms","start":"2026-05-05T10:21:59.003428Z","end":"2026-05-05T10:21:59.937786Z","steps":["trace[1940132598] 'read index received' (duration: 111.104572ms)","trace[1940132598] 'applied index is now lower than readState.Index' (duration: 823.251643ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:59.937968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.55444ms","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-05-05T10:21:59.938016Z","caller":"traceutil/trace.go:171","msg":"trace[1310128479] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1349; }","duration":"934.648342ms","start":"2026-05-05T10:21:59.003358Z","end":"2026-05-05T10:21:59.938006Z","steps":["trace[1310128479] 'agreement among raft nodes before linearized reading' (duration: 934.56804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:59.938058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:59.003338Z","time spent":"934.712004ms","remote":"127.0.0.1:43374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-05-05T10:21:59.938562Z","caller":"traceutil/trace.go:171","msg":"trace[521430547] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"468.805182ms","start":"2026-05-05T10:21:59.469734Z","end":"2026-05-05T10:21:59.938539Z","steps":["trace[521430547] 'process raft request' (duration: 467.958002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:59.938687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:59.469711Z","time spent":"468.911785ms","remote":"127.0.0.1:34034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6758,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:21:59.943614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.817645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T10:21:59.94365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.672053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T10:21:59.943668Z","caller":"traceutil/trace.go:171","msg":"trace[277635639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1351; }","duration":"360.918208ms","start":"2026-05-05T10:21:59.582738Z","end":"2026-05-05T10:21:59.943656Z","steps":["trace[277635639] 'agreement among raft nodes before linearized reading' (duration: 360.818145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:59.943696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:59.582715Z","time spent":"360.976339ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T10:21:59.943747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.696373ms","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-05-05T10:21:59.943773Z","caller":"traceutil/trace.go:171","msg":"trace[852708554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1351; }","duration":"523.759545ms","start":"2026-05-05T10:21:59.420007Z","end":"2026-05-05T10:21:59.943767Z","steps":["trace[852708554] 'agreement among raft nodes before linearized reading' (duration: 523.700243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:59.943795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:59.419984Z","time spent":"523.806275ms","remote":"127.0.0.1:34134","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-05-05T10:21:59.943694Z","caller":"traceutil/trace.go:171","msg":"trace[119376247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1351; }","duration":"724.761886ms","start":"2026-05-05T10:21:59.218922Z","end":"2026-05-05T10:21:59.943684Z","steps":["trace[119376247] 'agreement among raft nodes before linearized reading' (duration: 724.619782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:59.943835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.447979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-05T10:21:59.943889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.949526ms","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-05-05T10:21:59.943906Z","caller":"traceutil/trace.go:171","msg":"trace[1589456983] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1351; }","duration":"473.965897ms","start":"2026-05-05T10:21:59.469935Z","end":"2026-05-05T10:21:59.943901Z","steps":["trace[1589456983] 'agreement among raft nodes before linearized reading' (duration: 473.919306ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:59.943643Z","caller":"traceutil/trace.go:171","msg":"trace[1467139112] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"138.451585ms","start":"2026-05-05T10:21:59.805155Z","end":"2026-05-05T10:21:59.943607Z","steps":["trace[1467139112] 'process raft request' (duration: 138.302191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:59.943899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:59.218891Z","time spent":"724.973641ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T10:21:59.943907Z","caller":"traceutil/trace.go:171","msg":"trace[254369900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1351; }","duration":"481.558461ms","start":"2026-05-05T10:21:59.462332Z","end":"2026-05-05T10:21:59.943891Z","steps":["trace[254369900] 'agreement among raft nodes before linearized reading' (duration: 481.378507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:59.943929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:59.469873Z","time spent":"474.052659ms","remote":"127.0.0.1:34134","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-05-05T10:21:59.943957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:59.462312Z","time spent":"481.636714ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T10:22:24.321855Z","caller":"traceutil/trace.go:171","msg":"trace[704708141] linearizableReadLoop","detail":"{readStateIndex:1520; appliedIndex:1519; }","duration":"252.173733ms","start":"2026-05-05T10:22:24.069662Z","end":"2026-05-05T10:22:24.321836Z","steps":["trace[704708141] 'read index received' (duration: 235.903679ms)","trace[704708141] 'applied index is now lower than readState.Index' (duration: 16.269454ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:22:24.321895Z","caller":"traceutil/trace.go:171","msg":"trace[340185943] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"266.561311ms","start":"2026-05-05T10:22:24.055321Z","end":"2026-05-05T10:22:24.321883Z","steps":["trace[340185943] 'process raft request' (duration: 250.210425ms)","trace[340185943] 'compare' (duration: 16.118111ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:22:24.322014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.013348ms","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-05-05T10:22:24.322031Z","caller":"traceutil/trace.go:171","msg":"trace[1972885569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1462; }","duration":"247.066279ms","start":"2026-05-05T10:22:24.07496Z","end":"2026-05-05T10:22:24.322026Z","steps":["trace[1972885569] 'agreement among raft nodes before linearized reading' (duration: 246.975847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:22:24.322098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.179929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-05T10:22:24.322155Z","caller":"traceutil/trace.go:171","msg":"trace[908138810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1462; }","duration":"244.267502ms","start":"2026-05-05T10:22:24.077874Z","end":"2026-05-05T10:22:24.322142Z","steps":["trace[908138810] 'agreement among raft nodes before linearized reading' (duration: 244.092467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:22:24.322139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.204535ms","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-05-05T10:22:24.322215Z","caller":"traceutil/trace.go:171","msg":"trace[121949290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1462; }","duration":"243.311949ms","start":"2026-05-05T10:22:24.078877Z","end":"2026-05-05T10:22:24.322188Z","steps":["trace[121949290] 'agreement among raft nodes before linearized reading' (duration: 243.081152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:22:24.322287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.631395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T10:22:24.322386Z","caller":"traceutil/trace.go:171","msg":"trace[2016934725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1462; }","duration":"252.762918ms","start":"2026-05-05T10:22:24.069618Z","end":"2026-05-05T10:22:24.322381Z","steps":["trace[2016934725] 'agreement among raft nodes before linearized reading' (duration: 252.308107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:22:25.704253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.013874ms","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:525"} {"level":"info","ts":"2026-05-05T10:22:25.704324Z","caller":"traceutil/trace.go:171","msg":"trace[381240543] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1468; }","duration":"676.146237ms","start":"2026-05-05T10:22:25.028158Z","end":"2026-05-05T10:22:25.704304Z","steps":["trace[381240543] 'range keys from in-memory index tree' (duration: 675.85541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:22:25.704361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:22:25.028143Z","time spent":"676.207889ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T10:22:25.704772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189780937s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T10:22:25.704858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125345215s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:22:25.704905Z","caller":"traceutil/trace.go:171","msg":"trace[896391048] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1468; }","duration":"1.190102564s","start":"2026-05-05T10:22:24.51478Z","end":"2026-05-05T10:22:25.704883Z","steps":["trace[896391048] 'range keys from in-memory index tree' (duration: 1.189758946s)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:22:25.704959Z","caller":"traceutil/trace.go:171","msg":"trace[394323509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1468; }","duration":"1.125435237s","start":"2026-05-05T10:22:24.579484Z","end":"2026-05-05T10:22:25.704919Z","steps":["trace[394323509] 'range keys from in-memory index tree' (duration: 1.125288824s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:22:25.705009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:22:24.579453Z","time spent":"1.125545329s","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T10:22:26.670927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.84946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:22:26.671019Z","caller":"traceutil/trace.go:171","msg":"trace[16835065] linearizableReadLoop","detail":"{readStateIndex:1531; appliedIndex:1530; }","duration":"337.141751ms","start":"2026-05-05T10:22:26.333862Z","end":"2026-05-05T10:22:26.671004Z","steps":["trace[16835065] 'read index received' (duration: 1.110197ms)","trace[16835065] 'applied index is now lower than readState.Index' (duration: 336.030014ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:22:26.671062Z","caller":"traceutil/trace.go:171","msg":"trace[901888964] transaction","detail":"{read_only:false; response_revision:1473; number_of_response:1; }","duration":"338.006642ms","start":"2026-05-05T10:22:26.33304Z","end":"2026-05-05T10:22:26.671047Z","steps":[],"step_count":0} {"level":"warn","ts":"2026-05-05T10:22:26.671166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:22:26.333024Z","time spent":"338.085364ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:22:26.672553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.346889ms","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:530"} {"level":"info","ts":"2026-05-05T10:22:26.67261Z","caller":"traceutil/trace.go:171","msg":"trace[1818354937] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1473; }","duration":"340.421621ms","start":"2026-05-05T10:22:26.332176Z","end":"2026-05-05T10:22:26.672597Z","steps":["trace[1818354937] 'agreement among raft nodes before linearized reading' (duration: 338.891774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:22:26.672632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:22:26.332168Z","time spent":"340.459692ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-05T10:22:26.672747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.903813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T10:22:26.672764Z","caller":"traceutil/trace.go:171","msg":"trace[1194779992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1473; }","duration":"340.941113ms","start":"2026-05-05T10:22:26.331819Z","end":"2026-05-05T10:22:26.67276Z","steps":["trace[1194779992] 'agreement among raft nodes before linearized reading' (duration: 339.261072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:22:26.672777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:22:26.331809Z","time spent":"340.965413ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T10:22:26.67285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.535217ms","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-05-05T10:22:26.672868Z","caller":"traceutil/trace.go:171","msg":"trace[264686243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1473; }","duration":"270.581719ms","start":"2026-05-05T10:22:26.402282Z","end":"2026-05-05T10:22:26.672864Z","steps":["trace[264686243] 'agreement among raft nodes before linearized reading' (duration: 268.817625ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:22:28.018758Z","caller":"traceutil/trace.go:171","msg":"trace[202877658] linearizableReadLoop","detail":"{readStateIndex:1536; appliedIndex:1535; }","duration":"155.397676ms","start":"2026-05-05T10:22:27.86334Z","end":"2026-05-05T10:22:28.018738Z","steps":["trace[202877658] 'read index received' (duration: 155.227621ms)","trace[202877658] 'applied index is now lower than readState.Index' (duration: 169.195µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:22:28.018878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.528489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T10:22:28.018872Z","caller":"traceutil/trace.go:171","msg":"trace[489407228] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"322.741452ms","start":"2026-05-05T10:22:27.696101Z","end":"2026-05-05T10:22:28.018842Z","steps":["trace[489407228] 'process raft request' (duration: 322.513007ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:22:28.018918Z","caller":"traceutil/trace.go:171","msg":"trace[110971254] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1478; }","duration":"155.617521ms","start":"2026-05-05T10:22:27.863291Z","end":"2026-05-05T10:22:28.018909Z","steps":["trace[110971254] 'agreement among raft nodes before linearized reading' (duration: 155.527538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:22:28.019021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:22:27.696084Z","time spent":"322.859974ms","remote":"127.0.0.1:34134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:22:29.074772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.812754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-05T10:22:29.074848Z","caller":"traceutil/trace.go:171","msg":"trace[437239339] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1485; }","duration":"152.935607ms","start":"2026-05-05T10:22:28.921892Z","end":"2026-05-05T10:22:29.074828Z","steps":["trace[437239339] 'range keys from in-memory index tree' (duration: 152.64258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:22:29.074762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.102755ms","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:535"} {"level":"info","ts":"2026-05-05T10:22:29.075208Z","caller":"traceutil/trace.go:171","msg":"trace[1590514771] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1485; }","duration":"260.574546ms","start":"2026-05-05T10:22:28.814587Z","end":"2026-05-05T10:22:29.075162Z","steps":["trace[1590514771] 'range keys from in-memory index tree' (duration: 259.899139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:25:11.269883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.12576ms","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:525"} {"level":"info","ts":"2026-05-05T10:25:11.269976Z","caller":"traceutil/trace.go:171","msg":"trace[38644283] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2254; }","duration":"208.268864ms","start":"2026-05-05T10:25:11.061686Z","end":"2026-05-05T10:25:11.269955Z","steps":["trace[38644283] 'range keys from in-memory index tree' (duration: 207.988127ms)"],"step_count":1}