{"level":"warn","ts":"2026-04-20T09:42:05.911256Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T09:42:05.911341Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.157: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.157:2380","--initial-cluster=instance=https://199.19.213.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.157: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-04-20T09:42:05.911405Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T09:42:05.91142Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.157:2380"]} {"level":"info","ts":"2026-04-20T09:42:05.91144Z","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-04-20T09:42:05.91231Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"]} {"level":"info","ts":"2026-04-20T09:42:05.912511Z","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.157:2380"],"listen-peer-urls":["https://199.19.213.157:2380"],"advertise-client-urls":["https://199.19.213.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.157: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-04-20T09:42:05.918445Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.629066ms"} {"level":"info","ts":"2026-04-20T09:42:05.927641Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"10058a425931b8de","cluster-id":"2ff97ff1d0272754"} {"level":"info","ts":"2026-04-20T09:42:05.927718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T09:42:05.927739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became follower at term 0"} {"level":"info","ts":"2026-04-20T09:42:05.927751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 10058a425931b8de [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T09:42:05.927759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became follower at term 1"} {"level":"info","ts":"2026-04-20T09:42:05.927799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=(1154480897059305694)"} {"level":"warn","ts":"2026-04-20T09:42:05.935316Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T09:42:05.936945Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T09:42:05.938367Z","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-04-20T09:42:05.94133Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"10058a425931b8de","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T09:42:05.94561Z","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-04-20T09:42:05.945706Z","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-04-20T09:42:05.945718Z","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-04-20T09:42:05.945944Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"10058a425931b8de","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T09:42:05.946952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=(1154480897059305694)"} {"level":"info","ts":"2026-04-20T09:42:05.948923Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2ff97ff1d0272754","local-member-id":"10058a425931b8de","added-peer-id":"10058a425931b8de","added-peer-peer-urls":["https://199.19.213.157:2380"]} {"level":"info","ts":"2026-04-20T09:42:05.950379Z","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-04-20T09:42:05.950535Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.157:2380"} {"level":"info","ts":"2026-04-20T09:42:05.950564Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.157:2380"} {"level":"info","ts":"2026-04-20T09:42:05.951096Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"10058a425931b8de","initial-advertise-peer-urls":["https://199.19.213.157:2380"],"listen-peer-urls":["https://199.19.213.157:2380"],"advertise-client-urls":["https://199.19.213.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T09:42:05.951167Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T09:42:06.228982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T09:42:06.229041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T09:42:06.229118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de received MsgPreVoteResp from 10058a425931b8de at term 1"} {"level":"info","ts":"2026-04-20T09:42:06.229216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became candidate at term 2"} {"level":"info","ts":"2026-04-20T09:42:06.229238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de received MsgVoteResp from 10058a425931b8de at term 2"} {"level":"info","ts":"2026-04-20T09:42:06.229299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became leader at term 2"} {"level":"info","ts":"2026-04-20T09:42:06.229321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 10058a425931b8de elected leader 10058a425931b8de at term 2"} {"level":"info","ts":"2026-04-20T09:42:06.230185Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T09:42:06.230824Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"10058a425931b8de","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.157:2379]}","request-path":"/0/members/10058a425931b8de/attributes","cluster-id":"2ff97ff1d0272754","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T09:42:06.231005Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T09:42:06.23107Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T09:42:06.231377Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2ff97ff1d0272754","local-member-id":"10058a425931b8de","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T09:42:06.2316Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T09:42:06.231717Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T09:42:06.231863Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T09:42:06.232022Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T09:42:06.234897Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T09:42:06.235745Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.157:2379"} {"level":"info","ts":"2026-04-20T09:42:11.006263Z","caller":"traceutil/trace.go:171","msg":"trace[381105451] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"330.857935ms","start":"2026-04-20T09:42:10.675378Z","end":"2026-04-20T09:42:11.006236Z","steps":["trace[381105451] 'process raft request' (duration: 330.718681ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:42:11.006331Z","caller":"traceutil/trace.go:171","msg":"trace[254601682] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:271; }","duration":"298.790434ms","start":"2026-04-20T09:42:10.707493Z","end":"2026-04-20T09:42:11.006283Z","steps":["trace[254601682] 'read index received' (duration: 298.766263ms)","trace[254601682] 'applied index is now lower than readState.Index' (duration: 21.471µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:42:11.006553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.042441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-20T09:42:11.006629Z","caller":"traceutil/trace.go:171","msg":"trace[329688117] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:265; }","duration":"299.187964ms","start":"2026-04-20T09:42:10.70743Z","end":"2026-04-20T09:42:11.006618Z","steps":["trace[329688117] 'agreement among raft nodes before linearized reading' (duration: 299.02787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:11.006932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:10.675359Z","time spent":"331.040519ms","remote":"127.0.0.1:55754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4003,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:42:11.511859Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258102432033124,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T09:42:11.927057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.178643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T09:42:11.92718Z","caller":"traceutil/trace.go:171","msg":"trace[657458444] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"1.183943715s","start":"2026-04-20T09:42:10.743218Z","end":"2026-04-20T09:42:11.927161Z","steps":["trace[657458444] 'process raft request' (duration: 908.446314ms)","trace[657458444] 'compare' (duration: 275.05115ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:42:11.927234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:10.743184Z","time spent":"1.184028766s","remote":"127.0.0.1:55762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T09:42:12.728455Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.076527452s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-20T09:42:13.223045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.233706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T09:42:13.22331Z","caller":"traceutil/trace.go:171","msg":"trace[1956264359] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:271; }","duration":"2.212296434s","start":"2026-04-20T09:42:11.010997Z","end":"2026-04-20T09:42:13.223293Z","steps":["trace[1956264359] 'read index received' (duration: 640.666175ms)","trace[1956264359] 'applied index is now lower than readState.Index' (duration: 1.571629409s)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:42:13.223435Z","caller":"traceutil/trace.go:171","msg":"trace[1059684620] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"2.213412591s","start":"2026-04-20T09:42:11.010011Z","end":"2026-04-20T09:42:13.223424Z","steps":["trace[1059684620] 'process raft request' (duration: 1.718730045s)","trace[1059684620] 'compare' (duration: 493.700752ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:42:13.22353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:11.009982Z","time spent":"2.213518044s","remote":"127.0.0.1:55782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":203,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T09:42:13.223668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.212657043s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-cmp3fd\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:42:13.223724Z","caller":"traceutil/trace.go:171","msg":"trace[243956696] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-cmp3fd; range_end:; response_count:0; response_revision:268; }","duration":"2.212754285s","start":"2026-04-20T09:42:11.010957Z","end":"2026-04-20T09:42:13.223712Z","steps":["trace[243956696] 'agreement among raft nodes before linearized reading' (duration: 2.212631762s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:13.223925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:11.010937Z","time spent":"2.212983711s","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-cmp3fd\" "} {"level":"warn","ts":"2026-04-20T09:42:13.223719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.177660719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:42:13.223985Z","caller":"traceutil/trace.go:171","msg":"trace[649925488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:268; }","duration":"2.177956276s","start":"2026-04-20T09:42:11.04601Z","end":"2026-04-20T09:42:13.223966Z","steps":["trace[649925488] 'agreement among raft nodes before linearized reading' (duration: 2.177621317s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:13.224031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:11.045998Z","time spent":"2.178024957s","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T09:42:13.224094Z","caller":"traceutil/trace.go:171","msg":"trace[421568010] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"1.644477308s","start":"2026-04-20T09:42:11.57961Z","end":"2026-04-20T09:42:13.224087Z","steps":["trace[421568010] 'process raft request' (duration: 1.643607747s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:13.224146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:11.579511Z","time spent":"1.644601962s","remote":"127.0.0.1:55762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T09:42:13.224376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.14777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T09:42:13.224429Z","caller":"traceutil/trace.go:171","msg":"trace[1123697866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:268; }","duration":"680.231853ms","start":"2026-04-20T09:42:12.544187Z","end":"2026-04-20T09:42:13.224418Z","steps":["trace[1123697866] 'agreement among raft nodes before linearized reading' (duration: 680.056807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:13.224435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.993836ms","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-04-20T09:42:13.22446Z","caller":"traceutil/trace.go:171","msg":"trace[858076618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:268; }","duration":"708.067719ms","start":"2026-04-20T09:42:12.516387Z","end":"2026-04-20T09:42:13.224455Z","steps":["trace[858076618] 'agreement among raft nodes before linearized reading' (duration: 708.013137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:13.224485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:12.516351Z","time spent":"708.130399ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T09:42:13.224526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066105382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T09:42:13.224461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:12.544096Z","time spent":"680.358466ms","remote":"127.0.0.1:55822","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-04-20T09:42:13.224617Z","caller":"traceutil/trace.go:171","msg":"trace[742292635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:268; }","duration":"1.066311757s","start":"2026-04-20T09:42:12.158266Z","end":"2026-04-20T09:42:13.224577Z","steps":["trace[742292635] 'agreement among raft nodes before linearized reading' (duration: 1.065989298s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:13.224684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:12.158245Z","time spent":"1.0664288s","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T09:42:13.705094Z","caller":"traceutil/trace.go:171","msg":"trace[976914479] linearizableReadLoop","detail":"{readStateIndex:300; appliedIndex:299; }","duration":"137.72529ms","start":"2026-04-20T09:42:13.567349Z","end":"2026-04-20T09:42:13.705075Z","steps":["trace[976914479] 'read index received' (duration: 137.605617ms)","trace[976914479] 'applied index is now lower than readState.Index' (duration: 118.693µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:42:13.705202Z","caller":"traceutil/trace.go:171","msg":"trace[353995421] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"175.077053ms","start":"2026-04-20T09:42:13.530096Z","end":"2026-04-20T09:42:13.705173Z","steps":["trace[353995421] 'process raft request' (duration: 174.862097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:13.705231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.867743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:42:13.705339Z","caller":"traceutil/trace.go:171","msg":"trace[2064425737] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:294; }","duration":"138.020887ms","start":"2026-04-20T09:42:13.567301Z","end":"2026-04-20T09:42:13.705322Z","steps":["trace[2064425737] 'agreement among raft nodes before linearized reading' (duration: 137.866254ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:42:38.874445Z","caller":"traceutil/trace.go:171","msg":"trace[837610629] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"153.173441ms","start":"2026-04-20T09:42:38.721243Z","end":"2026-04-20T09:42:38.874417Z","steps":["trace[837610629] 'process raft request' (duration: 152.990407ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:42:45.01114Z","caller":"traceutil/trace.go:171","msg":"trace[959555917] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"692.781545ms","start":"2026-04-20T09:42:44.318334Z","end":"2026-04-20T09:42:45.011115Z","steps":["trace[959555917] 'process raft request' (duration: 692.655712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:45.011281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:44.318314Z","time spent":"692.910529ms","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T09:42:45.586185Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258102432033661,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T09:42:45.754568Z","caller":"traceutil/trace.go:171","msg":"trace[1111551423] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.429772257s","start":"2026-04-20T09:42:44.324764Z","end":"2026-04-20T09:42:45.754536Z","steps":["trace[1111551423] 'process raft request' (duration: 1.429407228s)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:42:45.75506Z","caller":"traceutil/trace.go:171","msg":"trace[1439801583] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"1.419414889s","start":"2026-04-20T09:42:44.335624Z","end":"2026-04-20T09:42:45.755039Z","steps":["trace[1439801583] 'process raft request' (duration: 1.418820364s)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:42:45.755069Z","caller":"traceutil/trace.go:171","msg":"trace[1157621606] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.430231379s","start":"2026-04-20T09:42:44.324816Z","end":"2026-04-20T09:42:45.755047Z","steps":["trace[1157621606] 'process raft request' (duration: 1.429537172s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:45.755162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:44.324811Z","time spent":"1.430302261s","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T09:42:45.755179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:44.335606Z","time spent":"1.419520481s","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T09:42:45.755326Z","caller":"traceutil/trace.go:171","msg":"trace[1278444004] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.418751151s","start":"2026-04-20T09:42:44.336533Z","end":"2026-04-20T09:42:45.755284Z","steps":["trace[1278444004] 'process raft request' (duration: 1.418013933s)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:42:45.755395Z","caller":"traceutil/trace.go:171","msg":"trace[1589417539] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.417576082s","start":"2026-04-20T09:42:44.33768Z","end":"2026-04-20T09:42:45.755256Z","steps":["trace[1589417539] 'process raft request' (duration: 1.417015228s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:45.755468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:44.336519Z","time spent":"1.418882065s","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T09:42:45.755486Z","caller":"traceutil/trace.go:171","msg":"trace[743171175] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.417430298s","start":"2026-04-20T09:42:44.337912Z","end":"2026-04-20T09:42:45.755342Z","steps":["trace[743171175] 'process raft request' (duration: 1.416885325s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:45.75562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:44.337904Z","time spent":"1.417631493s","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T09:42:45.75558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:44.324747Z","time spent":"1.430063385s","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T09:42:45.755674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:44.337671Z","time spent":"1.417858539s","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T09:42:45.755791Z","caller":"traceutil/trace.go:171","msg":"trace[1008336320] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.415782127s","start":"2026-04-20T09:42:44.339991Z","end":"2026-04-20T09:42:45.755773Z","steps":["trace[1008336320] 'process raft request' (duration: 1.414952786s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:45.75589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:44.339983Z","time spent":"1.415851788s","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T09:42:45.756024Z","caller":"traceutil/trace.go:171","msg":"trace[1761840779] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.406149197s","start":"2026-04-20T09:42:44.349859Z","end":"2026-04-20T09:42:45.756008Z","steps":["trace[1761840779] 'process raft request' (duration: 1.405922581s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:45.756122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:44.34985Z","time spent":"1.406241189s","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T09:42:45.756609Z","caller":"traceutil/trace.go:171","msg":"trace[249370769] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.402051664s","start":"2026-04-20T09:42:44.354535Z","end":"2026-04-20T09:42:45.756587Z","steps":["trace[249370769] 'process raft request' (duration: 1.40147549s)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:42:45.756633Z","caller":"traceutil/trace.go:171","msg":"trace[1634117742] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.355634764s","start":"2026-04-20T09:42:44.400955Z","end":"2026-04-20T09:42:45.75659Z","steps":["trace[1634117742] 'process raft request' (duration: 1.355241974s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:45.756713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:44.354517Z","time spent":"1.402154987s","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T09:42:45.75676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:44.400935Z","time spent":"1.355755166s","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T09:42:45.79388Z","caller":"traceutil/trace.go:171","msg":"trace[1019754863] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.279634837s","start":"2026-04-20T09:42:44.514226Z","end":"2026-04-20T09:42:45.793861Z","steps":["trace[1019754863] 'process raft request' (duration: 1.279277598s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:45.79402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:44.514172Z","time spent":"1.27976169s","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T09:42:45.80161Z","caller":"traceutil/trace.go:171","msg":"trace[657487485] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.280022666s","start":"2026-04-20T09:42:44.521558Z","end":"2026-04-20T09:42:45.80158Z","steps":["trace[657487485] 'process raft request' (duration: 1.272296543s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:45.801808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:44.521526Z","time spent":"1.28018464s","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:42:45.802082Z","caller":"traceutil/trace.go:171","msg":"trace[1911323014] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:543; }","duration":"716.072389ms","start":"2026-04-20T09:42:45.085966Z","end":"2026-04-20T09:42:45.802039Z","steps":["trace[1911323014] 'read index received' (duration: 668.248804ms)","trace[1911323014] 'applied index is now lower than readState.Index' (duration: 47.822025ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:42:45.802184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.208502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:42:45.804866Z","caller":"traceutil/trace.go:171","msg":"trace[1524428666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"718.929211ms","start":"2026-04-20T09:42:45.085923Z","end":"2026-04-20T09:42:45.804853Z","steps":["trace[1524428666] 'agreement among raft nodes before linearized reading' (duration: 716.186602ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:42:45.803272Z","caller":"traceutil/trace.go:171","msg":"trace[1671955057] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"785.964834ms","start":"2026-04-20T09:42:45.017287Z","end":"2026-04-20T09:42:45.803252Z","steps":["trace[1671955057] 'process raft request' (duration: 784.247332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:45.804995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.023545ms","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-04-20T09:42:45.803375Z","caller":"traceutil/trace.go:171","msg":"trace[1931343944] transaction","detail":"{read_only:false; number_of_response:1; response_revision:538; }","duration":"783.556025ms","start":"2026-04-20T09:42:45.019803Z","end":"2026-04-20T09:42:45.803359Z","steps":["trace[1931343944] 'process raft request' (duration: 781.885634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:45.805142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:45.085901Z","time spent":"718.998462ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T09:42:45.80341Z","caller":"traceutil/trace.go:171","msg":"trace[1932965033] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"785.141984ms","start":"2026-04-20T09:42:45.01826Z","end":"2026-04-20T09:42:45.803402Z","steps":["trace[1932965033] 'process raft request' (duration: 783.394731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:45.805181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:45.01727Z","time spent":"787.693337ms","remote":"127.0.0.1:56094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T09:42:45.805227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:45.018253Z","time spent":"786.933429ms","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:42:45.804644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.148896ms","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-04-20T09:42:45.805062Z","caller":"traceutil/trace.go:171","msg":"trace[320257750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:546; }","duration":"354.126297ms","start":"2026-04-20T09:42:45.450923Z","end":"2026-04-20T09:42:45.805049Z","steps":["trace[320257750] 'agreement among raft nodes before linearized reading' (duration: 353.801629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:45.805335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:45.019795Z","time spent":"785.268988ms","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6936,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:42:45.805458Z","caller":"traceutil/trace.go:171","msg":"trace[1857109613] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:546; }","duration":"430.903355ms","start":"2026-04-20T09:42:45.374439Z","end":"2026-04-20T09:42:45.805342Z","steps":["trace[1857109613] 'agreement among raft nodes before linearized reading' (duration: 430.063034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:45.804682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.382544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T09:42:45.805587Z","caller":"traceutil/trace.go:171","msg":"trace[1628615052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:546; }","duration":"355.302686ms","start":"2026-04-20T09:42:45.450266Z","end":"2026-04-20T09:42:45.805569Z","steps":["trace[1628615052] 'agreement among raft nodes before linearized reading' (duration: 354.329652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:45.805633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:45.450252Z","time spent":"355.369767ms","remote":"127.0.0.1:55822","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-04-20T09:42:45.80478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.196348ms","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-04-20T09:42:45.805696Z","caller":"traceutil/trace.go:171","msg":"trace[545070152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:546; }","duration":"361.125871ms","start":"2026-04-20T09:42:45.44454Z","end":"2026-04-20T09:42:45.805666Z","steps":["trace[545070152] 'agreement among raft nodes before linearized reading' (duration: 360.138977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:45.805702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:45.450913Z","time spent":"354.66815ms","remote":"127.0.0.1:55822","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":"warn","ts":"2026-04-20T09:42:45.805702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:45.374416Z","time spent":"431.184402ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T09:42:45.805742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:45.444522Z","time spent":"361.206354ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T09:42:52.312703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.293845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T09:42:52.312859Z","caller":"traceutil/trace.go:171","msg":"trace[2144509288] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"225.568356ms","start":"2026-04-20T09:42:52.08724Z","end":"2026-04-20T09:42:52.312808Z","steps":["trace[2144509288] 'read index received' (duration: 125.009774ms)","trace[2144509288] 'applied index is now lower than readState.Index' (duration: 100.557032ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:42:52.312848Z","caller":"traceutil/trace.go:171","msg":"trace[1794774913] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"399.027568ms","start":"2026-04-20T09:42:51.913791Z","end":"2026-04-20T09:42:52.312818Z","steps":["trace[1794774913] 'process raft request' (duration: 298.551308ms)","trace[1794774913] 'compare' (duration: 100.026159ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:42:52.312943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.704289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T09:42:52.31296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:51.913761Z","time spent":"399.152571ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:42:52.312976Z","caller":"traceutil/trace.go:171","msg":"trace[233117568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"225.77546ms","start":"2026-04-20T09:42:52.08719Z","end":"2026-04-20T09:42:52.312965Z","steps":["trace[233117568] 'agreement among raft nodes before linearized reading' (duration: 225.709709ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:42:52.520288Z","caller":"traceutil/trace.go:171","msg":"trace[379076280] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"258.288192ms","start":"2026-04-20T09:42:52.261969Z","end":"2026-04-20T09:42:52.520257Z","steps":["trace[379076280] 'process raft request' (duration: 241.368659ms)","trace[379076280] 'compare' (duration: 16.586915ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:42:53.127004Z","caller":"traceutil/trace.go:171","msg":"trace[1639021916] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"549.091646ms","start":"2026-04-20T09:42:52.577877Z","end":"2026-04-20T09:42:53.126969Z","steps":["trace[1639021916] 'process raft request' (duration: 548.787478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:53.127307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:52.577839Z","time spent":"549.25237ms","remote":"127.0.0.1:55802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T09:42:54.897019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.74012682s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T09:42:54.897439Z","caller":"traceutil/trace.go:171","msg":"trace[1202126473] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.750735275s","start":"2026-04-20T09:42:53.14666Z","end":"2026-04-20T09:42:54.897395Z","steps":["trace[1202126473] 'compare' (duration: 1.739849923s)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:42:54.897562Z","caller":"traceutil/trace.go:171","msg":"trace[181863676] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"1.040353509s","start":"2026-04-20T09:42:53.857169Z","end":"2026-04-20T09:42:54.897522Z","steps":["trace[181863676] 'read index received' (duration: 46.481µs)","trace[181863676] 'applied index is now lower than readState.Index' (duration: 1.040304578s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:42:54.897599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:53.146634Z","time spent":"1.750894749s","remote":"127.0.0.1:55762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T09:42:55.176209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.081162ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T09:42:55.176354Z","caller":"traceutil/trace.go:171","msg":"trace[125861798] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"278.726612ms","start":"2026-04-20T09:42:54.897608Z","end":"2026-04-20T09:42:55.176335Z","steps":["trace[125861798] 'read index received' (duration: 49.171µs)","trace[125861798] 'applied index is now lower than readState.Index' (duration: 278.64394ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:42:55.176546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.802093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T09:42:55.176554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262230062s","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-04-20T09:42:55.176635Z","caller":"traceutil/trace.go:171","msg":"trace[487801557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"858.941108ms","start":"2026-04-20T09:42:54.317672Z","end":"2026-04-20T09:42:55.176613Z","steps":["trace[487801557] 'agreement among raft nodes before linearized reading' (duration: 858.707072ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:42:55.176664Z","caller":"traceutil/trace.go:171","msg":"trace[1655605632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"1.262385696s","start":"2026-04-20T09:42:53.91425Z","end":"2026-04-20T09:42:55.176636Z","steps":["trace[1655605632] 'agreement among raft nodes before linearized reading' (duration: 1.26213973s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:55.176682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:54.317645Z","time spent":"859.027989ms","remote":"127.0.0.1:55822","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-04-20T09:42:55.176705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:53.914228Z","time spent":"1.262467618s","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T09:42:55.176603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.319364379s","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-04-20T09:42:55.176993Z","caller":"traceutil/trace.go:171","msg":"trace[1939829309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"1.31981455s","start":"2026-04-20T09:42:53.857119Z","end":"2026-04-20T09:42:55.176934Z","steps":["trace[1939829309] 'agreement among raft nodes before linearized reading' (duration: 1.040523994s)","trace[1939829309] 'range keys from in-memory index tree' (duration: 278.750283ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:42:55.177203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:53.8571Z","time spent":"1.320085597s","remote":"127.0.0.1:55822","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":"warn","ts":"2026-04-20T09:42:55.176583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305269017s","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-04-20T09:42:55.180756Z","caller":"traceutil/trace.go:171","msg":"trace[500126487] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"1.309486214s","start":"2026-04-20T09:42:53.871254Z","end":"2026-04-20T09:42:55.18074Z","steps":["trace[500126487] 'agreement among raft nodes before linearized reading' (duration: 1.305156725s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:55.180805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:53.871229Z","time spent":"1.309560375s","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T09:42:55.18698Z","caller":"traceutil/trace.go:171","msg":"trace[1139186567] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"286.2197ms","start":"2026-04-20T09:42:54.900743Z","end":"2026-04-20T09:42:55.186963Z","steps":["trace[1139186567] 'process raft request' (duration: 286.090816ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:42:55.198389Z","caller":"traceutil/trace.go:171","msg":"trace[592221428] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"295.854701ms","start":"2026-04-20T09:42:54.902515Z","end":"2026-04-20T09:42:55.19837Z","steps":["trace[592221428] 'process raft request' (duration: 295.636165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:55.198384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.652044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:42:55.198482Z","caller":"traceutil/trace.go:171","msg":"trace[405152868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"112.796708ms","start":"2026-04-20T09:42:55.08567Z","end":"2026-04-20T09:42:55.198467Z","steps":["trace[405152868] 'agreement among raft nodes before linearized reading' (duration: 112.651324ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:42:55.198541Z","caller":"traceutil/trace.go:171","msg":"trace[1008033470] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"296.100407ms","start":"2026-04-20T09:42:54.902409Z","end":"2026-04-20T09:42:55.198509Z","steps":["trace[1008033470] 'process raft request' (duration: 295.629945ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:42:55.19865Z","caller":"traceutil/trace.go:171","msg":"trace[408863183] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"294.981618ms","start":"2026-04-20T09:42:54.90366Z","end":"2026-04-20T09:42:55.198642Z","steps":["trace[408863183] 'process raft request' (duration: 294.546418ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:42:57.273799Z","caller":"traceutil/trace.go:171","msg":"trace[323080371] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:699; }","duration":"188.072197ms","start":"2026-04-20T09:42:57.085702Z","end":"2026-04-20T09:42:57.273774Z","steps":["trace[323080371] 'read index received' (duration: 187.929594ms)","trace[323080371] 'applied index is now lower than readState.Index' (duration: 140.943µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:42:57.273901Z","caller":"traceutil/trace.go:171","msg":"trace[1143788150] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"340.296511ms","start":"2026-04-20T09:42:56.933594Z","end":"2026-04-20T09:42:57.27389Z","steps":["trace[1143788150] 'process raft request' (duration: 340.033015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:42:57.274007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.292533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T09:42:57.274047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:42:56.933566Z","time spent":"340.362433ms","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T09:42:57.274118Z","caller":"traceutil/trace.go:171","msg":"trace[1493372107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"188.457087ms","start":"2026-04-20T09:42:57.085642Z","end":"2026-04-20T09:42:57.274099Z","steps":["trace[1493372107] 'agreement among raft nodes before linearized reading' (duration: 188.264903ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:42:57.280216Z","caller":"traceutil/trace.go:171","msg":"trace[1847597166] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"143.537376ms","start":"2026-04-20T09:42:57.136663Z","end":"2026-04-20T09:42:57.2802Z","steps":["trace[1847597166] 'process raft request' (duration: 143.138336ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:43:15.687069Z","caller":"traceutil/trace.go:171","msg":"trace[1177112942] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"313.466849ms","start":"2026-04-20T09:43:15.373572Z","end":"2026-04-20T09:43:15.687039Z","steps":["trace[1177112942] 'process raft request' (duration: 313.324176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:15.687228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:15.373553Z","time spent":"313.599953ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:43:16.33675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.389575ms","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-04-20T09:43:16.336876Z","caller":"traceutil/trace.go:171","msg":"trace[1295726175] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:814; }","duration":"447.529069ms","start":"2026-04-20T09:43:15.889329Z","end":"2026-04-20T09:43:16.336858Z","steps":["trace[1295726175] 'range keys from in-memory index tree' (duration: 447.372115ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:43:16.338819Z","caller":"traceutil/trace.go:171","msg":"trace[1170835458] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"964.331072ms","start":"2026-04-20T09:43:15.374469Z","end":"2026-04-20T09:43:16.3388Z","steps":["trace[1170835458] 'process raft request' (duration: 963.907072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:16.338939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:15.374423Z","time spent":"964.445255ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:43:16.339313Z","caller":"traceutil/trace.go:171","msg":"trace[507905772] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"964.67942ms","start":"2026-04-20T09:43:15.374615Z","end":"2026-04-20T09:43:16.339294Z","steps":["trace[507905772] 'process raft request' (duration: 964.491066ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:43:16.339355Z","caller":"traceutil/trace.go:171","msg":"trace[1787873515] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"964.535607ms","start":"2026-04-20T09:43:15.374799Z","end":"2026-04-20T09:43:16.339335Z","steps":["trace[1787873515] 'process raft request' (duration: 964.451045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:16.339505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:15.374601Z","time spent":"964.819146ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:43:16.339567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:15.37479Z","time spent":"964.668802ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:43:16.374291Z","caller":"traceutil/trace.go:171","msg":"trace[904396857] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:844; }","duration":"460.295829ms","start":"2026-04-20T09:43:15.913973Z","end":"2026-04-20T09:43:16.374268Z","steps":["trace[904396857] 'read index received' (duration: 424.418275ms)","trace[904396857] 'applied index is now lower than readState.Index' (duration: 35.876534ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:43:16.37477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.755628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:43:16.374726Z","caller":"traceutil/trace.go:171","msg":"trace[512710131] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"465.528102ms","start":"2026-04-20T09:43:15.90913Z","end":"2026-04-20T09:43:16.374658Z","steps":["trace[512710131] 'process raft request' (duration: 465.0238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:16.374795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.634399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:43:16.374877Z","caller":"traceutil/trace.go:171","msg":"trace[1924477690] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:818; }","duration":"460.765251ms","start":"2026-04-20T09:43:15.914097Z","end":"2026-04-20T09:43:16.374862Z","steps":["trace[1924477690] 'agreement among raft nodes before linearized reading' (duration: 460.601187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:16.374923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:15.914084Z","time spent":"460.827272ms","remote":"127.0.0.1:43112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T09:43:16.375031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.513381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T09:43:16.375063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.731643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:43:16.375076Z","caller":"traceutil/trace.go:171","msg":"trace[1506114773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:818; }","duration":"288.619703ms","start":"2026-04-20T09:43:16.086446Z","end":"2026-04-20T09:43:16.375065Z","steps":["trace[1506114773] 'agreement among raft nodes before linearized reading' (duration: 288.510071ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:43:16.375095Z","caller":"traceutil/trace.go:171","msg":"trace[329389068] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:818; }","duration":"293.781074ms","start":"2026-04-20T09:43:16.081305Z","end":"2026-04-20T09:43:16.375086Z","steps":["trace[329389068] 'agreement among raft nodes before linearized reading' (duration: 293.735723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:16.374898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.616367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:43:16.375244Z","caller":"traceutil/trace.go:171","msg":"trace[16761813] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:818; }","duration":"233.971726ms","start":"2026-04-20T09:43:16.141262Z","end":"2026-04-20T09:43:16.375233Z","steps":["trace[16761813] 'agreement among raft nodes before linearized reading' (duration: 233.595297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:16.37523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.24478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:43:16.375337Z","caller":"traceutil/trace.go:171","msg":"trace[155033386] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:818; }","duration":"295.337782ms","start":"2026-04-20T09:43:16.079973Z","end":"2026-04-20T09:43:16.375311Z","steps":["trace[155033386] 'agreement among raft nodes before linearized reading' (duration: 295.2277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:16.375488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.378116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T09:43:16.37553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.163408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:43:16.375595Z","caller":"traceutil/trace.go:171","msg":"trace[333748353] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:818; }","duration":"234.479789ms","start":"2026-04-20T09:43:16.141059Z","end":"2026-04-20T09:43:16.375539Z","steps":["trace[333748353] 'agreement among raft nodes before linearized reading' (duration: 234.333115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:16.375656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.768522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:43:16.375697Z","caller":"traceutil/trace.go:171","msg":"trace[35535537] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:818; }","duration":"314.825144ms","start":"2026-04-20T09:43:16.060861Z","end":"2026-04-20T09:43:16.375687Z","steps":["trace[35535537] 'agreement among raft nodes before linearized reading' (duration: 314.776913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:16.375734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.150771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T09:43:16.375738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:16.060845Z","time spent":"314.883465ms","remote":"127.0.0.1:43126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"info","ts":"2026-04-20T09:43:16.375761Z","caller":"traceutil/trace.go:171","msg":"trace[202062700] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:818; }","duration":"459.192201ms","start":"2026-04-20T09:43:15.91656Z","end":"2026-04-20T09:43:16.375753Z","steps":["trace[202062700] 'agreement among raft nodes before linearized reading' (duration: 459.159701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:16.375797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:15.916552Z","time spent":"459.237013ms","remote":"127.0.0.1:43112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"info","ts":"2026-04-20T09:43:16.374829Z","caller":"traceutil/trace.go:171","msg":"trace[1812366269] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:818; }","duration":"294.85097ms","start":"2026-04-20T09:43:16.079966Z","end":"2026-04-20T09:43:16.374817Z","steps":["trace[1812366269] 'agreement among raft nodes before linearized reading' (duration: 294.744417ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:43:16.375603Z","caller":"traceutil/trace.go:171","msg":"trace[788942653] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:818; }","duration":"314.202408ms","start":"2026-04-20T09:43:16.061347Z","end":"2026-04-20T09:43:16.375549Z","steps":["trace[788942653] 'agreement among raft nodes before linearized reading' (duration: 314.115166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:16.375613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.758222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:43:16.376245Z","caller":"traceutil/trace.go:171","msg":"trace[1422574152] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:818; }","duration":"315.407409ms","start":"2026-04-20T09:43:16.060828Z","end":"2026-04-20T09:43:16.376235Z","steps":["trace[1422574152] 'agreement among raft nodes before linearized reading' (duration: 314.754342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:16.376241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.904481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:43:16.376481Z","caller":"traceutil/trace.go:171","msg":"trace[1371558155] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:818; }","duration":"337.808324ms","start":"2026-04-20T09:43:16.038661Z","end":"2026-04-20T09:43:16.376469Z","steps":["trace[1371558155] 'agreement among raft nodes before linearized reading' (duration: 336.8577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:16.376511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:16.038654Z","time spent":"337.849225ms","remote":"127.0.0.1:43114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T09:43:16.375705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.091585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T09:43:16.376286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:16.06082Z","time spent":"315.45544ms","remote":"127.0.0.1:43126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-04-20T09:43:16.376651Z","caller":"traceutil/trace.go:171","msg":"trace[90569125] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:818; }","duration":"338.05202ms","start":"2026-04-20T09:43:16.038567Z","end":"2026-04-20T09:43:16.376619Z","steps":["trace[90569125] 'agreement among raft nodes before linearized reading' (duration: 337.069324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:16.376435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.895951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:43:16.376781Z","caller":"traceutil/trace.go:171","msg":"trace[1065582050] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:818; }","duration":"337.24092ms","start":"2026-04-20T09:43:16.03953Z","end":"2026-04-20T09:43:16.376771Z","steps":["trace[1065582050] 'agreement among raft nodes before linearized reading' (duration: 336.880101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:16.376813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:16.039457Z","time spent":"337.347933ms","remote":"127.0.0.1:43114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 "} {"level":"warn","ts":"2026-04-20T09:43:16.376818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:16.038545Z","time spent":"338.201293ms","remote":"127.0.0.1:43114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 "} {"level":"warn","ts":"2026-04-20T09:43:16.376136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:16.061343Z","time spent":"314.774173ms","remote":"127.0.0.1:43126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"warn","ts":"2026-04-20T09:43:16.377161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.273639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:43:16.3772Z","caller":"traceutil/trace.go:171","msg":"trace[1637330476] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:818; }","duration":"235.335581ms","start":"2026-04-20T09:43:16.141854Z","end":"2026-04-20T09:43:16.37719Z","steps":["trace[1637330476] 'agreement among raft nodes before linearized reading' (duration: 235.27573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:16.37764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:15.909102Z","time spent":"465.947082ms","remote":"127.0.0.1:55802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:43:16.378132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.146796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:43:16.378225Z","caller":"traceutil/trace.go:171","msg":"trace[1588307933] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:818; }","duration":"464.217598ms","start":"2026-04-20T09:43:15.913952Z","end":"2026-04-20T09:43:16.378169Z","steps":["trace[1588307933] 'agreement among raft nodes before linearized reading' (duration: 464.132526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:16.378396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:15.913939Z","time spent":"464.385572ms","remote":"127.0.0.1:43112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 "} {"level":"info","ts":"2026-04-20T09:43:45.207839Z","caller":"traceutil/trace.go:171","msg":"trace[1565774741] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"104.74031ms","start":"2026-04-20T09:43:45.103062Z","end":"2026-04-20T09:43:45.207802Z","steps":["trace[1565774741] 'process raft request' (duration: 104.526364ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:43:50.667675Z","caller":"traceutil/trace.go:171","msg":"trace[1223253916] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"161.45554ms","start":"2026-04-20T09:43:50.506176Z","end":"2026-04-20T09:43:50.667631Z","steps":["trace[1223253916] 'process raft request' (duration: 161.276624ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:43:50.688741Z","caller":"traceutil/trace.go:171","msg":"trace[1570149026] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1064; }","duration":"178.290424ms","start":"2026-04-20T09:43:50.510432Z","end":"2026-04-20T09:43:50.688723Z","steps":["trace[1570149026] 'read index received' (duration: 157.019007ms)","trace[1570149026] 'applied index is now lower than readState.Index' (duration: 21.270717ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:43:50.688829Z","caller":"traceutil/trace.go:171","msg":"trace[1431629565] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"157.765285ms","start":"2026-04-20T09:43:50.531055Z","end":"2026-04-20T09:43:50.688821Z","steps":["trace[1431629565] 'process raft request' (duration: 157.634772ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:43:50.688821Z","caller":"traceutil/trace.go:171","msg":"trace[444141566] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"180.590281ms","start":"2026-04-20T09:43:50.508194Z","end":"2026-04-20T09:43:50.688784Z","steps":["trace[444141566] 'process raft request' (duration: 180.385736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:50.689091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.651492ms","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-04-20T09:43:50.689136Z","caller":"traceutil/trace.go:171","msg":"trace[1016630405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1029; }","duration":"178.723805ms","start":"2026-04-20T09:43:50.510392Z","end":"2026-04-20T09:43:50.689116Z","steps":["trace[1016630405] 'agreement among raft nodes before linearized reading' (duration: 178.587861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:51.838814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.475915ms","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":"warn","ts":"2026-04-20T09:43:51.838811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.663178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:43:51.838896Z","caller":"traceutil/trace.go:171","msg":"trace[384924358] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1030; }","duration":"637.61738ms","start":"2026-04-20T09:43:51.201261Z","end":"2026-04-20T09:43:51.838879Z","steps":["trace[384924358] 'count revisions from in-memory index tree' (duration: 637.346323ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:43:51.838925Z","caller":"traceutil/trace.go:171","msg":"trace[717065851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1030; }","duration":"752.820922ms","start":"2026-04-20T09:43:51.086077Z","end":"2026-04-20T09:43:51.838898Z","steps":["trace[717065851] 'range keys from in-memory index tree' (duration: 752.549635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:51.838962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:51.201236Z","time spent":"637.705141ms","remote":"127.0.0.1:55670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-20T09:43:51.838981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:51.086061Z","time spent":"752.909335ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T09:43:51.838956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.445007ms","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-04-20T09:43:51.839038Z","caller":"traceutil/trace.go:171","msg":"trace[1195743397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1030; }","duration":"822.57489ms","start":"2026-04-20T09:43:51.016449Z","end":"2026-04-20T09:43:51.839024Z","steps":["trace[1195743397] 'range keys from in-memory index tree' (duration: 822.303283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:51.839067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:51.016429Z","time spent":"822.630952ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T09:43:51.867041Z","caller":"traceutil/trace.go:171","msg":"trace[1597559014] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"638.930322ms","start":"2026-04-20T09:43:51.228089Z","end":"2026-04-20T09:43:51.867019Z","steps":["trace[1597559014] 'process raft request' (duration: 638.735057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:51.867182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:51.228068Z","time spent":"639.029045ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:43:52.367927Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258102432034720,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T09:43:52.86814Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258102432034720,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T09:43:53.098545Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.217122682s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T09:43:53.099255Z","caller":"traceutil/trace.go:171","msg":"trace[248200014] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1069; }","duration":"1.232255744s","start":"2026-04-20T09:43:51.866979Z","end":"2026-04-20T09:43:53.099234Z","steps":["trace[248200014] 'read index received' (duration: 1.231649918s)","trace[248200014] 'applied index is now lower than readState.Index' (duration: 604.756µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:43:53.099271Z","caller":"traceutil/trace.go:171","msg":"trace[1856659618] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"1.256562657s","start":"2026-04-20T09:43:51.842679Z","end":"2026-04-20T09:43:53.099241Z","steps":["trace[1856659618] 'process raft request' (duration: 1.255989152s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:53.099426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:51.84266Z","time spent":"1.25667116s","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:43:53.099467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244418461s","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-04-20T09:43:53.099507Z","caller":"traceutil/trace.go:171","msg":"trace[580448426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1032; }","duration":"1.244494484s","start":"2026-04-20T09:43:51.855001Z","end":"2026-04-20T09:43:53.099495Z","steps":["trace[580448426] 'agreement among raft nodes before linearized reading' (duration: 1.24433511s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:53.099508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.784561ms","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":"warn","ts":"2026-04-20T09:43:53.099554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:51.854985Z","time spent":"1.244560055s","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T09:43:53.099561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.384541ms","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-04-20T09:43:53.099626Z","caller":"traceutil/trace.go:171","msg":"trace[1095801693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1032; }","duration":"327.470113ms","start":"2026-04-20T09:43:52.772139Z","end":"2026-04-20T09:43:53.099609Z","steps":["trace[1095801693] 'agreement among raft nodes before linearized reading' (duration: 327.289578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:53.099678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:52.772127Z","time spent":"327.539655ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T09:43:53.099682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.814573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T09:43:53.099732Z","caller":"traceutil/trace.go:171","msg":"trace[2017052487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1032; }","duration":"406.885094ms","start":"2026-04-20T09:43:52.692834Z","end":"2026-04-20T09:43:53.09972Z","steps":["trace[2017052487] 'agreement among raft nodes before linearized reading' (duration: 406.761891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:53.09975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.838302ms","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-04-20T09:43:53.099784Z","caller":"traceutil/trace.go:171","msg":"trace[1046686122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1032; }","duration":"425.905183ms","start":"2026-04-20T09:43:52.673867Z","end":"2026-04-20T09:43:53.099772Z","steps":["trace[1046686122] 'agreement among raft nodes before linearized reading' (duration: 425.820721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:53.099783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:52.692823Z","time spent":"406.948377ms","remote":"127.0.0.1:55822","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-04-20T09:43:53.099569Z","caller":"traceutil/trace.go:171","msg":"trace[947858567] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1032; }","duration":"554.915794ms","start":"2026-04-20T09:43:52.544636Z","end":"2026-04-20T09:43:53.099552Z","steps":["trace[947858567] 'agreement among raft nodes before linearized reading' (duration: 554.785091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:53.099822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:52.673847Z","time spent":"425.965675ms","remote":"127.0.0.1:55822","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":"warn","ts":"2026-04-20T09:43:53.099851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:52.544608Z","time spent":"555.230192ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-04-20T09:43:53.587327Z","caller":"traceutil/trace.go:171","msg":"trace[326494309] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"483.641858ms","start":"2026-04-20T09:43:53.103666Z","end":"2026-04-20T09:43:53.587307Z","steps":["trace[326494309] 'process raft request' (duration: 480.898249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:53.587451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:53.103646Z","time spent":"483.732531ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:43:53.587513Z","caller":"traceutil/trace.go:171","msg":"trace[914994766] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"483.602648ms","start":"2026-04-20T09:43:53.103889Z","end":"2026-04-20T09:43:53.587491Z","steps":["trace[914994766] 'process raft request' (duration: 483.357982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:53.587647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:53.103879Z","time spent":"483.69527ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:43:53.587763Z","caller":"traceutil/trace.go:171","msg":"trace[1771373761] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"483.799412ms","start":"2026-04-20T09:43:53.103956Z","end":"2026-04-20T09:43:53.587755Z","steps":["trace[1771373761] 'process raft request' (duration: 483.477274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:53.587804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:53.103946Z","time spent":"483.837383ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:43:53.587919Z","caller":"traceutil/trace.go:171","msg":"trace[1573324081] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1070; }","duration":"483.636218ms","start":"2026-04-20T09:43:53.104273Z","end":"2026-04-20T09:43:53.587909Z","steps":["trace[1573324081] 'read index received' (duration: 480.303254ms)","trace[1573324081] 'applied index is now lower than readState.Index' (duration: 3.332114ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:43:53.588053Z","caller":"traceutil/trace.go:171","msg":"trace[1704227787] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"483.647359ms","start":"2026-04-20T09:43:53.104397Z","end":"2026-04-20T09:43:53.588045Z","steps":["trace[1704227787] 'process raft request' (duration: 483.329721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:53.58805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.044344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T09:43:53.588095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:53.104388Z","time spent":"483.684319ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:43:53.5881Z","caller":"traceutil/trace.go:171","msg":"trace[281811577] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1036; }","duration":"462.133097ms","start":"2026-04-20T09:43:53.12595Z","end":"2026-04-20T09:43:53.588084Z","steps":["trace[281811577] 'agreement among raft nodes before linearized reading' (duration: 462.064265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:53.588136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:53.125888Z","time spent":"462.239159ms","remote":"127.0.0.1:56076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-20T09:43:53.588207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.931996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:43:53.588229Z","caller":"traceutil/trace.go:171","msg":"trace[1625267497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1036; }","duration":"483.968887ms","start":"2026-04-20T09:43:53.104253Z","end":"2026-04-20T09:43:53.588222Z","steps":["trace[1625267497] 'agreement among raft nodes before linearized reading' (duration: 483.932006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:43:53.588247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:43:53.104246Z","time spent":"483.997168ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T09:44:16.246084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258102432034916,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T09:44:16.583046Z","caller":"traceutil/trace.go:171","msg":"trace[420557365] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"864.094421ms","start":"2026-04-20T09:44:15.718926Z","end":"2026-04-20T09:44:16.58302Z","steps":["trace[420557365] 'process raft request' (duration: 863.913276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:16.583195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:15.718891Z","time spent":"864.231134ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:44:16.601418Z","caller":"traceutil/trace.go:171","msg":"trace[329799825] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1147; }","duration":"855.7734ms","start":"2026-04-20T09:44:15.745612Z","end":"2026-04-20T09:44:16.601385Z","steps":["trace[329799825] 'read index received' (duration: 837.231602ms)","trace[329799825] 'applied index is now lower than readState.Index' (duration: 18.540578ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:44:16.601642Z","caller":"traceutil/trace.go:171","msg":"trace[618434334] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"651.289616ms","start":"2026-04-20T09:44:15.950304Z","end":"2026-04-20T09:44:16.601594Z","steps":["trace[618434334] 'process raft request' (duration: 650.905317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:16.601773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.125229ms","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-04-20T09:44:16.601815Z","caller":"traceutil/trace.go:171","msg":"trace[310849927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1106; }","duration":"856.247461ms","start":"2026-04-20T09:44:15.745555Z","end":"2026-04-20T09:44:16.601802Z","steps":["trace[310849927] 'agreement among raft nodes before linearized reading' (duration: 856.028676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:16.601892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:15.950278Z","time spent":"651.479151ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:44:16.601948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:15.745528Z","time spent":"856.408576ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T09:44:16.601918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.252302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:44:16.602022Z","caller":"traceutil/trace.go:171","msg":"trace[652899518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1106; }","duration":"418.437686ms","start":"2026-04-20T09:44:16.18356Z","end":"2026-04-20T09:44:16.601997Z","steps":["trace[652899518] 'agreement among raft nodes before linearized reading' (duration: 418.228911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:16.602136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:16.183529Z","time spent":"418.58772ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T09:44:16.602241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.248644ms","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-04-20T09:44:16.602328Z","caller":"traceutil/trace.go:171","msg":"trace[859740376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1106; }","duration":"843.421349ms","start":"2026-04-20T09:44:15.758887Z","end":"2026-04-20T09:44:16.602308Z","steps":["trace[859740376] 'agreement among raft nodes before linearized reading' (duration: 842.944737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:16.602378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:15.758857Z","time spent":"843.51075ms","remote":"127.0.0.1:55822","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":"warn","ts":"2026-04-20T09:44:16.602659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.709534ms","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-04-20T09:44:16.602708Z","caller":"traceutil/trace.go:171","msg":"trace[843481658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1106; }","duration":"856.805077ms","start":"2026-04-20T09:44:15.745892Z","end":"2026-04-20T09:44:16.602697Z","steps":["trace[843481658] 'agreement among raft nodes before linearized reading' (duration: 855.849912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:16.602743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:15.74587Z","time spent":"856.860299ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T09:44:17.132279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258102432034921,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T09:44:17.16827Z","caller":"traceutil/trace.go:171","msg":"trace[311374046] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"560.659949ms","start":"2026-04-20T09:44:16.607581Z","end":"2026-04-20T09:44:17.168241Z","steps":["trace[311374046] 'process raft request' (duration: 560.248249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:17.168454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:16.607558Z","time spent":"560.811923ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:44:17.176525Z","caller":"traceutil/trace.go:171","msg":"trace[1470391517] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"568.277581ms","start":"2026-04-20T09:44:16.608231Z","end":"2026-04-20T09:44:17.176509Z","steps":["trace[1470391517] 'process raft request' (duration: 568.136887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:17.176611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:16.60822Z","time spent":"568.351433ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:44:17.176752Z","caller":"traceutil/trace.go:171","msg":"trace[1234062389] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1149; }","duration":"544.983922ms","start":"2026-04-20T09:44:16.631741Z","end":"2026-04-20T09:44:17.176725Z","steps":["trace[1234062389] 'read index received' (duration: 536.078968ms)","trace[1234062389] 'applied index is now lower than readState.Index' (duration: 8.902664ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:44:17.176815Z","caller":"traceutil/trace.go:171","msg":"trace[1936146381] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"568.107446ms","start":"2026-04-20T09:44:16.608656Z","end":"2026-04-20T09:44:17.176764Z","steps":["trace[1936146381] 'process raft request' (duration: 567.812929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:17.17695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.195218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T09:44:17.176982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.124327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T09:44:17.177028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:16.608631Z","time spent":"568.28934ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:44:17.177038Z","caller":"traceutil/trace.go:171","msg":"trace[650479526] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1109; }","duration":"545.339363ms","start":"2026-04-20T09:44:16.63168Z","end":"2026-04-20T09:44:17.17702Z","steps":["trace[650479526] 'agreement among raft nodes before linearized reading' (duration: 545.174777ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:44:17.177039Z","caller":"traceutil/trace.go:171","msg":"trace[1494366775] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1109; }","duration":"290.263541ms","start":"2026-04-20T09:44:16.886763Z","end":"2026-04-20T09:44:17.177026Z","steps":["trace[1494366775] 'agreement among raft nodes before linearized reading' (duration: 290.133716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:17.177082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:16.631659Z","time spent":"545.412524ms","remote":"127.0.0.1:55946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-04-20T09:44:18.825331Z","caller":"traceutil/trace.go:171","msg":"trace[310405813] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"232.164173ms","start":"2026-04-20T09:44:18.593135Z","end":"2026-04-20T09:44:18.825299Z","steps":["trace[310405813] 'process raft request' (duration: 232.03042ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:44:19.247942Z","caller":"traceutil/trace.go:171","msg":"trace[753373179] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1155; }","duration":"138.747994ms","start":"2026-04-20T09:44:19.10917Z","end":"2026-04-20T09:44:19.247918Z","steps":["trace[753373179] 'read index received' (duration: 138.495968ms)","trace[753373179] 'applied index is now lower than readState.Index' (duration: 251.146µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:44:19.247966Z","caller":"traceutil/trace.go:171","msg":"trace[308383591] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"639.49235ms","start":"2026-04-20T09:44:18.608445Z","end":"2026-04-20T09:44:19.247937Z","steps":["trace[308383591] 'process raft request' (duration: 639.322086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:19.248052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.869768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T09:44:19.248174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:18.608302Z","time spent":"639.756567ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:44:19.248185Z","caller":"traceutil/trace.go:171","msg":"trace[413377775] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1113; }","duration":"139.046742ms","start":"2026-04-20T09:44:19.109109Z","end":"2026-04-20T09:44:19.248156Z","steps":["trace[413377775] 'agreement among raft nodes before linearized reading' (duration: 138.859488ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:44:21.426152Z","caller":"traceutil/trace.go:171","msg":"trace[1517221371] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1162; }","duration":"339.38462ms","start":"2026-04-20T09:44:21.086743Z","end":"2026-04-20T09:44:21.426128Z","steps":["trace[1517221371] 'read index received' (duration: 339.246067ms)","trace[1517221371] 'applied index is now lower than readState.Index' (duration: 137.313µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:44:21.427153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.369836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:44:21.427298Z","caller":"traceutil/trace.go:171","msg":"trace[2004941716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1119; }","duration":"340.560691ms","start":"2026-04-20T09:44:21.086709Z","end":"2026-04-20T09:44:21.42727Z","steps":["trace[2004941716] 'agreement among raft nodes before linearized reading' (duration: 340.081259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:21.427362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:21.086694Z","time spent":"340.657663ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T09:44:21.426208Z","caller":"traceutil/trace.go:171","msg":"trace[500786733] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"592.081332ms","start":"2026-04-20T09:44:20.834092Z","end":"2026-04-20T09:44:21.426174Z","steps":["trace[500786733] 'process raft request' (duration: 591.881987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:21.434418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:20.834068Z","time spent":"600.224188ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:44:21.511592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.809898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-20T09:44:21.511687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.084299ms","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-04-20T09:44:21.511807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.040098ms","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-04-20T09:44:21.511834Z","caller":"traceutil/trace.go:171","msg":"trace[306713563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1121; }","duration":"224.232663ms","start":"2026-04-20T09:44:21.287547Z","end":"2026-04-20T09:44:21.51178Z","steps":["trace[306713563] 'agreement among raft nodes before linearized reading' (duration: 224.050418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:21.511855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.933927ms","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-04-20T09:44:21.511873Z","caller":"traceutil/trace.go:171","msg":"trace[948534249] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1121; }","duration":"184.162991ms","start":"2026-04-20T09:44:21.327694Z","end":"2026-04-20T09:44:21.511857Z","steps":["trace[948534249] 'agreement among raft nodes before linearized reading' (duration: 183.937015ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:44:21.511902Z","caller":"traceutil/trace.go:171","msg":"trace[1518327466] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1121; }","duration":"236.032431ms","start":"2026-04-20T09:44:21.275859Z","end":"2026-04-20T09:44:21.511891Z","steps":["trace[1518327466] 'agreement among raft nodes before linearized reading' (duration: 235.885607ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:44:21.511618Z","caller":"traceutil/trace.go:171","msg":"trace[1024012574] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"187.751662ms","start":"2026-04-20T09:44:21.323819Z","end":"2026-04-20T09:44:21.51157Z","steps":["trace[1024012574] 'process raft request' (duration: 187.474545ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:44:21.511686Z","caller":"traceutil/trace.go:171","msg":"trace[1139023218] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"255.8112ms","start":"2026-04-20T09:44:21.255838Z","end":"2026-04-20T09:44:21.511649Z","steps":["trace[1139023218] 'process raft request' (duration: 255.268676ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:44:21.511838Z","caller":"traceutil/trace.go:171","msg":"trace[250960266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1121; }","duration":"223.017582ms","start":"2026-04-20T09:44:21.288737Z","end":"2026-04-20T09:44:21.511754Z","steps":["trace[250960266] 'agreement among raft nodes before linearized reading' (duration: 222.726745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:21.691156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.3495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T09:44:21.69168Z","caller":"traceutil/trace.go:171","msg":"trace[989742592] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"174.751583ms","start":"2026-04-20T09:44:21.516518Z","end":"2026-04-20T09:44:21.69127Z","steps":["trace[989742592] 'process raft request' (duration: 52.185617ms)","trace[989742592] 'compare' (duration: 122.171136ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:44:21.711647Z","caller":"traceutil/trace.go:171","msg":"trace[1040759998] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"195.081436ms","start":"2026-04-20T09:44:21.516543Z","end":"2026-04-20T09:44:21.711624Z","steps":["trace[1040759998] 'process raft request' (duration: 194.833459ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:44:21.711712Z","caller":"traceutil/trace.go:171","msg":"trace[1299026923] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"194.655065ms","start":"2026-04-20T09:44:21.517034Z","end":"2026-04-20T09:44:21.711689Z","steps":["trace[1299026923] 'process raft request' (duration: 194.469041ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:44:21.711631Z","caller":"traceutil/trace.go:171","msg":"trace[444566436] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"194.310057ms","start":"2026-04-20T09:44:21.517291Z","end":"2026-04-20T09:44:21.711601Z","steps":["trace[444566436] 'process raft request' (duration: 194.261586ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:44:31.503079Z","caller":"traceutil/trace.go:171","msg":"trace[1103587573] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"335.130222ms","start":"2026-04-20T09:44:31.167924Z","end":"2026-04-20T09:44:31.503054Z","steps":["trace[1103587573] 'process raft request' (duration: 334.950898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:31.503217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:31.167906Z","time spent":"335.247585ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1616,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T09:44:35.705698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.621465368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:44:35.705769Z","caller":"traceutil/trace.go:171","msg":"trace[1528699219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"1.621575081s","start":"2026-04-20T09:44:34.08418Z","end":"2026-04-20T09:44:35.705755Z","steps":["trace[1528699219] 'range keys from in-memory index tree' (duration: 1.621410607s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:35.705799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:34.084166Z","time spent":"1.621626992s","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T09:44:36.51335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.403166ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T09:44:36.51359Z","caller":"traceutil/trace.go:171","msg":"trace[856237735] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1264; }","duration":"985.655662ms","start":"2026-04-20T09:44:35.527917Z","end":"2026-04-20T09:44:36.513573Z","steps":["trace[856237735] 'read index received' (duration: 177.0269ms)","trace[856237735] 'applied index is now lower than readState.Index' (duration: 808.627681ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:44:36.51378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.869157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T09:44:36.513811Z","caller":"traceutil/trace.go:171","msg":"trace[726928138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1216; }","duration":"985.933438ms","start":"2026-04-20T09:44:35.527865Z","end":"2026-04-20T09:44:36.513798Z","steps":["trace[726928138] 'agreement among raft nodes before linearized reading' (duration: 985.813945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:36.513843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:35.527849Z","time spent":"985.98614ms","remote":"127.0.0.1:55822","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-04-20T09:44:36.513905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.210624ms","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-04-20T09:44:36.51399Z","caller":"traceutil/trace.go:171","msg":"trace[1210505932] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1216; }","duration":"624.298667ms","start":"2026-04-20T09:44:35.889678Z","end":"2026-04-20T09:44:36.513977Z","steps":["trace[1210505932] 'range keys from in-memory index tree' (duration: 624.200904ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:44:36.514319Z","caller":"traceutil/trace.go:171","msg":"trace[748042843] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"955.52238ms","start":"2026-04-20T09:44:35.558771Z","end":"2026-04-20T09:44:36.514294Z","steps":["trace[748042843] 'process raft request' (duration: 954.71503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:36.515467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:35.55875Z","time spent":"956.607149ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:44:36.525988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.552304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T09:44:36.526019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.716007ms","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-04-20T09:44:36.526076Z","caller":"traceutil/trace.go:171","msg":"trace[1447344202] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1217; }","duration":"379.683258ms","start":"2026-04-20T09:44:36.14637Z","end":"2026-04-20T09:44:36.526053Z","steps":["trace[1447344202] 'agreement among raft nodes before linearized reading' (duration: 367.959892ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:44:36.526083Z","caller":"traceutil/trace.go:171","msg":"trace[934532983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1217; }","duration":"744.822039ms","start":"2026-04-20T09:44:35.781243Z","end":"2026-04-20T09:44:36.526065Z","steps":["trace[934532983] 'agreement among raft nodes before linearized reading' (duration: 733.089032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:36.526124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:36.146354Z","time spent":"379.7588ms","remote":"127.0.0.1:59644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-20T09:44:36.526135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.397564ms","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-04-20T09:44:36.526181Z","caller":"traceutil/trace.go:171","msg":"trace[338837185] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1217; }","duration":"762.444726ms","start":"2026-04-20T09:44:35.763723Z","end":"2026-04-20T09:44:36.526168Z","steps":["trace[338837185] 'agreement among raft nodes before linearized reading' (duration: 750.623987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:36.526213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:35.763666Z","time spent":"762.540028ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T09:44:36.526554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.169754ms","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-04-20T09:44:36.526578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.492976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:44:36.526587Z","caller":"traceutil/trace.go:171","msg":"trace[49236165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1217; }","duration":"746.232516ms","start":"2026-04-20T09:44:35.780344Z","end":"2026-04-20T09:44:36.526577Z","steps":["trace[49236165] 'agreement among raft nodes before linearized reading' (duration: 733.970156ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:44:36.526612Z","caller":"traceutil/trace.go:171","msg":"trace[287076355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1217; }","duration":"819.568748ms","start":"2026-04-20T09:44:35.707032Z","end":"2026-04-20T09:44:36.526601Z","steps":["trace[287076355] 'agreement among raft nodes before linearized reading' (duration: 807.236426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:36.52662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:35.7803Z","time spent":"746.314058ms","remote":"127.0.0.1:55822","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-04-20T09:44:36.526638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:35.707005Z","time spent":"819.626679ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T09:44:36.526131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:35.78123Z","time spent":"744.889372ms","remote":"127.0.0.1:55822","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":"warn","ts":"2026-04-20T09:44:36.527419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.892113ms","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-04-20T09:44:36.527484Z","caller":"traceutil/trace.go:171","msg":"trace[1954844844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1217; }","duration":"750.959824ms","start":"2026-04-20T09:44:35.776507Z","end":"2026-04-20T09:44:36.527467Z","steps":["trace[1954844844] 'agreement among raft nodes before linearized reading' (duration: 737.831893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:36.527521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:35.776451Z","time spent":"751.060806ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T09:44:49.605737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.31945ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T09:44:49.605884Z","caller":"traceutil/trace.go:171","msg":"trace[1766137017] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"148.640825ms","start":"2026-04-20T09:44:49.45722Z","end":"2026-04-20T09:44:49.60586Z","steps":["trace[1766137017] 'process raft request' (duration: 22.081637ms)","trace[1766137017] 'compare' (duration: 126.159917ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:44:52.361033Z","caller":"traceutil/trace.go:171","msg":"trace[778368942] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"221.511414ms","start":"2026-04-20T09:44:52.139503Z","end":"2026-04-20T09:44:52.361014Z","steps":["trace[778368942] 'process raft request' (duration: 221.399521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:53.175898Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258102432035313,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T09:44:53.485064Z","caller":"traceutil/trace.go:171","msg":"trace[806172785] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"809.37261ms","start":"2026-04-20T09:44:52.675615Z","end":"2026-04-20T09:44:53.484988Z","steps":["trace[806172785] 'read index received' (duration: 809.045972ms)","trace[806172785] 'applied index is now lower than readState.Index' (duration: 322.678µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:44:53.485323Z","caller":"traceutil/trace.go:171","msg":"trace[804745307] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"845.805859ms","start":"2026-04-20T09:44:52.639479Z","end":"2026-04-20T09:44:53.485285Z","steps":["trace[804745307] 'process raft request' (duration: 845.251086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:53.485435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.79871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T09:44:53.48548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:52.639455Z","time spent":"845.943693ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:44:53.485488Z","caller":"traceutil/trace.go:171","msg":"trace[672025891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1291; }","duration":"809.889422ms","start":"2026-04-20T09:44:52.675581Z","end":"2026-04-20T09:44:53.48547Z","steps":["trace[672025891] 'agreement among raft nodes before linearized reading' (duration: 809.689497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:53.485533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:52.675563Z","time spent":"809.959764ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T09:44:53.485677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.919375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:44:53.485715Z","caller":"traceutil/trace.go:171","msg":"trace[1246199109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"399.002247ms","start":"2026-04-20T09:44:53.086701Z","end":"2026-04-20T09:44:53.485703Z","steps":["trace[1246199109] 'agreement among raft nodes before linearized reading' (duration: 398.943375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:53.485712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.307438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T09:44:53.48574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.742052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-20T09:44:53.485749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:53.086663Z","time spent":"399.076969ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T09:44:53.485763Z","caller":"traceutil/trace.go:171","msg":"trace[33570469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1291; }","duration":"805.385149ms","start":"2026-04-20T09:44:52.680362Z","end":"2026-04-20T09:44:53.485747Z","steps":["trace[33570469] 'agreement among raft nodes before linearized reading' (duration: 805.228634ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:44:53.485776Z","caller":"traceutil/trace.go:171","msg":"trace[1461525739] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1291; }","duration":"802.810954ms","start":"2026-04-20T09:44:52.682954Z","end":"2026-04-20T09:44:53.485765Z","steps":["trace[1461525739] 'agreement among raft nodes before linearized reading' (duration: 802.668471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:53.485804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:52.680347Z","time spent":"805.445801ms","remote":"127.0.0.1:55822","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-04-20T09:44:53.485808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:52.682926Z","time spent":"802.875106ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T09:44:53.485891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.021759ms","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-04-20T09:44:53.485917Z","caller":"traceutil/trace.go:171","msg":"trace[982503782] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1291; }","duration":"803.062541ms","start":"2026-04-20T09:44:52.682848Z","end":"2026-04-20T09:44:53.485911Z","steps":["trace[982503782] 'agreement among raft nodes before linearized reading' (duration: 802.972359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:53.485934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:52.68284Z","time spent":"803.090731ms","remote":"127.0.0.1:55822","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-04-20T09:44:53.485963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.873471ms","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-04-20T09:44:53.486009Z","caller":"traceutil/trace.go:171","msg":"trace[1185293885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1291; }","duration":"803.943932ms","start":"2026-04-20T09:44:52.682051Z","end":"2026-04-20T09:44:53.485995Z","steps":["trace[1185293885] 'agreement among raft nodes before linearized reading' (duration: 803.836239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:44:53.486057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:44:52.682029Z","time spent":"804.017664ms","remote":"127.0.0.1:55822","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":"warn","ts":"2026-04-20T09:44:54.67448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.520539ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-04-20T09:45:08.135128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258102432035497,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T09:45:08.392963Z","caller":"traceutil/trace.go:171","msg":"trace[518102800] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1438; }","duration":"758.417392ms","start":"2026-04-20T09:45:07.634519Z","end":"2026-04-20T09:45:08.392936Z","steps":["trace[518102800] 'read index received' (duration: 758.043033ms)","trace[518102800] 'applied index is now lower than readState.Index' (duration: 372.719µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:45:08.393095Z","caller":"traceutil/trace.go:171","msg":"trace[1558932034] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"791.509258ms","start":"2026-04-20T09:45:07.601574Z","end":"2026-04-20T09:45:08.393083Z","steps":["trace[1558932034] 'process raft request' (duration: 791.17819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:45:08.393209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:07.601547Z","time spent":"791.58435ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:45:08.393296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.282113ms","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-04-20T09:45:08.393368Z","caller":"traceutil/trace.go:171","msg":"trace[203088800] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1384; }","duration":"738.400537ms","start":"2026-04-20T09:45:07.654949Z","end":"2026-04-20T09:45:08.393349Z","steps":["trace[203088800] 'agreement among raft nodes before linearized reading' (duration: 738.207602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:45:08.393431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:07.6549Z","time spent":"738.515889ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T09:45:08.393595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.03807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T09:45:08.393627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.986631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:99226"} {"level":"info","ts":"2026-04-20T09:45:08.393636Z","caller":"traceutil/trace.go:171","msg":"trace[1240184829] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1384; }","duration":"691.112912ms","start":"2026-04-20T09:45:07.70251Z","end":"2026-04-20T09:45:08.393623Z","steps":["trace[1240184829] 'agreement among raft nodes before linearized reading' (duration: 691.036321ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:45:08.393669Z","caller":"traceutil/trace.go:171","msg":"trace[1063606307] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1384; }","duration":"642.059613ms","start":"2026-04-20T09:45:07.751598Z","end":"2026-04-20T09:45:08.393658Z","steps":["trace[1063606307] 'agreement among raft nodes before linearized reading' (duration: 641.893189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:45:08.393679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:07.702495Z","time spent":"691.171474ms","remote":"127.0.0.1:55746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-20T09:45:08.393702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:07.751581Z","time spent":"642.114624ms","remote":"127.0.0.1:55652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":99248,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-20T09:45:08.393828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.612555ms","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-04-20T09:45:08.393896Z","caller":"traceutil/trace.go:171","msg":"trace[18298164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1384; }","duration":"735.721448ms","start":"2026-04-20T09:45:07.65816Z","end":"2026-04-20T09:45:08.393881Z","steps":["trace[18298164] 'agreement among raft nodes before linearized reading' (duration: 735.547854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:45:08.393946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:07.658129Z","time spent":"735.80508ms","remote":"127.0.0.1:55822","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-04-20T09:45:08.394028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.888682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T09:45:08.394084Z","caller":"traceutil/trace.go:171","msg":"trace[1751046895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1384; }","duration":"735.972145ms","start":"2026-04-20T09:45:07.658099Z","end":"2026-04-20T09:45:08.394071Z","steps":["trace[1751046895] 'agreement among raft nodes before linearized reading' (duration: 735.837241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:45:08.394131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:07.658026Z","time spent":"736.093698ms","remote":"127.0.0.1:55822","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-04-20T09:45:08.394165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.348356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T09:45:08.394214Z","caller":"traceutil/trace.go:171","msg":"trace[209639410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1384; }","duration":"306.437788ms","start":"2026-04-20T09:45:08.087765Z","end":"2026-04-20T09:45:08.394202Z","steps":["trace[209639410] 'agreement among raft nodes before linearized reading' (duration: 306.362136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:45:08.39425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.054983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-20T09:45:08.394251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:08.087746Z","time spent":"306.49865ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T09:45:08.393663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.497122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-20T09:45:08.394344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.819577ms","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-04-20T09:45:08.394369Z","caller":"traceutil/trace.go:171","msg":"trace[883724955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1384; }","duration":"736.226561ms","start":"2026-04-20T09:45:07.658128Z","end":"2026-04-20T09:45:08.394355Z","steps":["trace[883724955] 'agreement among raft nodes before linearized reading' (duration: 735.424271ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:45:08.394353Z","caller":"traceutil/trace.go:171","msg":"trace[62219246] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1384; }","duration":"187.215537ms","start":"2026-04-20T09:45:08.207111Z","end":"2026-04-20T09:45:08.394326Z","steps":["trace[62219246] 'agreement among raft nodes before linearized reading' (duration: 186.93435ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:45:08.394402Z","caller":"traceutil/trace.go:171","msg":"trace[1072114153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1384; }","duration":"759.92149ms","start":"2026-04-20T09:45:07.634467Z","end":"2026-04-20T09:45:08.394389Z","steps":["trace[1072114153] 'agreement among raft nodes before linearized reading' (duration: 759.784326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:45:08.394424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:07.658115Z","time spent":"736.295082ms","remote":"127.0.0.1:55822","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":"warn","ts":"2026-04-20T09:45:08.39445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:07.634442Z","time spent":"759.997252ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T09:45:08.394587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.674938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-20T09:45:08.394636Z","caller":"traceutil/trace.go:171","msg":"trace[1983161792] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1384; }","duration":"636.74903ms","start":"2026-04-20T09:45:07.757874Z","end":"2026-04-20T09:45:08.394623Z","steps":["trace[1983161792] 'agreement among raft nodes before linearized reading' (duration: 636.666337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:45:08.394681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:07.757862Z","time spent":"636.808812ms","remote":"127.0.0.1:55656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-04-20T09:45:08.900206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258102432035506,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T09:45:09.475009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.055722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T09:45:09.475172Z","caller":"traceutil/trace.go:171","msg":"trace[1177801496] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"1.075423249s","start":"2026-04-20T09:45:08.39968Z","end":"2026-04-20T09:45:09.475103Z","steps":["trace[1177801496] 'process raft request' (duration: 938.186994ms)","trace[1177801496] 'compare' (duration: 136.579189ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:45:09.475721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:08.399669Z","time spent":"1.075998333s","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:45:09.547388Z","caller":"traceutil/trace.go:171","msg":"trace[2059758454] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"1.147597992s","start":"2026-04-20T09:45:08.39975Z","end":"2026-04-20T09:45:09.547348Z","steps":["trace[2059758454] 'process raft request' (duration: 1.147350896s)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:45:09.547422Z","caller":"traceutil/trace.go:171","msg":"trace[1635414174] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"1.147232122s","start":"2026-04-20T09:45:08.400165Z","end":"2026-04-20T09:45:09.547397Z","steps":["trace[1635414174] 'process raft request' (duration: 1.14716119s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:45:09.547556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:08.39974Z","time spent":"1.147727885s","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:45:09.547583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:08.400156Z","time spent":"1.147331744s","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:45:09.547605Z","caller":"traceutil/trace.go:171","msg":"trace[1332703234] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"1.147788237s","start":"2026-04-20T09:45:08.399791Z","end":"2026-04-20T09:45:09.547579Z","steps":["trace[1332703234] 'process raft request' (duration: 1.147431758s)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:45:09.547552Z","caller":"traceutil/trace.go:171","msg":"trace[1991069341] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1439; }","duration":"1.147543611s","start":"2026-04-20T09:45:08.399993Z","end":"2026-04-20T09:45:09.547536Z","steps":["trace[1991069341] 'read index received' (duration: 937.888136ms)","trace[1991069341] 'applied index is now lower than readState.Index' (duration: 209.653394ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:45:09.547741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:08.399771Z","time spent":"1.147902949s","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:45:09.547843Z","caller":"traceutil/trace.go:171","msg":"trace[1907978797] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"556.35071ms","start":"2026-04-20T09:45:08.991454Z","end":"2026-04-20T09:45:09.547804Z","steps":["trace[1907978797] 'process raft request' (duration: 555.901159ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:45:09.547658Z","caller":"traceutil/trace.go:171","msg":"trace[1137207713] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"1.14752897s","start":"2026-04-20T09:45:08.400022Z","end":"2026-04-20T09:45:09.547551Z","steps":["trace[1137207713] 'process raft request' (duration: 1.147272063s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:45:09.547973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147948681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-04-20T09:45:09.548087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.930027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.157\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-20T09:45:09.548096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146485653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:99226"} {"level":"info","ts":"2026-04-20T09:45:09.548125Z","caller":"traceutil/trace.go:171","msg":"trace[916070921] range","detail":"{range_begin:/registry/masterleases/199.19.213.157; range_end:; response_count:1; response_revision:1391; }","duration":"114.013369ms","start":"2026-04-20T09:45:09.434101Z","end":"2026-04-20T09:45:09.548114Z","steps":["trace[916070921] 'agreement among raft nodes before linearized reading' (duration: 113.894146ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:45:09.548131Z","caller":"traceutil/trace.go:171","msg":"trace[1088416169] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1391; }","duration":"1.146526374s","start":"2026-04-20T09:45:08.401594Z","end":"2026-04-20T09:45:09.54812Z","steps":["trace[1088416169] 'agreement among raft nodes before linearized reading' (duration: 1.146397091s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:45:09.54816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:08.401588Z","time spent":"1.146565295s","remote":"127.0.0.1:55652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":99248,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-20T09:45:09.548388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:08.400002Z","time spent":"1.148031503s","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:45:09.547919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:08.991419Z","time spent":"556.463613ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T09:45:09.548577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.903322ms","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-04-20T09:45:09.548107Z","caller":"traceutil/trace.go:171","msg":"trace[43643408] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1391; }","duration":"1.148127855s","start":"2026-04-20T09:45:08.399966Z","end":"2026-04-20T09:45:09.548094Z","steps":["trace[43643408] 'agreement among raft nodes before linearized reading' (duration: 1.147871959s)"],"step_count":1} {"level":"info","ts":"2026-04-20T09:45:09.548815Z","caller":"traceutil/trace.go:171","msg":"trace[2062067442] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1391; }","duration":"728.203191ms","start":"2026-04-20T09:45:08.820593Z","end":"2026-04-20T09:45:09.548796Z","steps":["trace[2062067442] 'agreement among raft nodes before linearized reading' (duration: 727.918003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:45:09.549049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:08.39996Z","time spent":"1.148942867s","remote":"127.0.0.1:55656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-04-20T09:45:09.548691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.605761ms","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-04-20T09:45:09.549227Z","caller":"traceutil/trace.go:171","msg":"trace[218808875] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1391; }","duration":"733.172236ms","start":"2026-04-20T09:45:08.816039Z","end":"2026-04-20T09:45:09.549212Z","steps":["trace[218808875] 'agreement among raft nodes before linearized reading' (duration: 732.582741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:45:09.549261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:08.816001Z","time spent":"733.248378ms","remote":"127.0.0.1:55906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-20T09:45:09.549034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:08.820562Z","time spent":"728.382835ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-20T09:45:09.547441Z","caller":"traceutil/trace.go:171","msg":"trace[1559267165] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"1.147569091s","start":"2026-04-20T09:45:08.399844Z","end":"2026-04-20T09:45:09.547413Z","steps":["trace[1559267165] 'process raft request' (duration: 1.147417148s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:45:09.549808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T09:45:08.399801Z","time spent":"1.149918611s","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T09:45:57.905044Z","caller":"traceutil/trace.go:171","msg":"trace[1581047589] linearizableReadLoop","detail":"{readStateIndex:1675; appliedIndex:1674; }","duration":"108.025774ms","start":"2026-04-20T09:45:57.796993Z","end":"2026-04-20T09:45:57.905018Z","steps":["trace[1581047589] 'read index received' (duration: 107.816819ms)","trace[1581047589] 'applied index is now lower than readState.Index' (duration: 207.885µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:45:57.905102Z","caller":"traceutil/trace.go:171","msg":"trace[496134692] transaction","detail":"{read_only:false; response_revision:1610; number_of_response:1; }","duration":"110.300792ms","start":"2026-04-20T09:45:57.794764Z","end":"2026-04-20T09:45:57.905065Z","steps":["trace[496134692] 'process raft request' (duration: 110.110097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:45:57.905364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.090416ms","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-04-20T09:45:57.905411Z","caller":"traceutil/trace.go:171","msg":"trace[1041477607] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1610; }","duration":"108.187428ms","start":"2026-04-20T09:45:57.797213Z","end":"2026-04-20T09:45:57.905401Z","steps":["trace[1041477607] 'agreement among raft nodes before linearized reading' (duration: 107.949932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:45:57.905493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.436154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T09:45:57.905679Z","caller":"traceutil/trace.go:171","msg":"trace[195582067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1610; }","duration":"108.690281ms","start":"2026-04-20T09:45:57.796965Z","end":"2026-04-20T09:45:57.905655Z","steps":["trace[195582067] 'agreement among raft nodes before linearized reading' (duration: 108.174968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:45:57.905846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.703556ms","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-04-20T09:45:57.905966Z","caller":"traceutil/trace.go:171","msg":"trace[1267110466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1610; }","duration":"107.87621ms","start":"2026-04-20T09:45:57.798071Z","end":"2026-04-20T09:45:57.905947Z","steps":["trace[1267110466] 'agreement among raft nodes before linearized reading' (duration: 107.434729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:48:09.563525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.898858ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-20T09:48:09.563618Z","caller":"traceutil/trace.go:171","msg":"trace[52245581] linearizableReadLoop","detail":"{readStateIndex:2308; appliedIndex:2307; }","duration":"117.596751ms","start":"2026-04-20T09:48:09.446006Z","end":"2026-04-20T09:48:09.563603Z","steps":["trace[52245581] 'read index received' (duration: 9.619682ms)","trace[52245581] 'applied index is now lower than readState.Index' (duration: 107.975859ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:48:09.563823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.803536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T09:48:09.563874Z","caller":"traceutil/trace.go:171","msg":"trace[1402339671] range","detail":"{range_begin:/registry/masterleases/199.19.213.157; range_end:; response_count:1; response_revision:2216; }","duration":"117.893268ms","start":"2026-04-20T09:48:09.445964Z","end":"2026-04-20T09:48:09.563857Z","steps":["trace[1402339671] 'agreement among raft nodes before linearized reading' (duration: 117.741445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T09:48:16.720112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.038597ms","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-04-20T09:48:16.720227Z","caller":"traceutil/trace.go:171","msg":"trace[1190680300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2267; }","duration":"133.147992ms","start":"2026-04-20T09:48:16.587026Z","end":"2026-04-20T09:48:16.720174Z","steps":["trace[1190680300] 'agreement among raft nodes before linearized reading' (duration: 65.75505ms)","trace[1190680300] 'range keys from in-memory index tree' (duration: 67.170946ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T09:48:16.720545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.313331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T09:48:16.720646Z","caller":"traceutil/trace.go:171","msg":"trace[47950465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2267; }","duration":"132.447834ms","start":"2026-04-20T09:48:16.588174Z","end":"2026-04-20T09:48:16.720622Z","steps":["trace[47950465] 'agreement among raft nodes before linearized reading' (duration: 64.530579ms)","trace[47950465] 'range keys from in-memory index tree' (duration: 67.408762ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T09:48:20.07757Z","caller":"traceutil/trace.go:171","msg":"trace[940873921] transaction","detail":"{read_only:false; response_revision:2283; number_of_response:1; }","duration":"142.26909ms","start":"2026-04-20T09:48:19.935269Z","end":"2026-04-20T09:48:20.077538Z","steps":["trace[940873921] 'process raft request' (duration: 55.764779ms)","trace[940873921] 'compare' (duration: 85.940497ms)"],"step_count":2}