{"level":"warn","ts":"2026-03-26T18:26:42.98422Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-26T18:26:42.98435Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.110:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.110:2380","--initial-cluster=instance=https://162.253.55.110:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.110:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.110: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-03-26T18:26:42.984448Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-26T18:26:42.984467Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-03-26T18:26:42.984489Z","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-03-26T18:26:42.985246Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"]} {"level":"info","ts":"2026-03-26T18:26:42.98533Z","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://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.110: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-03-26T18:26:42.990446Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.822609ms"} {"level":"info","ts":"2026-03-26T18:26:42.998077Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f52f30d5714009d5","cluster-id":"650981a7a383c714"} {"level":"info","ts":"2026-03-26T18:26:42.998176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=()"} {"level":"info","ts":"2026-03-26T18:26:42.998214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 0"} {"level":"info","ts":"2026-03-26T18:26:42.998285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f52f30d5714009d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-26T18:26:42.998305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 1"} {"level":"info","ts":"2026-03-26T18:26:42.998369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"warn","ts":"2026-03-26T18:26:43.00637Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-26T18:26:43.007991Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-26T18:26:43.00935Z","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-03-26T18:26:43.013155Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f52f30d5714009d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-26T18:26:43.013389Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f52f30d5714009d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-26T18:26:43.013616Z","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-03-26T18:26:43.013668Z","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-03-26T18:26:43.013681Z","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-03-26T18:26:43.014539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"info","ts":"2026-03-26T18:26:43.014785Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","added-peer-id":"f52f30d5714009d5","added-peer-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-03-26T18:26:43.019057Z","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-03-26T18:26:43.019198Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-03-26T18:26:43.019248Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-03-26T18:26:43.019387Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f52f30d5714009d5","initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-26T18:26:43.019445Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-26T18:26:43.599359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 is starting a new election at term 1"} {"level":"info","ts":"2026-03-26T18:26:43.599439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-26T18:26:43.599507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgPreVoteResp from f52f30d5714009d5 at term 1"} {"level":"info","ts":"2026-03-26T18:26:43.599528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became candidate at term 2"} {"level":"info","ts":"2026-03-26T18:26:43.599543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgVoteResp from f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-03-26T18:26:43.59956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became leader at term 2"} {"level":"info","ts":"2026-03-26T18:26:43.599588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f52f30d5714009d5 elected leader f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-03-26T18:26:43.600692Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T18:26:43.601602Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f52f30d5714009d5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.110:2379]}","request-path":"/0/members/f52f30d5714009d5/attributes","cluster-id":"650981a7a383c714","publish-timeout":"7s"} {"level":"info","ts":"2026-03-26T18:26:43.601817Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-26T18:26:43.601866Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-26T18:26:43.602297Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T18:26:43.602418Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T18:26:43.602459Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T18:26:43.605442Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.110:2379"} {"level":"info","ts":"2026-03-26T18:26:43.605688Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-26T18:26:43.606319Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-26T18:26:43.606376Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-03-26T18:26:46.532149Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708645326102378207,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T18:26:46.942016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.020163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-26T18:26:46.942151Z","caller":"traceutil/trace.go:171","msg":"trace[1050706136] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"910.641822ms","start":"2026-03-26T18:26:46.031492Z","end":"2026-03-26T18:26:46.942134Z","steps":["trace[1050706136] 'read index received' (duration: 654.056449ms)","trace[1050706136] 'applied index is now lower than readState.Index' (duration: 256.583733ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:26:46.942429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.925147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-26T18:26:46.942485Z","caller":"traceutil/trace.go:171","msg":"trace[451193675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"911.034269ms","start":"2026-03-26T18:26:46.031437Z","end":"2026-03-26T18:26:46.942472Z","steps":["trace[451193675] 'agreement among raft nodes before linearized reading' (duration: 910.757045ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:26:46.94251Z","caller":"traceutil/trace.go:171","msg":"trace[185445062] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"948.96889ms","start":"2026-03-26T18:26:45.993502Z","end":"2026-03-26T18:26:46.942471Z","steps":["trace[185445062] 'process raft request' (duration: 691.9584ms)","trace[185445062] 'compare' (duration: 255.856529ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:26:46.942532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:26:46.031393Z","time spent":"911.129931ms","remote":"127.0.0.1:39792","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-03-26T18:26:46.942708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:26:45.993483Z","time spent":"949.138443ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T18:26:47.361712Z","caller":"traceutil/trace.go:171","msg":"trace[760192450] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"329.844945ms","start":"2026-03-26T18:26:47.031811Z","end":"2026-03-26T18:26:47.361656Z","steps":["trace[760192450] 'read index received' (duration: 290.279175ms)","trace[760192450] 'applied index is now lower than readState.Index' (duration: 39.56471ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:26:47.361908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.07166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-26T18:26:47.362001Z","caller":"traceutil/trace.go:171","msg":"trace[1614662775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:75; }","duration":"330.243124ms","start":"2026-03-26T18:26:47.031744Z","end":"2026-03-26T18:26:47.361987Z","steps":["trace[1614662775] 'agreement among raft nodes before linearized reading' (duration: 330.04685ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:26:47.362042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:26:47.031706Z","time spent":"330.326895ms","remote":"127.0.0.1:39792","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-03-26T18:26:47.361993Z","caller":"traceutil/trace.go:171","msg":"trace[458817682] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"413.474592ms","start":"2026-03-26T18:26:46.948385Z","end":"2026-03-26T18:26:47.36186Z","steps":["trace[458817682] 'process raft request' (duration: 373.802299ms)","trace[458817682] 'compare' (duration: 39.365347ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:26:47.362785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:26:46.948374Z","time spent":"414.120314ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":634,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T18:26:48.028451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.791779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-26T18:26:48.028577Z","caller":"traceutil/trace.go:171","msg":"trace[640406882] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"661.424356ms","start":"2026-03-26T18:26:47.367132Z","end":"2026-03-26T18:26:48.028557Z","steps":["trace[640406882] 'process raft request' (duration: 498.433462ms)","trace[640406882] 'compare' (duration: 162.622856ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:26:48.028901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:26:47.367097Z","time spent":"661.527598ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":621,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T18:27:16.063475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.998296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:16.06359Z","caller":"traceutil/trace.go:171","msg":"trace[1977259712] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:505; }","duration":"775.156238ms","start":"2026-03-26T18:27:15.288408Z","end":"2026-03-26T18:27:16.063565Z","steps":["trace[1977259712] 'range keys from in-memory index tree' (duration: 774.866543ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:16.063639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:15.288384Z","time spent":"775.24307ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-03-26T18:27:16.066264Z","caller":"traceutil/trace.go:171","msg":"trace[1254888788] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"293.449814ms","start":"2026-03-26T18:27:15.772755Z","end":"2026-03-26T18:27:16.066205Z","steps":["trace[1254888788] 'read index received' (duration: 293.214449ms)","trace[1254888788] 'applied index is now lower than readState.Index' (duration: 234.325µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:27:16.066323Z","caller":"traceutil/trace.go:171","msg":"trace[348812125] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"304.471418ms","start":"2026-03-26T18:27:15.761798Z","end":"2026-03-26T18:27:16.06627Z","steps":["trace[348812125] 'process raft request' (duration: 304.202422ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:16.066433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.663328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:16.06679Z","caller":"traceutil/trace.go:171","msg":"trace[592516962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"294.045965ms","start":"2026-03-26T18:27:15.772709Z","end":"2026-03-26T18:27:16.066755Z","steps":["trace[592516962] 'agreement among raft nodes before linearized reading' (duration: 293.653068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:16.066804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:15.761749Z","time spent":"304.827834ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:27:20.767231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.980061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-26T18:27:20.767339Z","caller":"traceutil/trace.go:171","msg":"trace[268169919] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"579.135514ms","start":"2026-03-26T18:27:20.188176Z","end":"2026-03-26T18:27:20.767311Z","steps":["trace[268169919] 'range keys from in-memory index tree' (duration: 578.803508ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:20.767383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:20.188154Z","time spent":"579.220445ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-26T18:27:20.949425Z","caller":"traceutil/trace.go:171","msg":"trace[1556522839] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"591.437492ms","start":"2026-03-26T18:27:20.357948Z","end":"2026-03-26T18:27:20.949386Z","steps":["trace[1556522839] 'process raft request' (duration: 583.937793ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:27:20.949494Z","caller":"traceutil/trace.go:171","msg":"trace[512775325] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"234.774519ms","start":"2026-03-26T18:27:20.714656Z","end":"2026-03-26T18:27:20.949431Z","steps":["trace[512775325] 'read index received' (duration: 226.364414ms)","trace[512775325] 'applied index is now lower than readState.Index' (duration: 8.408875ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:27:20.950309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.773479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:20.950411Z","caller":"traceutil/trace.go:171","msg":"trace[1783390919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"175.930012ms","start":"2026-03-26T18:27:20.774465Z","end":"2026-03-26T18:27:20.950395Z","steps":["trace[1783390919] 'agreement among raft nodes before linearized reading' (duration: 175.707068ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:27:20.95075Z","caller":"traceutil/trace.go:171","msg":"trace[1014327180] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"178.657511ms","start":"2026-03-26T18:27:20.772073Z","end":"2026-03-26T18:27:20.950731Z","steps":["trace[1014327180] 'process raft request' (duration: 177.274096ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:20.950883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:20.357913Z","time spent":"591.604744ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:27:20.951307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.664615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T18:27:20.951403Z","caller":"traceutil/trace.go:171","msg":"trace[1198402514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"236.759306ms","start":"2026-03-26T18:27:20.714634Z","end":"2026-03-26T18:27:20.951393Z","steps":["trace[1198402514] 'agreement among raft nodes before linearized reading' (duration: 234.892271ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:27:22.787704Z","caller":"traceutil/trace.go:171","msg":"trace[1940071533] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"297.579ms","start":"2026-03-26T18:27:22.490087Z","end":"2026-03-26T18:27:22.787666Z","steps":["trace[1940071533] 'read index received' (duration: 296.945318ms)","trace[1940071533] 'applied index is now lower than readState.Index' (duration: 632.622µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:27:22.787917Z","caller":"traceutil/trace.go:171","msg":"trace[1444291279] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"313.183078ms","start":"2026-03-26T18:27:22.474578Z","end":"2026-03-26T18:27:22.787762Z","steps":["trace[1444291279] 'process raft request' (duration: 312.472346ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:22.787977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.867345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5d5dk\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-03-26T18:27:22.788185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:22.474544Z","time spent":"313.493303ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:27:22.788354Z","caller":"traceutil/trace.go:171","msg":"trace[293251255] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5d5dk; range_end:; response_count:1; response_revision:611; }","duration":"298.07588ms","start":"2026-03-26T18:27:22.490069Z","end":"2026-03-26T18:27:22.788145Z","steps":["trace[293251255] 'agreement among raft nodes before linearized reading' (duration: 297.778294ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:23.53792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.709795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T18:27:23.538067Z","caller":"traceutil/trace.go:171","msg":"trace[622535593] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"734.70686ms","start":"2026-03-26T18:27:22.803337Z","end":"2026-03-26T18:27:23.538044Z","steps":["trace[622535593] 'process raft request' (duration: 556.766252ms)","trace[622535593] 'compare' (duration: 177.535681ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:27:23.538434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:22.803317Z","time spent":"735.048186ms","remote":"127.0.0.1:39986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:27:23.549529Z","caller":"traceutil/trace.go:171","msg":"trace[891990019] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"594.219983ms","start":"2026-03-26T18:27:22.955295Z","end":"2026-03-26T18:27:23.549515Z","steps":["trace[891990019] 'read index received' (duration: 404.898854ms)","trace[891990019] 'applied index is now lower than readState.Index' (duration: 189.320389ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:27:23.549726Z","caller":"traceutil/trace.go:171","msg":"trace[867874954] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"693.229114ms","start":"2026-03-26T18:27:22.856481Z","end":"2026-03-26T18:27:23.54971Z","steps":["trace[867874954] 'process raft request' (duration: 692.958489ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:23.549884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.759493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T18:27:23.549921Z","caller":"traceutil/trace.go:171","msg":"trace[1910958047] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"548.861035ms","start":"2026-03-26T18:27:23.001051Z","end":"2026-03-26T18:27:23.549912Z","steps":["trace[1910958047] 'agreement among raft nodes before linearized reading' (duration: 548.771604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:23.549963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:23.001028Z","time spent":"548.910206ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-26T18:27:23.550173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:22.856456Z","time spent":"693.689393ms","remote":"127.0.0.1:40136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T18:27:23.550194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.582202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-26T18:27:23.550393Z","caller":"traceutil/trace.go:171","msg":"trace[394361444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"303.850556ms","start":"2026-03-26T18:27:23.246514Z","end":"2026-03-26T18:27:23.550365Z","steps":["trace[394361444] 'agreement among raft nodes before linearized reading' (duration: 303.421439ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:23.550436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.72511ms","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-03-26T18:27:23.550554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:23.246484Z","time spent":"304.01527ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-26T18:27:23.55065Z","caller":"traceutil/trace.go:171","msg":"trace[312236371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"287.050306ms","start":"2026-03-26T18:27:23.263509Z","end":"2026-03-26T18:27:23.550559Z","steps":["trace[312236371] 'agreement among raft nodes before linearized reading' (duration: 286.388134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:23.550561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.264333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-26T18:27:23.550776Z","caller":"traceutil/trace.go:171","msg":"trace[648859847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"595.496777ms","start":"2026-03-26T18:27:22.955237Z","end":"2026-03-26T18:27:23.550733Z","steps":["trace[648859847] 'agreement among raft nodes before linearized reading' (duration: 594.387256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:23.550812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:22.955213Z","time spent":"595.590948ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-26T18:27:25.529754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014531053s","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-03-26T18:27:25.529895Z","caller":"traceutil/trace.go:171","msg":"trace[1860491706] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:613; }","duration":"1.014699436s","start":"2026-03-26T18:27:24.515173Z","end":"2026-03-26T18:27:25.529873Z","steps":["trace[1860491706] 'range keys from in-memory index tree' (duration: 1.014506162s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:25.53051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.82706021s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T18:27:25.53084Z","caller":"traceutil/trace.go:171","msg":"trace[1822240974] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.976461981s","start":"2026-03-26T18:27:23.554344Z","end":"2026-03-26T18:27:25.530806Z","steps":["trace[1822240974] 'process raft request' (duration: 148.975033ms)","trace[1822240974] 'compare' (duration: 1.826839077s)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:27:25.530985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:23.554321Z","time spent":"1.976583723s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:27:25.531072Z","caller":"traceutil/trace.go:171","msg":"trace[1264626858] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:636; }","duration":"1.971403038s","start":"2026-03-26T18:27:23.559647Z","end":"2026-03-26T18:27:25.53105Z","steps":["trace[1264626858] 'read index received' (duration: 143.690616ms)","trace[1264626858] 'applied index is now lower than readState.Index' (duration: 1.827711182s)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:27:25.53149Z","caller":"traceutil/trace.go:171","msg":"trace[1436883101] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.974502595s","start":"2026-03-26T18:27:23.556975Z","end":"2026-03-26T18:27:25.531477Z","steps":["trace[1436883101] 'process raft request' (duration: 1.973815942s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:25.531572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:23.556943Z","time spent":"1.974590117s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:27:25.531786Z","caller":"traceutil/trace.go:171","msg":"trace[1026692938] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"1.974189529s","start":"2026-03-26T18:27:23.557586Z","end":"2026-03-26T18:27:25.531776Z","steps":["trace[1026692938] 'process raft request' (duration: 1.973326433s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:25.531843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:23.557577Z","time spent":"1.97423829s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:27:25.531957Z","caller":"traceutil/trace.go:171","msg":"trace[2058733293] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"1.974026596s","start":"2026-03-26T18:27:23.557921Z","end":"2026-03-26T18:27:25.531947Z","steps":["trace[2058733293] 'process raft request' (duration: 1.9730777s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:25.532012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:23.557893Z","time spent":"1.974093698s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:27:25.532136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.972485138s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:25.532175Z","caller":"traceutil/trace.go:171","msg":"trace[467979236] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:617; }","duration":"1.972542548s","start":"2026-03-26T18:27:23.559621Z","end":"2026-03-26T18:27:25.532163Z","steps":["trace[467979236] 'agreement among raft nodes before linearized reading' (duration: 1.972486467s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:25.532204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:23.559612Z","time spent":"1.972586099s","remote":"127.0.0.1:40266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-03-26T18:27:25.649421Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.59387911s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-26T18:27:25.775125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000343574s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-26T18:27:25.775223Z","caller":"traceutil/trace.go:171","msg":"trace[670881194] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000499176s","start":"2026-03-26T18:27:23.774702Z","end":"2026-03-26T18:27:25.775202Z","steps":["trace[670881194] 'agreement among raft nodes before linearized reading' (duration: 2.000371244s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:25.77528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:23.774677Z","time spent":"2.000591828s","remote":"127.0.0.1:39792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/26 18:27:25 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-26T18:27:25.806558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.85227ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-26T18:27:25.806713Z","caller":"traceutil/trace.go:171","msg":"trace[1048765713] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"275.579133ms","start":"2026-03-26T18:27:25.53111Z","end":"2026-03-26T18:27:25.806689Z","steps":["trace[1048765713] 'read index received' (duration: 118.501229ms)","trace[1048765713] 'applied index is now lower than readState.Index' (duration: 157.075774ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:27:26.964958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.628015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:26.96514Z","caller":"traceutil/trace.go:171","msg":"trace[1349321827] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:632; }","duration":"196.87917ms","start":"2026-03-26T18:27:26.768219Z","end":"2026-03-26T18:27:26.965098Z","steps":["trace[1349321827] 'range keys from in-memory index tree' (duration: 196.518323ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:26.965537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.35041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T18:27:26.965575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.114974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:26.965626Z","caller":"traceutil/trace.go:171","msg":"trace[1228119092] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:632; }","duration":"223.209226ms","start":"2026-03-26T18:27:26.742405Z","end":"2026-03-26T18:27:26.965614Z","steps":["trace[1228119092] 'count revisions from in-memory index tree' (duration: 222.949181ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:27:26.965629Z","caller":"traceutil/trace.go:171","msg":"trace[903331359] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:632; }","duration":"222.517413ms","start":"2026-03-26T18:27:26.743091Z","end":"2026-03-26T18:27:26.965609Z","steps":["trace[903331359] 'range keys from in-memory index tree' (duration: 222.302439ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:26.965671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.159405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:26.965697Z","caller":"traceutil/trace.go:171","msg":"trace[166531398] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:632; }","duration":"223.246226ms","start":"2026-03-26T18:27:26.742444Z","end":"2026-03-26T18:27:26.96569Z","steps":["trace[166531398] 'range keys from in-memory index tree' (duration: 223.020722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:26.965754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.371586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:26.965775Z","caller":"traceutil/trace.go:171","msg":"trace[1144339111] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:632; }","duration":"198.419027ms","start":"2026-03-26T18:27:26.767347Z","end":"2026-03-26T18:27:26.965766Z","steps":["trace[1144339111] 'count revisions from in-memory index tree' (duration: 198.303724ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:26.966026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.963159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:26.966283Z","caller":"traceutil/trace.go:171","msg":"trace[1604912162] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:632; }","duration":"198.608611ms","start":"2026-03-26T18:27:26.767551Z","end":"2026-03-26T18:27:26.96616Z","steps":["trace[1604912162] 'range keys from in-memory index tree' (duration: 197.862087ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:27:27.926504Z","caller":"traceutil/trace.go:171","msg":"trace[1802405356] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"252.986776ms","start":"2026-03-26T18:27:27.67349Z","end":"2026-03-26T18:27:27.926476Z","steps":["trace[1802405356] 'read index received' (duration: 252.693121ms)","trace[1802405356] 'applied index is now lower than readState.Index' (duration: 292.395µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:27:27.926577Z","caller":"traceutil/trace.go:171","msg":"trace[1616437100] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"264.598741ms","start":"2026-03-26T18:27:27.66195Z","end":"2026-03-26T18:27:27.926549Z","steps":["trace[1616437100] 'process raft request' (duration: 264.178584ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:27.926954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.438185ms","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":"info","ts":"2026-03-26T18:27:27.927008Z","caller":"traceutil/trace.go:171","msg":"trace[448118331] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:653; }","duration":"253.561607ms","start":"2026-03-26T18:27:27.673433Z","end":"2026-03-26T18:27:27.926994Z","steps":["trace[448118331] 'agreement among raft nodes before linearized reading' (duration: 253.235261ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:27.930991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.344477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:27.931096Z","caller":"traceutil/trace.go:171","msg":"trace[1801772672] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:655; }","duration":"257.49264ms","start":"2026-03-26T18:27:27.673585Z","end":"2026-03-26T18:27:27.931077Z","steps":["trace[1801772672] 'agreement among raft nodes before linearized reading' (duration: 257.301026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:27.931365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.963232ms","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-03-26T18:27:27.931392Z","caller":"traceutil/trace.go:171","msg":"trace[414225643] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:655; }","duration":"236.034563ms","start":"2026-03-26T18:27:27.69535Z","end":"2026-03-26T18:27:27.931384Z","steps":["trace[414225643] 'agreement among raft nodes before linearized reading' (duration: 235.989942ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:27.931514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.228877ms","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":"warn","ts":"2026-03-26T18:27:27.931504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.509743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:27.931669Z","caller":"traceutil/trace.go:171","msg":"trace[1069477717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"156.933441ms","start":"2026-03-26T18:27:27.774672Z","end":"2026-03-26T18:27:27.931605Z","steps":["trace[1069477717] 'agreement among raft nodes before linearized reading' (duration: 156.681596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:27.931809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.317304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:27.931861Z","caller":"traceutil/trace.go:171","msg":"trace[204868290] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:655; }","duration":"179.500627ms","start":"2026-03-26T18:27:27.752346Z","end":"2026-03-26T18:27:27.931846Z","steps":["trace[204868290] 'agreement among raft nodes before linearized reading' (duration: 179.418056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:27.932007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.483577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:27.932034Z","caller":"traceutil/trace.go:171","msg":"trace[1634847024] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:655; }","duration":"179.519038ms","start":"2026-03-26T18:27:27.752508Z","end":"2026-03-26T18:27:27.932027Z","steps":["trace[1634847024] 'agreement among raft nodes before linearized reading' (duration: 179.480438ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:27:27.932135Z","caller":"traceutil/trace.go:171","msg":"trace[1448066028] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"176.749517ms","start":"2026-03-26T18:27:27.755373Z","end":"2026-03-26T18:27:27.932123Z","steps":["trace[1448066028] 'process raft request' (duration: 175.331111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:27.932288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.832105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:27.93232Z","caller":"traceutil/trace.go:171","msg":"trace[977133240] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:655; }","duration":"179.899365ms","start":"2026-03-26T18:27:27.752413Z","end":"2026-03-26T18:27:27.932312Z","steps":["trace[977133240] 'agreement among raft nodes before linearized reading' (duration: 179.844355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:27.932461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.235374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T18:27:27.932482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.344996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:27.932496Z","caller":"traceutil/trace.go:171","msg":"trace[217935098] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:655; }","duration":"211.309186ms","start":"2026-03-26T18:27:27.721179Z","end":"2026-03-26T18:27:27.932488Z","steps":["trace[217935098] 'agreement among raft nodes before linearized reading' (duration: 211.231284ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:27:27.932509Z","caller":"traceutil/trace.go:171","msg":"trace[711168103] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:655; }","duration":"211.385527ms","start":"2026-03-26T18:27:27.721114Z","end":"2026-03-26T18:27:27.9325Z","steps":["trace[711168103] 'agreement among raft nodes before linearized reading' (duration: 211.332496ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:27.932645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.484943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:27.932673Z","caller":"traceutil/trace.go:171","msg":"trace[1115735316] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:655; }","duration":"195.526685ms","start":"2026-03-26T18:27:27.737139Z","end":"2026-03-26T18:27:27.932666Z","steps":["trace[1115735316] 'agreement among raft nodes before linearized reading' (duration: 195.477684ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:27:27.931539Z","caller":"traceutil/trace.go:171","msg":"trace[1195186799] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:655; }","duration":"236.265208ms","start":"2026-03-26T18:27:27.695267Z","end":"2026-03-26T18:27:27.931532Z","steps":["trace[1195186799] 'agreement among raft nodes before linearized reading' (duration: 236.222937ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:27.931572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.537171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:27.932843Z","caller":"traceutil/trace.go:171","msg":"trace[816597866] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:655; }","duration":"257.865946ms","start":"2026-03-26T18:27:27.674967Z","end":"2026-03-26T18:27:27.932833Z","steps":["trace[816597866] 'agreement among raft nodes before linearized reading' (duration: 256.586542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:27.933039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.22034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:27.933068Z","caller":"traceutil/trace.go:171","msg":"trace[625322727] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:655; }","duration":"194.250961ms","start":"2026-03-26T18:27:27.73881Z","end":"2026-03-26T18:27:27.933061Z","steps":["trace[625322727] 'agreement among raft nodes before linearized reading' (duration: 194.20779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:27.934299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.846807ms","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-03-26T18:27:27.934336Z","caller":"traceutil/trace.go:171","msg":"trace[1527619809] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:655; }","duration":"237.931758ms","start":"2026-03-26T18:27:27.696394Z","end":"2026-03-26T18:27:27.934326Z","steps":["trace[1527619809] 'agreement among raft nodes before linearized reading' (duration: 234.945243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:27.934587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.400519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:27.934624Z","caller":"traceutil/trace.go:171","msg":"trace[1691824404] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:655; }","duration":"197.450049ms","start":"2026-03-26T18:27:27.737163Z","end":"2026-03-26T18:27:27.934613Z","steps":["trace[1691824404] 'agreement among raft nodes before linearized reading' (duration: 197.374918ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:27:41.74713Z","caller":"traceutil/trace.go:171","msg":"trace[251634423] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"195.449462ms","start":"2026-03-26T18:27:41.551653Z","end":"2026-03-26T18:27:41.747102Z","steps":["trace[251634423] 'read index received' (duration: 195.359101ms)","trace[251634423] 'applied index is now lower than readState.Index' (duration: 89.021µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:27:41.747305Z","caller":"traceutil/trace.go:171","msg":"trace[1328791225] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"259.769461ms","start":"2026-03-26T18:27:41.487497Z","end":"2026-03-26T18:27:41.747266Z","steps":["trace[1328791225] 'process raft request' (duration: 259.386555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:41.747644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.955862ms","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-03-26T18:27:41.747689Z","caller":"traceutil/trace.go:171","msg":"trace[561901477] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:781; }","duration":"196.086965ms","start":"2026-03-26T18:27:41.551591Z","end":"2026-03-26T18:27:41.747678Z","steps":["trace[561901477] 'agreement among raft nodes before linearized reading' (duration: 195.870261ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:42.247308Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708645326102379647,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-26T18:27:42.413903Z","caller":"traceutil/trace.go:171","msg":"trace[1342902526] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"769.123257ms","start":"2026-03-26T18:27:41.644746Z","end":"2026-03-26T18:27:42.413869Z","steps":["trace[1342902526] 'process raft request' (duration: 768.901912ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:42.414107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:41.644727Z","time spent":"769.27366ms","remote":"127.0.0.1:40032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:27:42.446355Z","caller":"traceutil/trace.go:171","msg":"trace[1911537298] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:809; }","duration":"699.104752ms","start":"2026-03-26T18:27:41.747223Z","end":"2026-03-26T18:27:42.446328Z","steps":["trace[1911537298] 'read index received' (duration: 666.392767ms)","trace[1911537298] 'applied index is now lower than readState.Index' (duration: 32.710155ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:27:42.446614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.491905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T18:27:42.446663Z","caller":"traceutil/trace.go:171","msg":"trace[273826075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:783; }","duration":"821.613277ms","start":"2026-03-26T18:27:41.625038Z","end":"2026-03-26T18:27:42.446651Z","steps":["trace[273826075] 'agreement among raft nodes before linearized reading' (duration: 821.406373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:42.446701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:41.625004Z","time spent":"821.688198ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-26T18:27:42.447016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.216345ms","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-03-26T18:27:42.447058Z","caller":"traceutil/trace.go:171","msg":"trace[1873270723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:783; }","duration":"777.279697ms","start":"2026-03-26T18:27:41.669769Z","end":"2026-03-26T18:27:42.447049Z","steps":["trace[1873270723] 'agreement among raft nodes before linearized reading' (duration: 777.159705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:42.447089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:41.669758Z","time spent":"777.325408ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T18:27:42.447298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.928856ms","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-03-26T18:27:42.447337Z","caller":"traceutil/trace.go:171","msg":"trace[298426737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:783; }","duration":"799.005708ms","start":"2026-03-26T18:27:41.648323Z","end":"2026-03-26T18:27:42.447329Z","steps":["trace[298426737] 'agreement among raft nodes before linearized reading' (duration: 798.903196ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:42.447365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:41.6483Z","time spent":"799.059129ms","remote":"127.0.0.1:40056","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":"info","ts":"2026-03-26T18:27:42.447531Z","caller":"traceutil/trace.go:171","msg":"trace[404473487] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"694.11762ms","start":"2026-03-26T18:27:41.753395Z","end":"2026-03-26T18:27:42.447513Z","steps":["trace[404473487] 'process raft request' (duration: 692.783336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:42.447655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:41.753364Z","time spent":"694.224372ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:27:42.447871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.615461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:27:42.447968Z","caller":"traceutil/trace.go:171","msg":"trace[670607522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"673.799854ms","start":"2026-03-26T18:27:41.774147Z","end":"2026-03-26T18:27:42.447947Z","steps":["trace[670607522] 'agreement among raft nodes before linearized reading' (duration: 672.932238ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:42.448021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:41.774122Z","time spent":"673.889866ms","remote":"127.0.0.1:39792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T18:27:42.448404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.649288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-26T18:27:42.448511Z","caller":"traceutil/trace.go:171","msg":"trace[2088635794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:783; }","duration":"774.782901ms","start":"2026-03-26T18:27:41.673715Z","end":"2026-03-26T18:27:42.448498Z","steps":["trace[2088635794] 'agreement among raft nodes before linearized reading' (duration: 772.948757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:27:42.448548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:27:41.673704Z","time spent":"774.834242ms","remote":"127.0.0.1:40056","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-03-26T18:28:16.844796Z","caller":"traceutil/trace.go:171","msg":"trace[411983670] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1030; }","duration":"179.001523ms","start":"2026-03-26T18:28:16.665764Z","end":"2026-03-26T18:28:16.844766Z","steps":["trace[411983670] 'read index received' (duration: 21.126065ms)","trace[411983670] 'applied index is now lower than readState.Index' (duration: 157.874108ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:28:16.845134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.34017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T18:28:16.845175Z","caller":"traceutil/trace.go:171","msg":"trace[818166456] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:994; }","duration":"179.425002ms","start":"2026-03-26T18:28:16.665736Z","end":"2026-03-26T18:28:16.845161Z","steps":["trace[818166456] 'agreement among raft nodes before linearized reading' (duration: 179.209358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:16.845334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.700669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-26T18:28:16.845447Z","caller":"traceutil/trace.go:171","msg":"trace[339165857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:994; }","duration":"178.856941ms","start":"2026-03-26T18:28:16.666562Z","end":"2026-03-26T18:28:16.845419Z","steps":["trace[339165857] 'agreement among raft nodes before linearized reading' (duration: 178.429334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:16.845368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.268721ms","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-03-26T18:28:16.845563Z","caller":"traceutil/trace.go:171","msg":"trace[190477393] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:994; }","duration":"177.522376ms","start":"2026-03-26T18:28:16.668025Z","end":"2026-03-26T18:28:16.845547Z","steps":["trace[190477393] 'agreement among raft nodes before linearized reading' (duration: 177.074517ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:28:16.844963Z","caller":"traceutil/trace.go:171","msg":"trace[1477650870] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"182.53483ms","start":"2026-03-26T18:28:16.662383Z","end":"2026-03-26T18:28:16.844918Z","steps":["trace[1477650870] 'process raft request' (duration: 163.781238ms)","trace[1477650870] 'compare' (duration: 18.454187ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:28:18.084612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.294317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T18:28:18.084796Z","caller":"traceutil/trace.go:171","msg":"trace[1847679630] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"1.192225268s","start":"2026-03-26T18:28:16.892522Z","end":"2026-03-26T18:28:18.084748Z","steps":["trace[1847679630] 'process raft request' (duration: 496.653866ms)","trace[1847679630] 'compare' (duration: 695.105674ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:28:18.084877Z","caller":"traceutil/trace.go:171","msg":"trace[1258403996] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"1.114707707s","start":"2026-03-26T18:28:16.970147Z","end":"2026-03-26T18:28:18.084855Z","steps":["trace[1258403996] 'process raft request' (duration: 1.114599565s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:18.084957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:16.970118Z","time spent":"1.114805748s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:28:18.084978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:16.892493Z","time spent":"1.192386451s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:28:18.776878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.735693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:28:18.777157Z","caller":"traceutil/trace.go:171","msg":"trace[944788299] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:999; }","duration":"342.054689ms","start":"2026-03-26T18:28:18.435078Z","end":"2026-03-26T18:28:18.777133Z","steps":["trace[944788299] 'count revisions from in-memory index tree' (duration: 341.56197ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:18.777306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:18.434968Z","time spent":"342.323925ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-03-26T18:28:19.339708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708645326102380109,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-26T18:28:19.374307Z","caller":"traceutil/trace.go:171","msg":"trace[1406929581] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"535.223509ms","start":"2026-03-26T18:28:18.839054Z","end":"2026-03-26T18:28:19.374278Z","steps":["trace[1406929581] 'read index received' (duration: 534.988844ms)","trace[1406929581] 'applied index is now lower than readState.Index' (duration: 233.655µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:28:19.374592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.835229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-26T18:28:19.374629Z","caller":"traceutil/trace.go:171","msg":"trace[2038085774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1000; }","duration":"496.949912ms","start":"2026-03-26T18:28:18.877668Z","end":"2026-03-26T18:28:19.374618Z","steps":["trace[2038085774] 'agreement among raft nodes before linearized reading' (duration: 496.766067ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:19.374609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.05009ms","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-03-26T18:28:19.374653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.065315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-26T18:28:19.374669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:18.877629Z","time spent":"497.031913ms","remote":"127.0.0.1:40056","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-03-26T18:28:19.374688Z","caller":"traceutil/trace.go:171","msg":"trace[213593326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"524.158582ms","start":"2026-03-26T18:28:18.850509Z","end":"2026-03-26T18:28:19.374668Z","steps":["trace[213593326] 'agreement among raft nodes before linearized reading' (duration: 523.922616ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:28:19.374702Z","caller":"traceutil/trace.go:171","msg":"trace[1433619011] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1000; }","duration":"504.147916ms","start":"2026-03-26T18:28:18.87054Z","end":"2026-03-26T18:28:19.374688Z","steps":["trace[1433619011] 'agreement among raft nodes before linearized reading' (duration: 504.020614ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:19.374725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:18.850501Z","time spent":"524.215932ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T18:28:19.374737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:18.870515Z","time spent":"504.214078ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-26T18:28:19.374754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.638556ms","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-03-26T18:28:19.374843Z","caller":"traceutil/trace.go:171","msg":"trace[1540737197] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1000; }","duration":"496.782799ms","start":"2026-03-26T18:28:18.878049Z","end":"2026-03-26T18:28:19.374832Z","steps":["trace[1540737197] 'agreement among raft nodes before linearized reading' (duration: 496.481433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:19.374892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.85196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.110\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-26T18:28:19.374894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:18.878041Z","time spent":"496.84445ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-26T18:28:19.374917Z","caller":"traceutil/trace.go:171","msg":"trace[1420028092] range","detail":"{range_begin:/registry/masterleases/162.253.55.110; range_end:; response_count:1; response_revision:1000; }","duration":"535.926722ms","start":"2026-03-26T18:28:18.838983Z","end":"2026-03-26T18:28:19.37491Z","steps":["trace[1420028092] 'agreement among raft nodes before linearized reading' (duration: 535.853551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:19.374937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:18.838955Z","time spent":"535.976523ms","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.110\" "} {"level":"info","ts":"2026-03-26T18:28:19.374449Z","caller":"traceutil/trace.go:171","msg":"trace[1064151411] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"591.792588ms","start":"2026-03-26T18:28:18.78259Z","end":"2026-03-26T18:28:19.374383Z","steps":["trace[1064151411] 'process raft request' (duration: 591.474903ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:19.37519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:18.782569Z","time spent":"592.545493ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:28:45.297983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031364475s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-26T18:28:45.298645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.863729ms","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-03-26T18:28:45.299507Z","caller":"traceutil/trace.go:171","msg":"trace[1186588445] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1084; }","duration":"783.722225ms","start":"2026-03-26T18:28:44.51576Z","end":"2026-03-26T18:28:45.299482Z","steps":["trace[1186588445] 'range keys from in-memory index tree' (duration: 782.851228ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:28:45.29969Z","caller":"traceutil/trace.go:171","msg":"trace[385982850] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1127; }","duration":"826.485937ms","start":"2026-03-26T18:28:44.473154Z","end":"2026-03-26T18:28:45.29964Z","steps":["trace[385982850] 'read index received' (duration: 329.425603ms)","trace[385982850] 'applied index is now lower than readState.Index' (duration: 497.057344ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:28:45.299725Z","caller":"traceutil/trace.go:171","msg":"trace[1175912600] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"1.092901279s","start":"2026-03-26T18:28:44.206784Z","end":"2026-03-26T18:28:45.299685Z","steps":["trace[1175912600] 'process raft request' (duration: 1.091316599s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:45.300038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:44.20676Z","time spent":"1.093124842s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:28:45.351718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.038436ms","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-03-26T18:28:45.351762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.564123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:28:45.351867Z","caller":"traceutil/trace.go:171","msg":"trace[377749626] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1085; }","duration":"209.26006ms","start":"2026-03-26T18:28:45.142554Z","end":"2026-03-26T18:28:45.351814Z","steps":["trace[377749626] 'agreement among raft nodes before linearized reading' (duration: 157.289657ms)","trace[377749626] 'count revisions from in-memory index tree' (duration: 51.78894ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:28:45.351706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.845079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:28:45.352027Z","caller":"traceutil/trace.go:171","msg":"trace[303750242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1085; }","duration":"577.168325ms","start":"2026-03-26T18:28:44.774789Z","end":"2026-03-26T18:28:45.351957Z","steps":["trace[303750242] 'agreement among raft nodes before linearized reading' (duration: 525.023968ms)","trace[303750242] 'range keys from in-memory index tree' (duration: 51.824781ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:28:45.352115Z","caller":"traceutil/trace.go:171","msg":"trace[1985862842] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1085; }","duration":"878.719465ms","start":"2026-03-26T18:28:44.473103Z","end":"2026-03-26T18:28:45.351822Z","steps":["trace[1985862842] 'agreement among raft nodes before linearized reading' (duration: 826.808353ms)","trace[1985862842] 'count revisions from in-memory index tree' (duration: 51.75572ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:28:45.352139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:44.774748Z","time spent":"577.330357ms","remote":"127.0.0.1:39792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T18:28:45.352437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:44.473079Z","time spent":"879.128232ms","remote":"127.0.0.1:40178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-03-26T18:28:45.630402Z","caller":"traceutil/trace.go:171","msg":"trace[1040359984] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"107.843581ms","start":"2026-03-26T18:28:45.522527Z","end":"2026-03-26T18:28:45.63037Z","steps":["trace[1040359984] 'process raft request' (duration: 107.570556ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:28:45.630744Z","caller":"traceutil/trace.go:171","msg":"trace[417167622] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"108.067305ms","start":"2026-03-26T18:28:45.522659Z","end":"2026-03-26T18:28:45.630726Z","steps":["trace[417167622] 'process raft request' (duration: 107.661028ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:28:45.630732Z","caller":"traceutil/trace.go:171","msg":"trace[1067899579] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"107.80523ms","start":"2026-03-26T18:28:45.522884Z","end":"2026-03-26T18:28:45.630689Z","steps":["trace[1067899579] 'process raft request' (duration: 107.677418ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:28:47.69136Z","caller":"traceutil/trace.go:171","msg":"trace[238386397] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"169.531867ms","start":"2026-03-26T18:28:47.521803Z","end":"2026-03-26T18:28:47.691335Z","steps":["trace[238386397] 'read index received' (duration: 169.331553ms)","trace[238386397] 'applied index is now lower than readState.Index' (duration: 199.134µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:28:47.6921Z","caller":"traceutil/trace.go:171","msg":"trace[168379865] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"387.010621ms","start":"2026-03-26T18:28:47.305052Z","end":"2026-03-26T18:28:47.692063Z","steps":["trace[168379865] 'process raft request' (duration: 386.145775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:47.692294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:47.305022Z","time spent":"387.174585ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:28:47.692296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.477495ms","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-03-26T18:28:47.692407Z","caller":"traceutil/trace.go:171","msg":"trace[1153393984] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1091; }","duration":"170.637478ms","start":"2026-03-26T18:28:47.521753Z","end":"2026-03-26T18:28:47.69239Z","steps":["trace[1153393984] 'agreement among raft nodes before linearized reading' (duration: 170.412924ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:28:47.830594Z","caller":"traceutil/trace.go:171","msg":"trace[469395315] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"133.44634ms","start":"2026-03-26T18:28:47.697109Z","end":"2026-03-26T18:28:47.830556Z","steps":["trace[469395315] 'process raft request' (duration: 111.477049ms)","trace[469395315] 'compare' (duration: 21.842609ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:28:47.89742Z","caller":"traceutil/trace.go:171","msg":"trace[1319973806] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"199.854304ms","start":"2026-03-26T18:28:47.697526Z","end":"2026-03-26T18:28:47.897381Z","steps":["trace[1319973806] 'process raft request' (duration: 199.748862ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:28:47.897479Z","caller":"traceutil/trace.go:171","msg":"trace[1623470113] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"199.931636ms","start":"2026-03-26T18:28:47.697474Z","end":"2026-03-26T18:28:47.897406Z","steps":["trace[1623470113] 'process raft request' (duration: 199.62994ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:28:47.897738Z","caller":"traceutil/trace.go:171","msg":"trace[31683559] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"200.13723ms","start":"2026-03-26T18:28:47.697566Z","end":"2026-03-26T18:28:47.897703Z","steps":["trace[31683559] 'process raft request' (duration: 199.765563ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:28:47.897775Z","caller":"traceutil/trace.go:171","msg":"trace[189756512] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1135; }","duration":"121.726861ms","start":"2026-03-26T18:28:47.776014Z","end":"2026-03-26T18:28:47.897741Z","steps":["trace[189756512] 'read index received' (duration: 32.657023ms)","trace[189756512] 'applied index is now lower than readState.Index' (duration: 89.068448ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:28:47.89793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.893164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:28:47.897973Z","caller":"traceutil/trace.go:171","msg":"trace[274951714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1096; }","duration":"121.998146ms","start":"2026-03-26T18:28:47.775964Z","end":"2026-03-26T18:28:47.897962Z","steps":["trace[274951714] 'agreement among raft nodes before linearized reading' (duration: 121.901294ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:28:47.897839Z","caller":"traceutil/trace.go:171","msg":"trace[789612162] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"198.965848ms","start":"2026-03-26T18:28:47.69885Z","end":"2026-03-26T18:28:47.897816Z","steps":["trace[789612162] 'process raft request' (duration: 198.748424ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:49.148608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:48.841069Z","time spent":"307.533973ms","remote":"127.0.0.1:39846","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-26T18:28:50.275206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708645326102380369,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-26T18:28:50.490978Z","caller":"traceutil/trace.go:171","msg":"trace[1777350103] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1143; }","duration":"716.578936ms","start":"2026-03-26T18:28:49.774311Z","end":"2026-03-26T18:28:50.49089Z","steps":["trace[1777350103] 'read index received' (duration: 716.359382ms)","trace[1777350103] 'applied index is now lower than readState.Index' (duration: 218.654µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:28:50.491164Z","caller":"traceutil/trace.go:171","msg":"trace[1469786495] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"791.547562ms","start":"2026-03-26T18:28:49.699608Z","end":"2026-03-26T18:28:50.491155Z","steps":["trace[1469786495] 'process raft request' (duration: 791.142893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:50.491309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:49.699579Z","time spent":"791.628584ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:28:50.491393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.062595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:28:50.491499Z","caller":"traceutil/trace.go:171","msg":"trace[207012587] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1099; }","duration":"717.217488ms","start":"2026-03-26T18:28:49.774255Z","end":"2026-03-26T18:28:50.491473Z","steps":["trace[207012587] 'agreement among raft nodes before linearized reading' (duration: 717.062306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:50.491512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.132339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T18:28:50.491558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:49.774199Z","time spent":"717.345101ms","remote":"127.0.0.1:39792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T18:28:50.491556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.065214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T18:28:50.491602Z","caller":"traceutil/trace.go:171","msg":"trace[851884259] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1099; }","duration":"272.293862ms","start":"2026-03-26T18:28:50.219287Z","end":"2026-03-26T18:28:50.491581Z","steps":["trace[851884259] 'agreement among raft nodes before linearized reading' (duration: 272.14052ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:28:50.49162Z","caller":"traceutil/trace.go:171","msg":"trace[1533393504] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1099; }","duration":"656.155975ms","start":"2026-03-26T18:28:49.835447Z","end":"2026-03-26T18:28:50.491603Z","steps":["trace[1533393504] 'agreement among raft nodes before linearized reading' (duration: 655.89389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:50.49167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:49.835433Z","time spent":"656.220507ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-26T18:28:50.491845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.937486ms","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-03-26T18:28:50.491638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.514559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-26T18:28:50.491934Z","caller":"traceutil/trace.go:171","msg":"trace[728846049] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1099; }","duration":"589.053497ms","start":"2026-03-26T18:28:49.902858Z","end":"2026-03-26T18:28:50.491912Z","steps":["trace[728846049] 'agreement among raft nodes before linearized reading' (duration: 588.876914ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:28:50.491975Z","caller":"traceutil/trace.go:171","msg":"trace[267056911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1099; }","duration":"587.905386ms","start":"2026-03-26T18:28:49.904058Z","end":"2026-03-26T18:28:50.491963Z","steps":["trace[267056911] 'agreement among raft nodes before linearized reading' (duration: 587.331986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:50.491989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:49.902846Z","time spent":"589.132128ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-26T18:28:50.491983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.696939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-26T18:28:50.492016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.359904ms","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-03-26T18:28:50.49207Z","caller":"traceutil/trace.go:171","msg":"trace[1715199874] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1099; }","duration":"589.853912ms","start":"2026-03-26T18:28:49.902186Z","end":"2026-03-26T18:28:50.492039Z","steps":["trace[1715199874] 'agreement among raft nodes before linearized reading' (duration: 589.652748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:50.492114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:49.902161Z","time spent":"589.944514ms","remote":"127.0.0.1:40056","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":"info","ts":"2026-03-26T18:28:50.492068Z","caller":"traceutil/trace.go:171","msg":"trace[1321336065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1099; }","duration":"588.433655ms","start":"2026-03-26T18:28:49.903622Z","end":"2026-03-26T18:28:50.492056Z","steps":["trace[1321336065] 'agreement among raft nodes before linearized reading' (duration: 588.286702ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:50.492209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:49.90361Z","time spent":"588.578418ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T18:28:50.492024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:49.904048Z","time spent":"587.962827ms","remote":"127.0.0.1:40056","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-03-26T18:28:50.800397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.235546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T18:28:50.800618Z","caller":"traceutil/trace.go:171","msg":"trace[1266280354] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"302.031195ms","start":"2026-03-26T18:28:50.498567Z","end":"2026-03-26T18:28:50.800598Z","steps":["trace[1266280354] 'process raft request' (duration: 301.994944ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:50.800688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:50.498557Z","time spent":"302.092135ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:28:50.80095Z","caller":"traceutil/trace.go:171","msg":"trace[1063584392] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"304.211044ms","start":"2026-03-26T18:28:50.496728Z","end":"2026-03-26T18:28:50.800939Z","steps":["trace[1063584392] 'process raft request' (duration: 195.343547ms)","trace[1063584392] 'compare' (duration: 107.92681ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:28:50.801007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:50.496708Z","time spent":"304.277175ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:28:50.801105Z","caller":"traceutil/trace.go:171","msg":"trace[1294703913] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"303.517882ms","start":"2026-03-26T18:28:50.497579Z","end":"2026-03-26T18:28:50.801097Z","steps":["trace[1294703913] 'process raft request' (duration: 302.890241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:50.801143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:50.497569Z","time spent":"303.556633ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:28:50.801222Z","caller":"traceutil/trace.go:171","msg":"trace[1036111483] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"303.404489ms","start":"2026-03-26T18:28:50.497812Z","end":"2026-03-26T18:28:50.801217Z","steps":["trace[1036111483] 'process raft request' (duration: 302.697357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:50.80127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:50.497803Z","time spent":"303.448101ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:28:50.801352Z","caller":"traceutil/trace.go:171","msg":"trace[1079499282] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"303.504862ms","start":"2026-03-26T18:28:50.497841Z","end":"2026-03-26T18:28:50.801346Z","steps":["trace[1079499282] 'process raft request' (duration: 302.694818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:28:50.80139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:28:50.497829Z","time spent":"303.542562ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:29:03.365472Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708645326102380568,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T18:29:03.866323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708645326102380568,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T18:29:04.062532Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.202954385s","expected-duration":"1s"} {"level":"info","ts":"2026-03-26T18:29:04.062945Z","caller":"traceutil/trace.go:171","msg":"trace[1661063605] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"1.203421612s","start":"2026-03-26T18:29:02.859489Z","end":"2026-03-26T18:29:04.06291Z","steps":["trace[1661063605] 'process raft request' (duration: 1.20325964s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:04.063132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:02.859476Z","time spent":"1.203559295s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:29:04.892416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.829057ms","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-03-26T18:29:04.892599Z","caller":"traceutil/trace.go:171","msg":"trace[546167580] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1190; }","duration":"377.069961ms","start":"2026-03-26T18:29:04.515504Z","end":"2026-03-26T18:29:04.892574Z","steps":["trace[546167580] 'range keys from in-memory index tree' (duration: 376.798206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:04.892925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.386836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T18:29:04.893169Z","caller":"traceutil/trace.go:171","msg":"trace[500526847] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1238; }","duration":"2.028572801s","start":"2026-03-26T18:29:02.864574Z","end":"2026-03-26T18:29:04.893147Z","steps":["trace[500526847] 'read index received' (duration: 1.198160497s)","trace[500526847] 'applied index is now lower than readState.Index' (duration: 830.411084ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:29:04.893429Z","caller":"traceutil/trace.go:171","msg":"trace[1208605820] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"2.033241655s","start":"2026-03-26T18:29:02.860163Z","end":"2026-03-26T18:29:04.893405Z","steps":["trace[1208605820] 'process raft request' (duration: 1.82827033s)","trace[1208605820] 'compare' (duration: 204.181501ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:29:04.893543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:02.860129Z","time spent":"2.033363307s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:29:04.893923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.029335015s","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-03-26T18:29:04.893995Z","caller":"traceutil/trace.go:171","msg":"trace[1337141226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1193; }","duration":"2.029426186s","start":"2026-03-26T18:29:02.86455Z","end":"2026-03-26T18:29:04.893977Z","steps":["trace[1337141226] 'agreement among raft nodes before linearized reading' (duration: 2.029190542s)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:29:04.894011Z","caller":"traceutil/trace.go:171","msg":"trace[1523505423] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"2.033756283s","start":"2026-03-26T18:29:02.860238Z","end":"2026-03-26T18:29:04.893994Z","steps":["trace[1523505423] 'process raft request' (duration: 2.032802997s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:04.894064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:02.864541Z","time spent":"2.029507327s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T18:29:04.894082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:02.86021Z","time spent":"2.033835135s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:29:04.894267Z","caller":"traceutil/trace.go:171","msg":"trace[1724850079] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"1.963100655s","start":"2026-03-26T18:29:02.931147Z","end":"2026-03-26T18:29:04.894248Z","steps":["trace[1724850079] 'process raft request' (duration: 1.961954714s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:04.894423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:02.931126Z","time spent":"1.963192896s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:29:04.911623Z","caller":"traceutil/trace.go:171","msg":"trace[249993775] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"366.594833ms","start":"2026-03-26T18:29:04.544999Z","end":"2026-03-26T18:29:04.911593Z","steps":["trace[249993775] 'process raft request' (duration: 366.259177ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:04.911755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.508431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T18:29:04.911792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:04.544977Z","time spent":"366.713045ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:29:04.911831Z","caller":"traceutil/trace.go:171","msg":"trace[732886522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1195; }","duration":"138.609523ms","start":"2026-03-26T18:29:04.773203Z","end":"2026-03-26T18:29:04.911813Z","steps":["trace[732886522] 'agreement among raft nodes before linearized reading' (duration: 138.503881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:04.911849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.045984214s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-26T18:29:04.911971Z","caller":"traceutil/trace.go:171","msg":"trace[1796519799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1195; }","duration":"2.046125876s","start":"2026-03-26T18:29:02.865795Z","end":"2026-03-26T18:29:04.911921Z","steps":["trace[1796519799] 'agreement among raft nodes before linearized reading' (duration: 2.045850151s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:04.912829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:02.865783Z","time spent":"2.046234958s","remote":"127.0.0.1:40056","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-03-26T18:29:17.147854Z","caller":"traceutil/trace.go:171","msg":"trace[1640210514] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"172.528882ms","start":"2026-03-26T18:29:16.975292Z","end":"2026-03-26T18:29:17.147821Z","steps":["trace[1640210514] 'process raft request' (duration: 172.279748ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:29:17.15155Z","caller":"traceutil/trace.go:171","msg":"trace[1912367383] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1282; }","duration":"170.024038ms","start":"2026-03-26T18:29:16.98151Z","end":"2026-03-26T18:29:17.151534Z","steps":["trace[1912367383] 'read index received' (duration: 166.137038ms)","trace[1912367383] 'applied index is now lower than readState.Index' (duration: 3.88577ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:29:17.151646Z","caller":"traceutil/trace.go:171","msg":"trace[1004435687] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"173.385468ms","start":"2026-03-26T18:29:16.978208Z","end":"2026-03-26T18:29:17.151593Z","steps":["trace[1004435687] 'process raft request' (duration: 173.254376ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:17.151904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.351714ms","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-03-26T18:29:17.151935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.699163ms","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-03-26T18:29:17.151996Z","caller":"traceutil/trace.go:171","msg":"trace[1292056784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1232; }","duration":"170.470476ms","start":"2026-03-26T18:29:16.981503Z","end":"2026-03-26T18:29:17.151973Z","steps":["trace[1292056784] 'agreement among raft nodes before linearized reading' (duration: 170.248712ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:29:17.152022Z","caller":"traceutil/trace.go:171","msg":"trace[854682763] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1232; }","duration":"168.819845ms","start":"2026-03-26T18:29:16.983181Z","end":"2026-03-26T18:29:17.152001Z","steps":["trace[854682763] 'agreement among raft nodes before linearized reading' (duration: 168.591641ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:17.151741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.204151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-26T18:29:17.152075Z","caller":"traceutil/trace.go:171","msg":"trace[1298244124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1232; }","duration":"170.579677ms","start":"2026-03-26T18:29:16.981482Z","end":"2026-03-26T18:29:17.152061Z","steps":["trace[1298244124] 'agreement among raft nodes before linearized reading' (duration: 170.14172ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:29:19.791991Z","caller":"traceutil/trace.go:171","msg":"trace[1793698553] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"218.686683ms","start":"2026-03-26T18:29:19.573266Z","end":"2026-03-26T18:29:19.791953Z","steps":["trace[1793698553] 'process raft request' (duration: 218.407498ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:29:20.129107Z","caller":"traceutil/trace.go:171","msg":"trace[2134211018] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"117.431652ms","start":"2026-03-26T18:29:20.011654Z","end":"2026-03-26T18:29:20.129085Z","steps":["trace[2134211018] 'process raft request' (duration: 117.271348ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:20.739344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.856511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T18:29:20.739623Z","caller":"traceutil/trace.go:171","msg":"trace[1050676299] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"601.905545ms","start":"2026-03-26T18:29:20.137682Z","end":"2026-03-26T18:29:20.739587Z","steps":["trace[1050676299] 'process raft request' (duration: 27.697287ms)","trace[1050676299] 'compare' (duration: 573.179359ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:29:20.739681Z","caller":"traceutil/trace.go:171","msg":"trace[100529437] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"598.922191ms","start":"2026-03-26T18:29:20.140734Z","end":"2026-03-26T18:29:20.739656Z","steps":["trace[100529437] 'process raft request' (duration: 598.760048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:20.739788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:20.140724Z","time spent":"599.012572ms","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:29:20.739827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:20.13766Z","time spent":"602.065517ms","remote":"127.0.0.1:39982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:29:20.739998Z","caller":"traceutil/trace.go:171","msg":"trace[567064499] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"599.076854ms","start":"2026-03-26T18:29:20.140902Z","end":"2026-03-26T18:29:20.739978Z","steps":["trace[567064499] 'process raft request' (duration: 598.679807ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:20.740101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:20.140897Z","time spent":"599.148125ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:29:20.740154Z","caller":"traceutil/trace.go:171","msg":"trace[591320329] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"597.738409ms","start":"2026-03-26T18:29:20.142403Z","end":"2026-03-26T18:29:20.740141Z","steps":["trace[591320329] 'process raft request' (duration: 597.418604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:20.740222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:20.14239Z","time spent":"597.79793ms","remote":"127.0.0.1:40224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:29:21.650721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.808406ms","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":"info","ts":"2026-03-26T18:29:21.650819Z","caller":"traceutil/trace.go:171","msg":"trace[1978784505] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1264; }","duration":"659.963108ms","start":"2026-03-26T18:29:20.99083Z","end":"2026-03-26T18:29:21.650793Z","steps":["trace[1978784505] 'count revisions from in-memory index tree' (duration: 659.706254ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:29:21.651619Z","caller":"traceutil/trace.go:171","msg":"trace[1076037367] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1317; }","duration":"489.634636ms","start":"2026-03-26T18:29:21.16197Z","end":"2026-03-26T18:29:21.651604Z","steps":["trace[1076037367] 'read index received' (duration: 464.970672ms)","trace[1076037367] 'applied index is now lower than readState.Index' (duration: 24.663034ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:29:21.651744Z","caller":"traceutil/trace.go:171","msg":"trace[814869230] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"492.805342ms","start":"2026-03-26T18:29:21.158925Z","end":"2026-03-26T18:29:21.651731Z","steps":["trace[814869230] 'process raft request' (duration: 468.079977ms)","trace[814869230] 'compare' (duration: 24.286358ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:29:21.65184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:21.158896Z","time spent":"492.872644ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:29:21.651996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.550684ms","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-03-26T18:29:21.652078Z","caller":"traceutil/trace.go:171","msg":"trace[1280145448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1265; }","duration":"479.659846ms","start":"2026-03-26T18:29:21.172396Z","end":"2026-03-26T18:29:21.652056Z","steps":["trace[1280145448] 'agreement among raft nodes before linearized reading' (duration: 479.448242ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:21.652124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:21.172389Z","time spent":"479.725727ms","remote":"127.0.0.1:40056","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-03-26T18:29:21.65226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.08275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-26T18:29:21.652304Z","caller":"traceutil/trace.go:171","msg":"trace[1842343360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1265; }","duration":"472.134991ms","start":"2026-03-26T18:29:21.180159Z","end":"2026-03-26T18:29:21.652294Z","steps":["trace[1842343360] 'agreement among raft nodes before linearized reading' (duration: 471.994319ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:21.652334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:21.180155Z","time spent":"472.172102ms","remote":"127.0.0.1:40056","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-03-26T18:29:21.652448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.325015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-26T18:29:21.652484Z","caller":"traceutil/trace.go:171","msg":"trace[468580232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1265; }","duration":"472.387626ms","start":"2026-03-26T18:29:21.180086Z","end":"2026-03-26T18:29:21.652473Z","steps":["trace[468580232] 'agreement among raft nodes before linearized reading' (duration: 472.274903ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:21.652515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:21.180079Z","time spent":"472.428467ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-26T18:29:21.652513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.541013ms","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-03-26T18:29:21.652547Z","caller":"traceutil/trace.go:171","msg":"trace[657288938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1265; }","duration":"490.604283ms","start":"2026-03-26T18:29:21.161933Z","end":"2026-03-26T18:29:21.652537Z","steps":["trace[657288938] 'agreement among raft nodes before linearized reading' (duration: 490.518002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:21.652574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:21.161921Z","time spent":"490.647945ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-26T18:29:21.650882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:20.990804Z","time spent":"660.06679ms","remote":"127.0.0.1:41104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-03-26T18:29:21.856118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.083392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T18:29:21.856354Z","caller":"traceutil/trace.go:171","msg":"trace[1957300461] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"198.784645ms","start":"2026-03-26T18:29:21.657534Z","end":"2026-03-26T18:29:21.856319Z","steps":["trace[1957300461] 'process raft request' (duration: 94.381978ms)","trace[1957300461] 'compare' (duration: 103.864488ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:29:21.856644Z","caller":"traceutil/trace.go:171","msg":"trace[48163536] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"199.02537ms","start":"2026-03-26T18:29:21.657611Z","end":"2026-03-26T18:29:21.856636Z","steps":["trace[48163536] 'process raft request' (duration: 198.767255ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:29:21.85677Z","caller":"traceutil/trace.go:171","msg":"trace[324281569] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"198.833866ms","start":"2026-03-26T18:29:21.657925Z","end":"2026-03-26T18:29:21.856759Z","steps":["trace[324281569] 'process raft request' (duration: 198.544681ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:29:21.85684Z","caller":"traceutil/trace.go:171","msg":"trace[1525114414] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"198.51074ms","start":"2026-03-26T18:29:21.658324Z","end":"2026-03-26T18:29:21.856835Z","steps":["trace[1525114414] 'process raft request' (duration: 198.181043ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:29:23.064455Z","caller":"traceutil/trace.go:171","msg":"trace[1356327281] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"316.357719ms","start":"2026-03-26T18:29:22.748046Z","end":"2026-03-26T18:29:23.064404Z","steps":["trace[1356327281] 'process raft request' (duration: 316.193656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:23.064648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:22.748005Z","time spent":"316.546742ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:29:24.166519Z","caller":"traceutil/trace.go:171","msg":"trace[2062744113] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"392.461497ms","start":"2026-03-26T18:29:23.774026Z","end":"2026-03-26T18:29:24.166487Z","steps":["trace[2062744113] 'read index received' (duration: 392.414947ms)","trace[2062744113] 'applied index is now lower than readState.Index' (duration: 45.07µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:29:24.166608Z","caller":"traceutil/trace.go:171","msg":"trace[378071634] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"510.540671ms","start":"2026-03-26T18:29:23.656025Z","end":"2026-03-26T18:29:24.166565Z","steps":["trace[378071634] 'process raft request' (duration: 510.299447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:24.166721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.666481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T18:29:24.166905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:23.655997Z","time spent":"510.837495ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:29:24.167041Z","caller":"traceutil/trace.go:171","msg":"trace[951952244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"392.992637ms","start":"2026-03-26T18:29:23.773984Z","end":"2026-03-26T18:29:24.166976Z","steps":["trace[951952244] 'agreement among raft nodes before linearized reading' (duration: 392.672411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:24.167181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:23.773964Z","time spent":"393.16589ms","remote":"127.0.0.1:39792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-26T18:29:24.296124Z","caller":"traceutil/trace.go:171","msg":"trace[215479231] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"129.313987ms","start":"2026-03-26T18:29:24.166783Z","end":"2026-03-26T18:29:24.296097Z","steps":["trace[215479231] 'read index received' (duration: 29.614193ms)","trace[215479231] 'applied index is now lower than readState.Index' (duration: 99.697724ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:29:24.29638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.7352ms","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-03-26T18:29:24.29641Z","caller":"traceutil/trace.go:171","msg":"trace[869570811] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1273; }","duration":"433.826112ms","start":"2026-03-26T18:29:23.862577Z","end":"2026-03-26T18:29:24.296403Z","steps":["trace[869570811] 'agreement among raft nodes before linearized reading' (duration: 433.649499ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:24.296434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:23.862551Z","time spent":"433.877362ms","remote":"127.0.0.1:40056","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-03-26T18:29:24.296512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.913939ms","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-03-26T18:29:24.296578Z","caller":"traceutil/trace.go:171","msg":"trace[562879032] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1273; }","duration":"436.027431ms","start":"2026-03-26T18:29:23.860535Z","end":"2026-03-26T18:29:24.296562Z","steps":["trace[562879032] 'agreement among raft nodes before linearized reading' (duration: 435.822957ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:24.296613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:23.860514Z","time spent":"436.092993ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-26T18:29:24.296759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.959732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-26T18:29:24.296784Z","caller":"traceutil/trace.go:171","msg":"trace[731927346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1273; }","duration":"435.035854ms","start":"2026-03-26T18:29:23.861742Z","end":"2026-03-26T18:29:24.296778Z","steps":["trace[731927346] 'agreement among raft nodes before linearized reading' (duration: 434.975572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:24.296801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:23.86173Z","time spent":"435.066775ms","remote":"127.0.0.1:40056","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-03-26T18:29:24.296589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.651315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:29:24.296879Z","caller":"traceutil/trace.go:171","msg":"trace[492666370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1273; }","duration":"127.978221ms","start":"2026-03-26T18:29:24.168892Z","end":"2026-03-26T18:29:24.29687Z","steps":["trace[492666370] 'agreement among raft nodes before linearized reading' (duration: 127.679776ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:24.297024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.603964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-26T18:29:24.297048Z","caller":"traceutil/trace.go:171","msg":"trace[1087368506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1273; }","duration":"435.642304ms","start":"2026-03-26T18:29:23.8614Z","end":"2026-03-26T18:29:24.297042Z","steps":["trace[1087368506] 'agreement among raft nodes before linearized reading' (duration: 435.575723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:24.297068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:23.861394Z","time spent":"435.668834ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-26T18:29:25.39593Z","caller":"traceutil/trace.go:171","msg":"trace[2012100593] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"324.039137ms","start":"2026-03-26T18:29:25.071857Z","end":"2026-03-26T18:29:25.395897Z","steps":["trace[2012100593] 'process raft request' (duration: 323.855434ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:25.396134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:25.071837Z","time spent":"324.18939ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:29:26.084113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.591821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T18:29:26.084249Z","caller":"traceutil/trace.go:171","msg":"trace[1416476332] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1338; }","duration":"311.40371ms","start":"2026-03-26T18:29:25.772812Z","end":"2026-03-26T18:29:26.084216Z","steps":["trace[1416476332] 'read index received' (duration: 102.569345ms)","trace[1416476332] 'applied index is now lower than readState.Index' (duration: 208.832465ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:29:26.084324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.511862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:29:26.084356Z","caller":"traceutil/trace.go:171","msg":"trace[1422433291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1285; }","duration":"311.585804ms","start":"2026-03-26T18:29:25.77276Z","end":"2026-03-26T18:29:26.084346Z","steps":["trace[1422433291] 'agreement among raft nodes before linearized reading' (duration: 311.524473ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:29:26.084275Z","caller":"traceutil/trace.go:171","msg":"trace[1764423328] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"952.187154ms","start":"2026-03-26T18:29:25.132045Z","end":"2026-03-26T18:29:26.084232Z","steps":["trace[1764423328] 'process raft request' (duration: 743.357308ms)","trace[1764423328] 'compare' (duration: 208.102982ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:29:26.084394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:25.772736Z","time spent":"311.650754ms","remote":"127.0.0.1:39806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T18:29:26.084664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:25.132018Z","time spent":"952.447548ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:29:26.524078Z","caller":"traceutil/trace.go:171","msg":"trace[94309515] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"208.53763ms","start":"2026-03-26T18:29:26.31551Z","end":"2026-03-26T18:29:26.524048Z","steps":["trace[94309515] 'read index received' (duration: 208.408798ms)","trace[94309515] 'applied index is now lower than readState.Index' (duration: 127.692µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:29:26.524358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.822685ms","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-03-26T18:29:26.524401Z","caller":"traceutil/trace.go:171","msg":"trace[472698256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1286; }","duration":"208.934147ms","start":"2026-03-26T18:29:26.315456Z","end":"2026-03-26T18:29:26.52439Z","steps":["trace[472698256] 'agreement among raft nodes before linearized reading' (duration: 208.735054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:26.524675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.025861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-26T18:29:26.524687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.372908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-26T18:29:26.524729Z","caller":"traceutil/trace.go:171","msg":"trace[573480306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1286; }","duration":"128.45915ms","start":"2026-03-26T18:29:26.39626Z","end":"2026-03-26T18:29:26.52472Z","steps":["trace[573480306] 'agreement among raft nodes before linearized reading' (duration: 128.353368ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:29:26.524727Z","caller":"traceutil/trace.go:171","msg":"trace[608750735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1286; }","duration":"129.123542ms","start":"2026-03-26T18:29:26.39559Z","end":"2026-03-26T18:29:26.524714Z","steps":["trace[608750735] 'agreement among raft nodes before linearized reading' (duration: 128.991429ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:29:26.524169Z","caller":"traceutil/trace.go:171","msg":"trace[1554197554] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"351.645144ms","start":"2026-03-26T18:29:26.1725Z","end":"2026-03-26T18:29:26.524145Z","steps":["trace[1554197554] 'process raft request' (duration: 351.329938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:26.524796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.613985ms","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-03-26T18:29:26.524869Z","caller":"traceutil/trace.go:171","msg":"trace[1665962853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1286; }","duration":"127.728118ms","start":"2026-03-26T18:29:26.397123Z","end":"2026-03-26T18:29:26.524851Z","steps":["trace[1665962853] 'agreement among raft nodes before linearized reading' (duration: 127.471103ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:26.524966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:26.17248Z","time spent":"352.364617ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:29:26.954842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.296539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T18:29:26.954961Z","caller":"traceutil/trace.go:171","msg":"trace[1240606568] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1342; }","duration":"180.68929ms","start":"2026-03-26T18:29:26.77426Z","end":"2026-03-26T18:29:26.954949Z","steps":["trace[1240606568] 'read index received' (duration: 48.110975ms)","trace[1240606568] 'applied index is now lower than readState.Index' (duration: 132.577395ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:29:26.955142Z","caller":"traceutil/trace.go:171","msg":"trace[137962383] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"425.666475ms","start":"2026-03-26T18:29:26.529468Z","end":"2026-03-26T18:29:26.955135Z","steps":["trace[137962383] 'process raft request' (duration: 293.004229ms)","trace[137962383] 'compare' (duration: 132.136696ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T18:29:26.95518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:26.529452Z","time spent":"425.710855ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T18:29:26.955283Z","caller":"traceutil/trace.go:171","msg":"trace[202201838] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"424.770278ms","start":"2026-03-26T18:29:26.530508Z","end":"2026-03-26T18:29:26.955278Z","steps":["trace[202201838] 'process raft request' (duration: 424.407772ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:26.955312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:26.530489Z","time spent":"424.808179ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:29:26.955381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.123247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:29:26.955398Z","caller":"traceutil/trace.go:171","msg":"trace[2005568705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1290; }","duration":"181.212428ms","start":"2026-03-26T18:29:26.774181Z","end":"2026-03-26T18:29:26.955393Z","steps":["trace[2005568705] 'agreement among raft nodes before linearized reading' (duration: 181.185508ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:26.955476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.485404ms","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":"info","ts":"2026-03-26T18:29:26.955505Z","caller":"traceutil/trace.go:171","msg":"trace[473980696] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1290; }","duration":"151.537765ms","start":"2026-03-26T18:29:26.803962Z","end":"2026-03-26T18:29:26.9555Z","steps":["trace[473980696] 'agreement among raft nodes before linearized reading' (duration: 151.499455ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:29:34.593599Z","caller":"traceutil/trace.go:171","msg":"trace[1117851319] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"259.785952ms","start":"2026-03-26T18:29:34.333788Z","end":"2026-03-26T18:29:34.593574Z","steps":["trace[1117851319] 'process raft request' (duration: 259.652799ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:36.14233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.643229ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-26T18:29:36.142585Z","caller":"traceutil/trace.go:171","msg":"trace[1619591004] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1402; }","duration":"1.640037265s","start":"2026-03-26T18:29:34.502532Z","end":"2026-03-26T18:29:36.142569Z","steps":["trace[1619591004] 'read index received' (duration: 90.916765ms)","trace[1619591004] 'applied index is now lower than readState.Index' (duration: 1.54911951s)"],"step_count":2} {"level":"info","ts":"2026-03-26T18:29:36.142765Z","caller":"traceutil/trace.go:171","msg":"trace[1129292320] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"1.591253277s","start":"2026-03-26T18:29:34.551503Z","end":"2026-03-26T18:29:36.142756Z","steps":["trace[1129292320] 'process raft request' (duration: 1.590916231s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:36.142918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:34.551466Z","time spent":"1.591329598s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T18:29:36.143039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.454824763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-26T18:29:36.143155Z","caller":"traceutil/trace.go:171","msg":"trace[549584177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1349; }","duration":"1.455008676s","start":"2026-03-26T18:29:34.688119Z","end":"2026-03-26T18:29:36.143127Z","steps":["trace[549584177] 'agreement among raft nodes before linearized reading' (duration: 1.454718131s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:36.143241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:34.688091Z","time spent":"1.455139958s","remote":"127.0.0.1:40056","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-03-26T18:29:36.143259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.729431ms","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-03-26T18:29:36.14329Z","caller":"traceutil/trace.go:171","msg":"trace[1002116720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1349; }","duration":"705.814503ms","start":"2026-03-26T18:29:35.437467Z","end":"2026-03-26T18:29:36.143281Z","steps":["trace[1002116720] 'agreement among raft nodes before linearized reading' (duration: 705.705321ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:36.143317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:35.437438Z","time spent":"705.875154ms","remote":"127.0.0.1:40056","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-03-26T18:29:36.143401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.640880469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-26T18:29:36.143423Z","caller":"traceutil/trace.go:171","msg":"trace[909021211] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1349; }","duration":"1.6409422s","start":"2026-03-26T18:29:34.502475Z","end":"2026-03-26T18:29:36.143417Z","steps":["trace[909021211] 'agreement among raft nodes before linearized reading' (duration: 1.640889419s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:36.143439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:34.502451Z","time spent":"1.64098407s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-26T18:29:36.143474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.433132582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-26T18:29:36.143505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.452172945s","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-03-26T18:29:36.143518Z","caller":"traceutil/trace.go:171","msg":"trace[1353144096] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1349; }","duration":"1.433202624s","start":"2026-03-26T18:29:34.710303Z","end":"2026-03-26T18:29:36.143505Z","steps":["trace[1353144096] 'agreement among raft nodes before linearized reading' (duration: 1.433118322s)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:29:36.14354Z","caller":"traceutil/trace.go:171","msg":"trace[1751618280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1349; }","duration":"1.452230716s","start":"2026-03-26T18:29:34.691297Z","end":"2026-03-26T18:29:36.143528Z","steps":["trace[1751618280] 'agreement among raft nodes before linearized reading' (duration: 1.452141105s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:36.143569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:34.691282Z","time spent":"1.452281657s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T18:29:36.143573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:34.710291Z","time spent":"1.433271935s","remote":"127.0.0.1:40244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-03-26T18:29:36.143615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.480271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:29:36.143643Z","caller":"traceutil/trace.go:171","msg":"trace[107843454] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1349; }","duration":"261.557963ms","start":"2026-03-26T18:29:35.882079Z","end":"2026-03-26T18:29:36.143637Z","steps":["trace[107843454] 'agreement among raft nodes before linearized reading' (duration: 261.514042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:36.14374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.261081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T18:29:36.143815Z","caller":"traceutil/trace.go:171","msg":"trace[317717539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1349; }","duration":"369.306342ms","start":"2026-03-26T18:29:35.77445Z","end":"2026-03-26T18:29:36.143756Z","steps":["trace[317717539] 'agreement among raft nodes before linearized reading' (duration: 369.27434ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:36.143836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:35.774438Z","time spent":"369.394463ms","remote":"127.0.0.1:39792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T18:29:36.14385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157516537s","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-03-26T18:29:36.143858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299987589s","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-03-26T18:29:36.143883Z","caller":"traceutil/trace.go:171","msg":"trace[114426790] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1349; }","duration":"1.157593018s","start":"2026-03-26T18:29:34.986282Z","end":"2026-03-26T18:29:36.143875Z","steps":["trace[114426790] 'agreement among raft nodes before linearized reading' (duration: 1.157477016s)"],"step_count":1} {"level":"info","ts":"2026-03-26T18:29:36.143891Z","caller":"traceutil/trace.go:171","msg":"trace[1963390668] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1349; }","duration":"1.300128451s","start":"2026-03-26T18:29:34.843753Z","end":"2026-03-26T18:29:36.143882Z","steps":["trace[1963390668] 'agreement among raft nodes before linearized reading' (duration: 1.300015679s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:36.143909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:34.986258Z","time spent":"1.157645219s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-26T18:29:36.143918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:34.84373Z","time spent":"1.300180972s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-26T18:29:36.143957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.988233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-26T18:29:36.143984Z","caller":"traceutil/trace.go:171","msg":"trace[1328175924] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1349; }","duration":"415.060255ms","start":"2026-03-26T18:29:35.728916Z","end":"2026-03-26T18:29:36.143976Z","steps":["trace[1328175924] 'agreement among raft nodes before linearized reading' (duration: 415.002174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:36.144006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:35.728888Z","time spent":"415.113936ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-26T18:29:36.144192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.156846833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T18:29:36.144224Z","caller":"traceutil/trace.go:171","msg":"trace[210720307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1349; }","duration":"1.156903305s","start":"2026-03-26T18:29:34.987314Z","end":"2026-03-26T18:29:36.144217Z","steps":["trace[210720307] 'agreement among raft nodes before linearized reading' (duration: 1.156682801s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T18:29:36.144243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T18:29:34.987303Z","time spent":"1.156935315s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-26T18:32:40.915949Z","caller":"traceutil/trace.go:171","msg":"trace[31668134] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"158.170621ms","start":"2026-03-26T18:32:40.757747Z","end":"2026-03-26T18:32:40.915917Z","steps":["trace[31668134] 'process raft request' (duration: 157.990337ms)"],"step_count":1}