{"level":"warn","ts":"2026-04-24T01:52:18.406192Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-24T01:52:18.406266Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-24T01:52:18.406322Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-24T01:52:18.406332Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-04-24T01:52:18.406351Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-24T01:52:18.406844Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-04-24T01:52:18.406917Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-24T01:52:18.417671Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.552263ms"} {"level":"info","ts":"2026-04-24T01:52:18.430618Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-04-24T01:52:18.430702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-04-24T01:52:18.430723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-04-24T01:52:18.430733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-24T01:52:18.43074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-04-24T01:52:18.430773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-04-24T01:52:18.440837Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-24T01:52:18.445158Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-24T01:52:18.446479Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-24T01:52:18.448131Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-24T01:52:18.449244Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-24T01:52:18.449292Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-24T01:52:18.450609Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-24T01:52:18.45064Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-24T01:52:18.451402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-04-24T01:52:18.451593Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-04-24T01:52:18.452051Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-24T01:52:18.452198Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-04-24T01:52:18.452221Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-04-24T01:52:18.452817Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-24T01:52:18.45285Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-24T01:52:18.731563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-04-24T01:52:18.731642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-24T01:52:18.731669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-04-24T01:52:18.731775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-04-24T01:52:18.731794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-04-24T01:52:18.731812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-04-24T01:52:18.731854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-04-24T01:52:18.734282Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T01:52:18.736053Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-24T01:52:18.736059Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-04-24T01:52:18.736105Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-24T01:52:18.736517Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-24T01:52:18.736539Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-24T01:52:18.736772Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T01:52:18.736946Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T01:52:18.736997Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T01:52:18.738796Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-24T01:52:18.750195Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-04-24T01:52:27.068507Z","caller":"traceutil/trace.go:171","msg":"trace[776961838] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"102.885868ms","start":"2026-04-24T01:52:26.965533Z","end":"2026-04-24T01:52:27.068419Z","steps":["trace[776961838] 'process raft request' (duration: 102.595101ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:34.871959Z","caller":"traceutil/trace.go:171","msg":"trace[855459112] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:449; }","duration":"277.632955ms","start":"2026-04-24T01:52:34.594308Z","end":"2026-04-24T01:52:34.871941Z","steps":["trace[855459112] 'read index received' (duration: 277.597954ms)","trace[855459112] 'applied index is now lower than readState.Index' (duration: 34.161µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:52:34.872019Z","caller":"traceutil/trace.go:171","msg":"trace[1438932695] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"436.963381ms","start":"2026-04-24T01:52:34.435042Z","end":"2026-04-24T01:52:34.872005Z","steps":["trace[1438932695] 'process raft request' (duration: 436.803998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:34.872071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.745227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-24T01:52:34.872115Z","caller":"traceutil/trace.go:171","msg":"trace[1343770976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:437; }","duration":"277.84695ms","start":"2026-04-24T01:52:34.594262Z","end":"2026-04-24T01:52:34.872109Z","steps":["trace[1343770976] 'agreement among raft nodes before linearized reading' (duration: 277.765508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:34.872348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.509798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-24T01:52:34.872391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.709755ms","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-04-24T01:52:34.872444Z","caller":"traceutil/trace.go:171","msg":"trace[1745949616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:437; }","duration":"128.783696ms","start":"2026-04-24T01:52:34.743644Z","end":"2026-04-24T01:52:34.872428Z","steps":["trace[1745949616] 'agreement among raft nodes before linearized reading' (duration: 128.670733ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:34.872446Z","caller":"traceutil/trace.go:171","msg":"trace[1333057209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:437; }","duration":"220.642212ms","start":"2026-04-24T01:52:34.65178Z","end":"2026-04-24T01:52:34.872422Z","steps":["trace[1333057209] 'agreement among raft nodes before linearized reading' (duration: 220.449007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:34.872478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:34.435028Z","time spent":"437.026492ms","remote":"127.0.0.1:57138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:52:35.123155Z","caller":"traceutil/trace.go:171","msg":"trace[1817057557] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"247.012632ms","start":"2026-04-24T01:52:34.876121Z","end":"2026-04-24T01:52:35.123134Z","steps":["trace[1817057557] 'process raft request' (duration: 246.958841ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:35.123219Z","caller":"traceutil/trace.go:171","msg":"trace[1618854266] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"247.078865ms","start":"2026-04-24T01:52:34.876118Z","end":"2026-04-24T01:52:35.123197Z","steps":["trace[1618854266] 'process raft request' (duration: 238.034912ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:35.127476Z","caller":"traceutil/trace.go:171","msg":"trace[1075632363] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"250.605648ms","start":"2026-04-24T01:52:34.876657Z","end":"2026-04-24T01:52:35.127262Z","steps":["trace[1075632363] 'process raft request' (duration: 250.434484ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:35.57297Z","caller":"traceutil/trace.go:171","msg":"trace[659456273] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"209.901808ms","start":"2026-04-24T01:52:35.363037Z","end":"2026-04-24T01:52:35.572938Z","steps":["trace[659456273] 'process raft request' (duration: 209.726494ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:43.543595Z","caller":"traceutil/trace.go:171","msg":"trace[95680809] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"157.35285ms","start":"2026-04-24T01:52:43.386213Z","end":"2026-04-24T01:52:43.543566Z","steps":["trace[95680809] 'process raft request' (duration: 145.302356ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:43.758964Z","caller":"traceutil/trace.go:171","msg":"trace[1126322232] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"133.150129ms","start":"2026-04-24T01:52:43.625785Z","end":"2026-04-24T01:52:43.758935Z","steps":["trace[1126322232] 'process raft request' (duration: 122.942778ms)","trace[1126322232] 'compare' (duration: 10.056407ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:52:44.324212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.317274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-24T01:52:44.324295Z","caller":"traceutil/trace.go:171","msg":"trace[485781696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"220.434256ms","start":"2026-04-24T01:52:44.103836Z","end":"2026-04-24T01:52:44.32427Z","steps":["trace[485781696] 'range keys from in-memory index tree' (duration: 220.233032ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:45.42719Z","caller":"traceutil/trace.go:171","msg":"trace[1745346302] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"255.663138ms","start":"2026-04-24T01:52:45.171497Z","end":"2026-04-24T01:52:45.42716Z","steps":["trace[1745346302] 'process raft request' (duration: 232.411189ms)","trace[1745346302] 'compare' (duration: 22.840558ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:52:45.42723Z","caller":"traceutil/trace.go:171","msg":"trace[552754529] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"243.723415ms","start":"2026-04-24T01:52:45.183469Z","end":"2026-04-24T01:52:45.427192Z","steps":["trace[552754529] 'process raft request' (duration: 243.553652ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:45.42746Z","caller":"traceutil/trace.go:171","msg":"trace[1989632654] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"238.270887ms","start":"2026-04-24T01:52:45.189181Z","end":"2026-04-24T01:52:45.427452Z","steps":["trace[1989632654] 'process raft request' (duration: 237.865767ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:45.427486Z","caller":"traceutil/trace.go:171","msg":"trace[464512110] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"246.252045ms","start":"2026-04-24T01:52:45.181228Z","end":"2026-04-24T01:52:45.42748Z","steps":["trace[464512110] 'process raft request' (duration: 245.703081ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:45.427462Z","caller":"traceutil/trace.go:171","msg":"trace[568729412] transaction","detail":"{read_only:false; number_of_response:1; response_revision:512; }","duration":"237.217612ms","start":"2026-04-24T01:52:45.190197Z","end":"2026-04-24T01:52:45.427415Z","steps":["trace[568729412] 'process raft request' (duration: 236.912065ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:45.427463Z","caller":"traceutil/trace.go:171","msg":"trace[56505368] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"252.247726ms","start":"2026-04-24T01:52:45.175207Z","end":"2026-04-24T01:52:45.427455Z","steps":["trace[56505368] 'process raft request' (duration: 251.687622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:45.427578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.288479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-24T01:52:45.427607Z","caller":"traceutil/trace.go:171","msg":"trace[2121175434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:518; }","duration":"230.32931ms","start":"2026-04-24T01:52:45.197271Z","end":"2026-04-24T01:52:45.4276Z","steps":["trace[2121175434] 'agreement among raft nodes before linearized reading' (duration: 230.253708ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:45.427638Z","caller":"traceutil/trace.go:171","msg":"trace[1637189094] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"237.496199ms","start":"2026-04-24T01:52:45.190134Z","end":"2026-04-24T01:52:45.427631Z","steps":["trace[1637189094] 'process raft request' (duration: 236.932856ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:45.427488Z","caller":"traceutil/trace.go:171","msg":"trace[1957096450] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:520; }","duration":"230.173216ms","start":"2026-04-24T01:52:45.197276Z","end":"2026-04-24T01:52:45.427449Z","steps":["trace[1957096450] 'read index received' (duration: 206.607361ms)","trace[1957096450] 'applied index is now lower than readState.Index' (duration: 23.565265ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:52:45.427681Z","caller":"traceutil/trace.go:171","msg":"trace[248922717] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"245.419886ms","start":"2026-04-24T01:52:45.182252Z","end":"2026-04-24T01:52:45.427672Z","steps":["trace[248922717] 'process raft request' (duration: 244.709088ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:45.427731Z","caller":"traceutil/trace.go:171","msg":"trace[459451646] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"237.507339ms","start":"2026-04-24T01:52:45.190216Z","end":"2026-04-24T01:52:45.427724Z","steps":["trace[459451646] 'process raft request' (duration: 236.918855ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:45.427741Z","caller":"traceutil/trace.go:171","msg":"trace[796056093] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"244.813682ms","start":"2026-04-24T01:52:45.182919Z","end":"2026-04-24T01:52:45.427733Z","steps":["trace[796056093] 'process raft request' (duration: 244.068454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:45.427774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.670516ms","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-04-24T01:52:45.427799Z","caller":"traceutil/trace.go:171","msg":"trace[850971449] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"236.640158ms","start":"2026-04-24T01:52:45.191153Z","end":"2026-04-24T01:52:45.427793Z","steps":["trace[850971449] 'process raft request' (duration: 236.013694ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:45.427803Z","caller":"traceutil/trace.go:171","msg":"trace[969604547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"193.742947ms","start":"2026-04-24T01:52:45.234052Z","end":"2026-04-24T01:52:45.427795Z","steps":["trace[969604547] 'agreement among raft nodes before linearized reading' (duration: 193.671195ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:45.427812Z","caller":"traceutil/trace.go:171","msg":"trace[621756269] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"198.105491ms","start":"2026-04-24T01:52:45.229701Z","end":"2026-04-24T01:52:45.427806Z","steps":["trace[621756269] 'process raft request' (duration: 197.6602ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:45.42786Z","caller":"traceutil/trace.go:171","msg":"trace[1456830571] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"228.177109ms","start":"2026-04-24T01:52:45.199678Z","end":"2026-04-24T01:52:45.427855Z","steps":["trace[1456830571] 'process raft request' (duration: 227.569285ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:45.427882Z","caller":"traceutil/trace.go:171","msg":"trace[904928331] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"219.988136ms","start":"2026-04-24T01:52:45.207888Z","end":"2026-04-24T01:52:45.427876Z","steps":["trace[904928331] 'process raft request' (duration: 219.428983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:45.427958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.782208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-24T01:52:45.427985Z","caller":"traceutil/trace.go:171","msg":"trace[1753912215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"154.84617ms","start":"2026-04-24T01:52:45.273131Z","end":"2026-04-24T01:52:45.427977Z","steps":["trace[1753912215] 'agreement among raft nodes before linearized reading' (duration: 154.776538ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:46.30701Z","caller":"traceutil/trace.go:171","msg":"trace[219661430] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"859.197914ms","start":"2026-04-24T01:52:45.447793Z","end":"2026-04-24T01:52:46.306991Z","steps":["trace[219661430] 'process raft request' (duration: 859.008649ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:46.307056Z","caller":"traceutil/trace.go:171","msg":"trace[1058905286] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"863.546277ms","start":"2026-04-24T01:52:45.443492Z","end":"2026-04-24T01:52:46.307038Z","steps":["trace[1058905286] 'process raft request' (duration: 863.152808ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:46.307123Z","caller":"traceutil/trace.go:171","msg":"trace[542146807] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"863.426004ms","start":"2026-04-24T01:52:45.443662Z","end":"2026-04-24T01:52:46.307088Z","steps":["trace[542146807] 'process raft request' (duration: 863.020335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:46.307144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:45.447739Z","time spent":"859.333757ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:52:46.307196Z","caller":"traceutil/trace.go:171","msg":"trace[2008324590] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"865.064582ms","start":"2026-04-24T01:52:45.442121Z","end":"2026-04-24T01:52:46.307186Z","steps":["trace[2008324590] 'process raft request' (duration: 864.483198ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:46.30718Z","caller":"traceutil/trace.go:171","msg":"trace[1652417483] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"865.557514ms","start":"2026-04-24T01:52:45.441592Z","end":"2026-04-24T01:52:46.30715Z","steps":["trace[1652417483] 'process raft request' (duration: 864.897807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:46.307248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:45.442111Z","time spent":"865.109153ms","remote":"127.0.0.1:57214","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-04-24T01:52:46.307247Z","caller":"traceutil/trace.go:171","msg":"trace[1302723541] transaction","detail":"{read_only:false; number_of_response:1; response_revision:519; }","duration":"865.743918ms","start":"2026-04-24T01:52:45.44146Z","end":"2026-04-24T01:52:46.307203Z","steps":["trace[1302723541] 'process raft request' (duration: 820.603434ms)","trace[1302723541] 'compare' (duration: 44.351364ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:52:46.307307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:45.441587Z","time spent":"865.660426ms","remote":"127.0.0.1:57452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T01:52:46.307315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:45.443651Z","time spent":"863.553147ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:52:46.30741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:45.441444Z","time spent":"865.869421ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:52:46.3075Z","caller":"traceutil/trace.go:171","msg":"trace[1086717264] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"865.797099ms","start":"2026-04-24T01:52:45.441689Z","end":"2026-04-24T01:52:46.307486Z","steps":["trace[1086717264] 'process raft request' (duration: 864.885127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:46.307155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:45.443477Z","time spent":"863.620008ms","remote":"127.0.0.1:57214","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-04-24T01:52:46.307548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:45.441672Z","time spent":"865.85308ms","remote":"127.0.0.1:57452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T01:52:46.307596Z","caller":"traceutil/trace.go:171","msg":"trace[1136216523] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"855.059156ms","start":"2026-04-24T01:52:45.452525Z","end":"2026-04-24T01:52:46.307584Z","steps":["trace[1136216523] 'process raft request' (duration: 854.38486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:46.307664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:45.452517Z","time spent":"855.111527ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:52:46.307829Z","caller":"traceutil/trace.go:171","msg":"trace[774057178] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"855.25635ms","start":"2026-04-24T01:52:45.452564Z","end":"2026-04-24T01:52:46.30782Z","steps":["trace[774057178] 'process raft request' (duration: 854.960843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:46.307911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:45.45256Z","time spent":"855.317632ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:52:46.308237Z","caller":"traceutil/trace.go:171","msg":"trace[976677182] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"843.271098ms","start":"2026-04-24T01:52:45.464949Z","end":"2026-04-24T01:52:46.30822Z","steps":["trace[976677182] 'process raft request' (duration: 842.814248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:46.30833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:45.464931Z","time spent":"843.35439ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:52:46.312224Z","caller":"traceutil/trace.go:171","msg":"trace[1540866334] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:536; }","duration":"166.795212ms","start":"2026-04-24T01:52:46.145404Z","end":"2026-04-24T01:52:46.312199Z","steps":["trace[1540866334] 'read index received' (duration: 116.803854ms)","trace[1540866334] 'applied index is now lower than readState.Index' (duration: 49.990278ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:52:46.312285Z","caller":"traceutil/trace.go:171","msg":"trace[353690761] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"815.379381ms","start":"2026-04-24T01:52:45.496881Z","end":"2026-04-24T01:52:46.31226Z","steps":["trace[353690761] 'process raft request' (duration: 815.060143ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:46.312376Z","caller":"traceutil/trace.go:171","msg":"trace[828036155] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"814.111162ms","start":"2026-04-24T01:52:45.498253Z","end":"2026-04-24T01:52:46.312364Z","steps":["trace[828036155] 'process raft request' (duration: 813.816954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:46.312538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:45.49823Z","time spent":"814.210644ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:52:46.312617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.215382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:52:46.312662Z","caller":"traceutil/trace.go:171","msg":"trace[642919294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"167.298104ms","start":"2026-04-24T01:52:46.145353Z","end":"2026-04-24T01:52:46.312651Z","steps":["trace[642919294] 'agreement among raft nodes before linearized reading' (duration: 167.234173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:46.312378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:45.496863Z","time spent":"815.478214ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T01:52:46.728717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.956304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:52:46.729115Z","caller":"traceutil/trace.go:171","msg":"trace[1425861360] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"404.504105ms","start":"2026-04-24T01:52:46.324583Z","end":"2026-04-24T01:52:46.729087Z","steps":["trace[1425861360] 'process raft request' (duration: 404.243489ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:46.729106Z","caller":"traceutil/trace.go:171","msg":"trace[750449889] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"409.135714ms","start":"2026-04-24T01:52:46.319872Z","end":"2026-04-24T01:52:46.729008Z","steps":["trace[750449889] 'process raft request' (duration: 90.619157ms)","trace[750449889] 'compare' (duration: 317.77786ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:52:46.729312Z","caller":"traceutil/trace.go:171","msg":"trace[916237991] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"400.033539ms","start":"2026-04-24T01:52:46.329199Z","end":"2026-04-24T01:52:46.729232Z","steps":["trace[916237991] 'process raft request' (duration: 399.735872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:46.72926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:46.324572Z","time spent":"404.623728ms","remote":"127.0.0.1:57452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T01:52:46.729574Z","caller":"traceutil/trace.go:171","msg":"trace[1047739020] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"353.719108ms","start":"2026-04-24T01:52:46.375843Z","end":"2026-04-24T01:52:46.729562Z","steps":["trace[1047739020] 'process raft request' (duration: 353.634026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:46.729569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:46.32919Z","time spent":"400.263705ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:52:46.729702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:46.375824Z","time spent":"353.782349ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T01:52:46.729706Z","caller":"traceutil/trace.go:171","msg":"trace[1084242702] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"374.035197ms","start":"2026-04-24T01:52:46.35565Z","end":"2026-04-24T01:52:46.729685Z","steps":["trace[1084242702] 'process raft request' (duration: 373.442712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:46.729796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:46.355622Z","time spent":"374.13148ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:52:46.729862Z","caller":"traceutil/trace.go:171","msg":"trace[1286749346] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:548; }","duration":"387.559026ms","start":"2026-04-24T01:52:46.342263Z","end":"2026-04-24T01:52:46.729822Z","steps":["trace[1286749346] 'read index received' (duration: 29.180118ms)","trace[1286749346] 'applied index is now lower than readState.Index' (duration: 358.377888ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:52:46.730005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.73618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-24T01:52:46.730042Z","caller":"traceutil/trace.go:171","msg":"trace[1396272284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:536; }","duration":"387.804702ms","start":"2026-04-24T01:52:46.342228Z","end":"2026-04-24T01:52:46.730032Z","steps":["trace[1396272284] 'agreement among raft nodes before linearized reading' (duration: 387.663989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:46.730069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:46.342215Z","time spent":"387.848273ms","remote":"127.0.0.1:57214","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-04-24T01:52:46.73009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:46.319864Z","time spent":"409.521513ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:52:47.510298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.488242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:52:47.510432Z","caller":"traceutil/trace.go:171","msg":"trace[53713628] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"614.406253ms","start":"2026-04-24T01:52:46.896009Z","end":"2026-04-24T01:52:47.510416Z","steps":["trace[53713628] 'process raft request' (duration: 126.718007ms)","trace[53713628] 'compare' (duration: 487.372849ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:52:47.510497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:46.89599Z","time spent":"614.476104ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:52:47.513261Z","caller":"traceutil/trace.go:171","msg":"trace[2048208754] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"614.871834ms","start":"2026-04-24T01:52:46.898372Z","end":"2026-04-24T01:52:47.513244Z","steps":["trace[2048208754] 'process raft request' (duration: 614.70772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:47.513364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:46.898345Z","time spent":"614.965616ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:52:47.513758Z","caller":"traceutil/trace.go:171","msg":"trace[1755553246] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"574.262456ms","start":"2026-04-24T01:52:46.939483Z","end":"2026-04-24T01:52:47.513745Z","steps":["trace[1755553246] 'process raft request' (duration: 573.693443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:47.513802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:46.939464Z","time spent":"574.318848ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T01:52:47.624877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.411397ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-24T01:52:47.624978Z","caller":"traceutil/trace.go:171","msg":"trace[664468824] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:562; }","duration":"389.582314ms","start":"2026-04-24T01:52:47.235381Z","end":"2026-04-24T01:52:47.624963Z","steps":["trace[664468824] 'read index received' (duration: 277.673325ms)","trace[664468824] 'applied index is now lower than readState.Index' (duration: 111.907769ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:52:47.625058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.666156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:52:47.625088Z","caller":"traceutil/trace.go:171","msg":"trace[259293570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"389.703506ms","start":"2026-04-24T01:52:47.235376Z","end":"2026-04-24T01:52:47.625079Z","steps":["trace[259293570] 'agreement among raft nodes before linearized reading' (duration: 389.643995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:47.625125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:47.235336Z","time spent":"389.783018ms","remote":"127.0.0.1:56978","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-04-24T01:52:47.628233Z","caller":"traceutil/trace.go:171","msg":"trace[1838537265] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"105.571438ms","start":"2026-04-24T01:52:47.522649Z","end":"2026-04-24T01:52:47.62822Z","steps":["trace[1838537265] 'process raft request' (duration: 105.411534ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:47.685237Z","caller":"traceutil/trace.go:171","msg":"trace[1447289104] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"147.369944ms","start":"2026-04-24T01:52:47.537824Z","end":"2026-04-24T01:52:47.685194Z","steps":["trace[1447289104] 'process raft request' (duration: 147.306933ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:47.685241Z","caller":"traceutil/trace.go:171","msg":"trace[732169787] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"151.695497ms","start":"2026-04-24T01:52:47.533506Z","end":"2026-04-24T01:52:47.685202Z","steps":["trace[732169787] 'process raft request' (duration: 151.466782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:47.68551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.91557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-24T01:52:47.685596Z","caller":"traceutil/trace.go:171","msg":"trace[895104722] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:549; }","duration":"205.031723ms","start":"2026-04-24T01:52:47.480544Z","end":"2026-04-24T01:52:47.685576Z","steps":["trace[895104722] 'agreement among raft nodes before linearized reading' (duration: 204.676565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:48.521435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.253666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:52:48.521509Z","caller":"traceutil/trace.go:171","msg":"trace[1126655106] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"800.768956ms","start":"2026-04-24T01:52:47.720729Z","end":"2026-04-24T01:52:48.521498Z","steps":["trace[1126655106] 'process raft request' (duration: 520.394497ms)","trace[1126655106] 'compare' (duration: 280.130563ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:52:48.521542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:47.720707Z","time spent":"800.820467ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:52:48.592546Z","caller":"traceutil/trace.go:171","msg":"trace[253826581] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"863.068135ms","start":"2026-04-24T01:52:47.729447Z","end":"2026-04-24T01:52:48.592515Z","steps":["trace[253826581] 'process raft request' (duration: 862.809499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:48.592729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:47.729426Z","time spent":"863.211049ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:52:48.593292Z","caller":"traceutil/trace.go:171","msg":"trace[1715405247] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:571; }","duration":"349.920799ms","start":"2026-04-24T01:52:48.243355Z","end":"2026-04-24T01:52:48.593276Z","steps":["trace[1715405247] 'read index received' (duration: 348.781391ms)","trace[1715405247] 'applied index is now lower than readState.Index' (duration: 1.138458ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:52:48.593374Z","caller":"traceutil/trace.go:171","msg":"trace[1207274691] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"652.550863ms","start":"2026-04-24T01:52:47.940808Z","end":"2026-04-24T01:52:48.593359Z","steps":["trace[1207274691] 'process raft request' (duration: 651.955009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:48.59343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.068172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T01:52:48.593459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:47.940782Z","time spent":"652.644344ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T01:52:48.593464Z","caller":"traceutil/trace.go:171","msg":"trace[723063707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"350.164315ms","start":"2026-04-24T01:52:48.243289Z","end":"2026-04-24T01:52:48.593453Z","steps":["trace[723063707] 'agreement among raft nodes before linearized reading' (duration: 350.075843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:48.593498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:48.243265Z","time spent":"350.226526ms","remote":"127.0.0.1:56978","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-04-24T01:52:48.593527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.328108ms","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-04-24T01:52:48.593577Z","caller":"traceutil/trace.go:171","msg":"trace[1364848127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:554; }","duration":"280.4162ms","start":"2026-04-24T01:52:48.313147Z","end":"2026-04-24T01:52:48.593563Z","steps":["trace[1364848127] 'agreement among raft nodes before linearized reading' (duration: 280.270347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:48.593591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.600579ms","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-04-24T01:52:48.593664Z","caller":"traceutil/trace.go:171","msg":"trace[612503250] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:554; }","duration":"281.734962ms","start":"2026-04-24T01:52:48.311909Z","end":"2026-04-24T01:52:48.593644Z","steps":["trace[612503250] 'agreement among raft nodes before linearized reading' (duration: 281.499007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:48.99254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.462827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:52:48.992736Z","caller":"traceutil/trace.go:171","msg":"trace[492415864] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"395.892442ms","start":"2026-04-24T01:52:48.596817Z","end":"2026-04-24T01:52:48.992709Z","steps":["trace[492415864] 'process raft request' (duration: 74.20694ms)","trace[492415864] 'compare' (duration: 321.356604ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:52:48.992783Z","caller":"traceutil/trace.go:171","msg":"trace[557707979] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"358.151312ms","start":"2026-04-24T01:52:48.634626Z","end":"2026-04-24T01:52:48.992777Z","steps":["trace[557707979] 'process raft request' (duration: 358.04816ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:48.992776Z","caller":"traceutil/trace.go:171","msg":"trace[909903120] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"395.107534ms","start":"2026-04-24T01:52:48.597646Z","end":"2026-04-24T01:52:48.992754Z","steps":["trace[909903120] 'process raft request' (duration: 394.96731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:48.992832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:48.634606Z","time spent":"358.206333ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:52:48.992838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:48.596792Z","time spent":"395.998915ms","remote":"127.0.0.1:57214","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-04-24T01:52:48.992874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:48.597628Z","time spent":"395.191945ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:52:48.992996Z","caller":"traceutil/trace.go:171","msg":"trace[577957150] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"357.566029ms","start":"2026-04-24T01:52:48.635399Z","end":"2026-04-24T01:52:48.992965Z","steps":["trace[577957150] 'process raft request' (duration: 357.332393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:48.993155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:48.635392Z","time spent":"357.670611ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:52:49.475778Z","caller":"traceutil/trace.go:171","msg":"trace[1033834656] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:573; }","duration":"729.237831ms","start":"2026-04-24T01:52:48.746516Z","end":"2026-04-24T01:52:49.475753Z","steps":["trace[1033834656] 'read index received' (duration: 112.78354ms)","trace[1033834656] 'applied index is now lower than readState.Index' (duration: 616.453171ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:52:49.475858Z","caller":"traceutil/trace.go:171","msg":"trace[1030615948] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"781.983634ms","start":"2026-04-24T01:52:48.693842Z","end":"2026-04-24T01:52:49.475825Z","steps":["trace[1030615948] 'process raft request' (duration: 735.403775ms)","trace[1030615948] 'compare' (duration: 46.366864ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:52:49.475999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:48.693807Z","time spent":"782.117926ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T01:52:49.47604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.492547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-24T01:52:49.476092Z","caller":"traceutil/trace.go:171","msg":"trace[1787993181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"729.60841ms","start":"2026-04-24T01:52:48.746471Z","end":"2026-04-24T01:52:49.476079Z","steps":["trace[1787993181] 'agreement among raft nodes before linearized reading' (duration: 729.368664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:49.47613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:48.746451Z","time spent":"729.672301ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-24T01:52:49.55685Z","caller":"traceutil/trace.go:171","msg":"trace[674367344] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"540.89104ms","start":"2026-04-24T01:52:49.01593Z","end":"2026-04-24T01:52:49.556821Z","steps":["trace[674367344] 'process raft request' (duration: 540.521341ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:49.556881Z","caller":"traceutil/trace.go:171","msg":"trace[772329168] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"506.582402ms","start":"2026-04-24T01:52:49.05027Z","end":"2026-04-24T01:52:49.556852Z","steps":["trace[772329168] 'process raft request' (duration: 506.391297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:49.556995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.505966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T01:52:49.557037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:49.015908Z","time spent":"541.035083ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:52:49.557072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:49.050256Z","time spent":"506.732935ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:52:49.557082Z","caller":"traceutil/trace.go:171","msg":"trace[618373781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"323.611409ms","start":"2026-04-24T01:52:49.233452Z","end":"2026-04-24T01:52:49.557063Z","steps":["trace[618373781] 'agreement among raft nodes before linearized reading' (duration: 323.384733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:49.557129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:49.233404Z","time spent":"323.715431ms","remote":"127.0.0.1:56964","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-04-24T01:52:49.656592Z","caller":"traceutil/trace.go:171","msg":"trace[472079825] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"176.577973ms","start":"2026-04-24T01:52:49.479996Z","end":"2026-04-24T01:52:49.656574Z","steps":["trace[472079825] 'process raft request' (duration: 117.008009ms)","trace[472079825] 'compare' (duration: 59.487172ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:52:49.754177Z","caller":"traceutil/trace.go:171","msg":"trace[1445657731] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"156.9829ms","start":"2026-04-24T01:52:49.597177Z","end":"2026-04-24T01:52:49.75416Z","steps":["trace[1445657731] 'process raft request' (duration: 156.835717ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:50.205005Z","caller":"traceutil/trace.go:171","msg":"trace[1507547108] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:582; }","duration":"502.147357ms","start":"2026-04-24T01:52:49.702835Z","end":"2026-04-24T01:52:50.204983Z","steps":["trace[1507547108] 'read index received' (duration: 51.34092ms)","trace[1507547108] 'applied index is now lower than readState.Index' (duration: 450.805387ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:52:50.205194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.345922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-24T01:52:50.205228Z","caller":"traceutil/trace.go:171","msg":"trace[1366082691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:565; }","duration":"502.431213ms","start":"2026-04-24T01:52:49.702784Z","end":"2026-04-24T01:52:50.205215Z","steps":["trace[1366082691] 'agreement among raft nodes before linearized reading' (duration: 502.28329ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:50.205218Z","caller":"traceutil/trace.go:171","msg":"trace[1711201385] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"547.476755ms","start":"2026-04-24T01:52:49.657708Z","end":"2026-04-24T01:52:50.205184Z","steps":["trace[1711201385] 'process raft request' (duration: 547.101837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:50.20526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:49.702766Z","time spent":"502.486685ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-24T01:52:50.205326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:49.657637Z","time spent":"547.631618ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:52:50.205371Z","caller":"traceutil/trace.go:171","msg":"trace[1631421522] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"555.33676ms","start":"2026-04-24T01:52:49.650017Z","end":"2026-04-24T01:52:50.205354Z","steps":["trace[1631421522] 'process raft request' (duration: 554.549882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:50.20544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:49.649996Z","time spent":"555.413082ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T01:52:50.626957Z","caller":"traceutil/trace.go:171","msg":"trace[59332670] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"863.504935ms","start":"2026-04-24T01:52:49.763421Z","end":"2026-04-24T01:52:50.626926Z","steps":["trace[59332670] 'process raft request' (duration: 847.988639ms)","trace[59332670] 'compare' (duration: 14.761788ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:52:50.62715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:49.763394Z","time spent":"863.636109ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18032,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:52:50.701218Z","caller":"traceutil/trace.go:171","msg":"trace[1849406986] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"490.837661ms","start":"2026-04-24T01:52:50.210351Z","end":"2026-04-24T01:52:50.701189Z","steps":["trace[1849406986] 'process raft request' (duration: 490.594055ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:50.701496Z","caller":"traceutil/trace.go:171","msg":"trace[1344368798] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"472.854705ms","start":"2026-04-24T01:52:50.228625Z","end":"2026-04-24T01:52:50.70148Z","steps":["trace[1344368798] 'process raft request' (duration: 472.475987ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:50.701515Z","caller":"traceutil/trace.go:171","msg":"trace[2138891629] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:584; }","duration":"468.092954ms","start":"2026-04-24T01:52:50.233352Z","end":"2026-04-24T01:52:50.701445Z","steps":["trace[2138891629] 'read index received' (duration: 378.136584ms)","trace[2138891629] 'applied index is now lower than readState.Index' (duration: 89.95466ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:52:50.701601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:50.228611Z","time spent":"472.925038ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:52:50.701731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.367661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:52:50.701794Z","caller":"traceutil/trace.go:171","msg":"trace[1173795220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"468.459442ms","start":"2026-04-24T01:52:50.233317Z","end":"2026-04-24T01:52:50.701777Z","steps":["trace[1173795220] 'agreement among raft nodes before linearized reading' (duration: 468.36099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:50.701932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:50.233305Z","time spent":"468.571705ms","remote":"127.0.0.1:56978","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-04-24T01:52:50.702173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:50.210325Z","time spent":"491.419783ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:52:51.585027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.175654ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:52:51.585177Z","caller":"traceutil/trace.go:171","msg":"trace[456142799] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:568; }","duration":"294.347688ms","start":"2026-04-24T01:52:51.290812Z","end":"2026-04-24T01:52:51.58516Z","steps":["trace[456142799] 'range keys from in-memory index tree' (duration: 294.157004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:51.585609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.886282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:52:51.585726Z","caller":"traceutil/trace.go:171","msg":"trace[295032144] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"884.100981ms","start":"2026-04-24T01:52:50.701608Z","end":"2026-04-24T01:52:51.585709Z","steps":["trace[295032144] 'read index received' (duration: 288.963303ms)","trace[295032144] 'applied index is now lower than readState.Index' (duration: 595.136088ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:52:51.586031Z","caller":"traceutil/trace.go:171","msg":"trace[1401948302] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"933.790691ms","start":"2026-04-24T01:52:50.65222Z","end":"2026-04-24T01:52:51.586011Z","steps":["trace[1401948302] 'process raft request' (duration: 338.355476ms)","trace[1401948302] 'compare' (duration: 594.572025ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:52:51.586165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:50.652189Z","time spent":"933.899484ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:52:51.586167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346666264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-04-24T01:52:51.586231Z","caller":"traceutil/trace.go:171","msg":"trace[1936382036] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:569; }","duration":"1.346806918s","start":"2026-04-24T01:52:50.23941Z","end":"2026-04-24T01:52:51.586217Z","steps":["trace[1936382036] 'agreement among raft nodes before linearized reading' (duration: 1.346524751s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:51.58633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:50.239388Z","time spent":"1.346907081s","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29593,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-24T01:52:51.625097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.037665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:52:51.625183Z","caller":"traceutil/trace.go:171","msg":"trace[1800346040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"922.181508ms","start":"2026-04-24T01:52:50.702979Z","end":"2026-04-24T01:52:51.62516Z","steps":["trace[1800346040] 'agreement among raft nodes before linearized reading' (duration: 922.007874ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:51.625184Z","caller":"traceutil/trace.go:171","msg":"trace[1508322465] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"914.537687ms","start":"2026-04-24T01:52:50.710632Z","end":"2026-04-24T01:52:51.625169Z","steps":["trace[1508322465] 'process raft request' (duration: 914.041796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:51.625225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:50.702964Z","time spent":"922.25248ms","remote":"127.0.0.1:56964","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-04-24T01:52:51.625245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.146742ms","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-04-24T01:52:51.625383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.866351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-24T01:52:51.625455Z","caller":"traceutil/trace.go:171","msg":"trace[1269817449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:571; }","duration":"627.986294ms","start":"2026-04-24T01:52:50.997452Z","end":"2026-04-24T01:52:51.625438Z","steps":["trace[1269817449] 'agreement among raft nodes before linearized reading' (duration: 627.773069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:51.625502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:50.997431Z","time spent":"628.062355ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-24T01:52:51.625483Z","caller":"traceutil/trace.go:171","msg":"trace[121003021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:571; }","duration":"629.430938ms","start":"2026-04-24T01:52:50.996038Z","end":"2026-04-24T01:52:51.625469Z","steps":["trace[121003021] 'agreement among raft nodes before linearized reading' (duration: 628.908266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:51.625318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:50.710607Z","time spent":"914.64141ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:52:51.624893Z","caller":"traceutil/trace.go:171","msg":"trace[1894283913] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"817.43331ms","start":"2026-04-24T01:52:50.807438Z","end":"2026-04-24T01:52:51.624871Z","steps":["trace[1894283913] 'process raft request' (duration: 817.351058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:51.625762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:50.807421Z","time spent":"818.294029ms","remote":"127.0.0.1:57214","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-04-24T01:52:51.625687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:52:50.996018Z","time spent":"629.570081ms","remote":"127.0.0.1:57214","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-04-24T01:52:51.742519Z","caller":"traceutil/trace.go:171","msg":"trace[320761905] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"113.117576ms","start":"2026-04-24T01:52:51.629375Z","end":"2026-04-24T01:52:51.742493Z","steps":["trace[320761905] 'process raft request' (duration: 112.593584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:51.742669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.310457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:52:51.742825Z","caller":"traceutil/trace.go:171","msg":"trace[1891727147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"109.48701ms","start":"2026-04-24T01:52:51.633321Z","end":"2026-04-24T01:52:51.742808Z","steps":["trace[1891727147] 'agreement among raft nodes before linearized reading' (duration: 109.305326ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:51.742744Z","caller":"traceutil/trace.go:171","msg":"trace[560853251] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"112.464051ms","start":"2026-04-24T01:52:51.630267Z","end":"2026-04-24T01:52:51.742731Z","steps":["trace[560853251] 'process raft request' (duration: 111.818466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:51.778404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.852864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:59631"} {"level":"info","ts":"2026-04-24T01:52:51.778529Z","caller":"traceutil/trace.go:171","msg":"trace[1180321733] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:576; }","duration":"103.023658ms","start":"2026-04-24T01:52:51.67549Z","end":"2026-04-24T01:52:51.778513Z","steps":["trace[1180321733] 'agreement among raft nodes before linearized reading' (duration: 102.782293ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:52.83363Z","caller":"traceutil/trace.go:171","msg":"trace[498800432] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"125.927838ms","start":"2026-04-24T01:52:52.707685Z","end":"2026-04-24T01:52:52.833613Z","steps":["trace[498800432] 'process raft request' (duration: 125.774935ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:53.91119Z","caller":"traceutil/trace.go:171","msg":"trace[34982506] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"122.787905ms","start":"2026-04-24T01:52:53.788379Z","end":"2026-04-24T01:52:53.911167Z","steps":["trace[34982506] 'read index received' (duration: 120.90159ms)","trace[34982506] 'applied index is now lower than readState.Index' (duration: 1.885295ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:52:53.911238Z","caller":"traceutil/trace.go:171","msg":"trace[2005403009] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"160.748579ms","start":"2026-04-24T01:52:53.750465Z","end":"2026-04-24T01:52:53.911214Z","steps":["trace[2005403009] 'process raft request' (duration: 158.975967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:53.911418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.02561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-24T01:52:53.911474Z","caller":"traceutil/trace.go:171","msg":"trace[1333065654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"123.130343ms","start":"2026-04-24T01:52:53.788328Z","end":"2026-04-24T01:52:53.911459Z","steps":["trace[1333065654] 'agreement among raft nodes before linearized reading' (duration: 122.940798ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:55.89648Z","caller":"traceutil/trace.go:171","msg":"trace[499173949] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"126.498192ms","start":"2026-04-24T01:52:55.769956Z","end":"2026-04-24T01:52:55.896454Z","steps":["trace[499173949] 'read index received' (duration: 126.334468ms)","trace[499173949] 'applied index is now lower than readState.Index' (duration: 162.514µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:52:55.896523Z","caller":"traceutil/trace.go:171","msg":"trace[894565869] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"127.503576ms","start":"2026-04-24T01:52:55.768991Z","end":"2026-04-24T01:52:55.896495Z","steps":["trace[894565869] 'process raft request' (duration: 127.322181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:55.896612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.628635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:52:55.896654Z","caller":"traceutil/trace.go:171","msg":"trace[1527702918] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:595; }","duration":"126.700626ms","start":"2026-04-24T01:52:55.76994Z","end":"2026-04-24T01:52:55.89664Z","steps":["trace[1527702918] 'agreement among raft nodes before linearized reading' (duration: 126.617864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:57.324226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.500351ms","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-04-24T01:52:57.324339Z","caller":"traceutil/trace.go:171","msg":"trace[78753972] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:607; }","duration":"204.637753ms","start":"2026-04-24T01:52:57.119659Z","end":"2026-04-24T01:52:57.324296Z","steps":["trace[78753972] 'range keys from in-memory index tree' (duration: 204.442279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:57.325495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.682139ms","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":"info","ts":"2026-04-24T01:52:57.325587Z","caller":"traceutil/trace.go:171","msg":"trace[1244572544] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:607; }","duration":"205.809231ms","start":"2026-04-24T01:52:57.119746Z","end":"2026-04-24T01:52:57.325555Z","steps":["trace[1244572544] 'range keys from in-memory index tree' (duration: 205.655668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:57.325735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.949215ms","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":"warn","ts":"2026-04-24T01:52:57.325922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.862067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:52:57.325976Z","caller":"traceutil/trace.go:171","msg":"trace[976411271] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:607; }","duration":"111.949549ms","start":"2026-04-24T01:52:57.214011Z","end":"2026-04-24T01:52:57.325961Z","steps":["trace[976411271] 'count revisions from in-memory index tree' (duration: 111.781135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:57.326174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.608195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:52:57.326212Z","caller":"traceutil/trace.go:171","msg":"trace[311414727] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:607; }","duration":"112.675145ms","start":"2026-04-24T01:52:57.213526Z","end":"2026-04-24T01:52:57.326202Z","steps":["trace[311414727] 'range keys from in-memory index tree' (duration: 112.545712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:57.32633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.362298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:52:57.326364Z","caller":"traceutil/trace.go:171","msg":"trace[38027881] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:607; }","duration":"112.41987ms","start":"2026-04-24T01:52:57.213935Z","end":"2026-04-24T01:52:57.326354Z","steps":["trace[38027881] 'range keys from in-memory index tree' (duration: 112.324188ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:57.325773Z","caller":"traceutil/trace.go:171","msg":"trace[1587590925] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:607; }","duration":"205.995756ms","start":"2026-04-24T01:52:57.119766Z","end":"2026-04-24T01:52:57.325762Z","steps":["trace[1587590925] 'count revisions from in-memory index tree' (duration: 205.918024ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:58.858348Z","caller":"traceutil/trace.go:171","msg":"trace[1547154075] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"215.232653ms","start":"2026-04-24T01:52:58.643075Z","end":"2026-04-24T01:52:58.858308Z","steps":["trace[1547154075] 'read index received' (duration: 214.880325ms)","trace[1547154075] 'applied index is now lower than readState.Index' (duration: 351.418µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:52:58.858399Z","caller":"traceutil/trace.go:171","msg":"trace[815294257] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"231.022665ms","start":"2026-04-24T01:52:58.62735Z","end":"2026-04-24T01:52:58.858372Z","steps":["trace[815294257] 'process raft request' (duration: 230.893702ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:52:58.858545Z","caller":"traceutil/trace.go:171","msg":"trace[456791789] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"233.317059ms","start":"2026-04-24T01:52:58.625204Z","end":"2026-04-24T01:52:58.858521Z","steps":["trace[456791789] 'process raft request' (duration: 232.832938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:58.858728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.032182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:52:58.859581Z","caller":"traceutil/trace.go:171","msg":"trace[1781411073] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:631; }","duration":"241.899772ms","start":"2026-04-24T01:52:58.617669Z","end":"2026-04-24T01:52:58.859569Z","steps":["trace[1781411073] 'agreement among raft nodes before linearized reading' (duration: 241.019371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:52:58.858718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.767195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:52:58.860531Z","caller":"traceutil/trace.go:171","msg":"trace[1370339189] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:631; }","duration":"242.6116ms","start":"2026-04-24T01:52:58.617909Z","end":"2026-04-24T01:52:58.860521Z","steps":["trace[1370339189] 'agreement among raft nodes before linearized reading' (duration: 240.614802ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:53:00.35581Z","caller":"traceutil/trace.go:171","msg":"trace[1227782449] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"121.008243ms","start":"2026-04-24T01:53:00.234777Z","end":"2026-04-24T01:53:00.355785Z","steps":["trace[1227782449] 'read index received' (duration: 95.224294ms)","trace[1227782449] 'applied index is now lower than readState.Index' (duration: 25.782899ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:53:00.355933Z","caller":"traceutil/trace.go:171","msg":"trace[1257196021] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"122.273933ms","start":"2026-04-24T01:53:00.233633Z","end":"2026-04-24T01:53:00.355907Z","steps":["trace[1257196021] 'process raft request' (duration: 96.349151ms)","trace[1257196021] 'compare' (duration: 25.695906ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:53:00.35596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.148557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:53:00.356022Z","caller":"traceutil/trace.go:171","msg":"trace[1459097284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"121.249429ms","start":"2026-04-24T01:53:00.234756Z","end":"2026-04-24T01:53:00.356006Z","steps":["trace[1459097284] 'agreement among raft nodes before linearized reading' (duration: 121.135986ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:53:00.580285Z","caller":"traceutil/trace.go:171","msg":"trace[845558139] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"160.684618ms","start":"2026-04-24T01:53:00.419531Z","end":"2026-04-24T01:53:00.580216Z","steps":["trace[845558139] 'process raft request' (duration: 160.530254ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:53:00.700953Z","caller":"traceutil/trace.go:171","msg":"trace[726614326] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"105.495808ms","start":"2026-04-24T01:53:00.595438Z","end":"2026-04-24T01:53:00.700934Z","steps":["trace[726614326] 'process raft request' (duration: 105.16556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:01.134546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.470366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:53:01.134649Z","caller":"traceutil/trace.go:171","msg":"trace[694113484] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"117.665253ms","start":"2026-04-24T01:53:01.016968Z","end":"2026-04-24T01:53:01.134634Z","steps":["trace[694113484] 'read index received' (duration: 69.011µs)","trace[694113484] 'applied index is now lower than readState.Index' (duration: 117.595022ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:53:01.13469Z","caller":"traceutil/trace.go:171","msg":"trace[455225044] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"217.498868ms","start":"2026-04-24T01:53:00.91715Z","end":"2026-04-24T01:53:01.134649Z","steps":["trace[455225044] 'process raft request' (duration: 55.843477ms)","trace[455225044] 'compare' (duration: 161.363584ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:53:01.134864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.893989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-04-24T01:53:01.135176Z","caller":"traceutil/trace.go:171","msg":"trace[1345622214] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:687; }","duration":"118.198346ms","start":"2026-04-24T01:53:01.016887Z","end":"2026-04-24T01:53:01.135085Z","steps":["trace[1345622214] 'agreement among raft nodes before linearized reading' (duration: 117.797077ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:53:01.647242Z","caller":"traceutil/trace.go:171","msg":"trace[1501750386] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"116.858914ms","start":"2026-04-24T01:53:01.530363Z","end":"2026-04-24T01:53:01.647222Z","steps":["trace[1501750386] 'process raft request' (duration: 116.773902ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:53:01.797263Z","caller":"traceutil/trace.go:171","msg":"trace[69330458] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"154.220145ms","start":"2026-04-24T01:53:01.643023Z","end":"2026-04-24T01:53:01.797243Z","steps":["trace[69330458] 'process raft request' (duration: 75.555961ms)","trace[69330458] 'compare' (duration: 78.515891ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:53:01.965391Z","caller":"traceutil/trace.go:171","msg":"trace[815369080] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"103.167652ms","start":"2026-04-24T01:53:01.862193Z","end":"2026-04-24T01:53:01.965361Z","steps":["trace[815369080] 'process raft request' (duration: 101.392141ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:53:02.407747Z","caller":"traceutil/trace.go:171","msg":"trace[2035269599] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"104.546044ms","start":"2026-04-24T01:53:02.30318Z","end":"2026-04-24T01:53:02.407726Z","steps":["trace[2035269599] 'process raft request' (duration: 103.286965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:12.650769Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439603673040017,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T01:53:13.151209Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439603673040017,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T01:53:13.246137Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.160463946s","expected-duration":"1s"} {"level":"info","ts":"2026-04-24T01:53:13.246476Z","caller":"traceutil/trace.go:171","msg":"trace[1096107691] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"1.160907606s","start":"2026-04-24T01:53:12.085547Z","end":"2026-04-24T01:53:13.246455Z","steps":["trace[1096107691] 'process raft request' (duration: 1.160743182s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:13.246675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:53:12.085519Z","time spent":"1.161033438s","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:53:13.25557Z","caller":"traceutil/trace.go:171","msg":"trace[754318926] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:799; }","duration":"1.105439798s","start":"2026-04-24T01:53:12.150119Z","end":"2026-04-24T01:53:13.255559Z","steps":["trace[754318926] 'read index received' (duration: 1.096251832s)","trace[754318926] 'applied index is now lower than readState.Index' (duration: 9.187296ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:53:13.255628Z","caller":"traceutil/trace.go:171","msg":"trace[1272652101] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"671.5608ms","start":"2026-04-24T01:53:12.58406Z","end":"2026-04-24T01:53:13.25562Z","steps":["trace[1272652101] 'process raft request' (duration: 671.441797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:13.255708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:53:12.584033Z","time spent":"671.616981ms","remote":"127.0.0.1:57184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:53:13.255923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105805667s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-24T01:53:13.25597Z","caller":"traceutil/trace.go:171","msg":"trace[36284732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:776; }","duration":"1.10590421s","start":"2026-04-24T01:53:12.150057Z","end":"2026-04-24T01:53:13.255962Z","steps":["trace[36284732] 'agreement among raft nodes before linearized reading' (duration: 1.105751815s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:13.255998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:53:12.150035Z","time spent":"1.105956451s","remote":"127.0.0.1:57214","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-04-24T01:53:13.256144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081309879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-24T01:53:13.256198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.905737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-24T01:53:13.256207Z","caller":"traceutil/trace.go:171","msg":"trace[2129361657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:776; }","duration":"1.081398581s","start":"2026-04-24T01:53:12.174794Z","end":"2026-04-24T01:53:13.256193Z","steps":["trace[2129361657] 'agreement among raft nodes before linearized reading' (duration: 1.081249677s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:13.256254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:53:12.174781Z","time spent":"1.081462923s","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-24T01:53:13.256353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02060221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:53:13.256379Z","caller":"traceutil/trace.go:171","msg":"trace[1245817924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"1.02062633s","start":"2026-04-24T01:53:12.235745Z","end":"2026-04-24T01:53:13.256372Z","steps":["trace[1245817924] 'agreement among raft nodes before linearized reading' (duration: 1.020594409s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:13.256395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:53:12.235724Z","time spent":"1.020667581s","remote":"127.0.0.1:56978","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-04-24T01:53:13.256218Z","caller":"traceutil/trace.go:171","msg":"trace[392132328] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:776; }","duration":"466.991218ms","start":"2026-04-24T01:53:12.789221Z","end":"2026-04-24T01:53:13.256212Z","steps":["trace[392132328] 'agreement among raft nodes before linearized reading' (duration: 466.935697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:13.256433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:53:12.789197Z","time spent":"467.224374ms","remote":"127.0.0.1:57214","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-04-24T01:53:13.256543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.066567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-99qnt\" ","response":"range_response_count:1 size:18713"} {"level":"info","ts":"2026-04-24T01:53:13.256565Z","caller":"traceutil/trace.go:171","msg":"trace[88344729] range","detail":"{range_begin:/registry/pods/kube-system/cilium-99qnt; range_end:; response_count:1; response_revision:776; }","duration":"648.126038ms","start":"2026-04-24T01:53:12.608434Z","end":"2026-04-24T01:53:13.25656Z","steps":["trace[88344729] 'agreement among raft nodes before linearized reading' (duration: 648.051377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:13.256581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:53:12.608406Z","time spent":"648.17214ms","remote":"127.0.0.1:57138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18736,"request content":"key:\"/registry/pods/kube-system/cilium-99qnt\" "} {"level":"info","ts":"2026-04-24T01:53:17.402602Z","caller":"traceutil/trace.go:171","msg":"trace[1509431485] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"119.484737ms","start":"2026-04-24T01:53:17.283098Z","end":"2026-04-24T01:53:17.402583Z","steps":["trace[1509431485] 'read index received' (duration: 119.389785ms)","trace[1509431485] 'applied index is now lower than readState.Index' (duration: 93.852µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:53:17.402716Z","caller":"traceutil/trace.go:171","msg":"trace[1379945349] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"120.588963ms","start":"2026-04-24T01:53:17.282118Z","end":"2026-04-24T01:53:17.402707Z","steps":["trace[1379945349] 'process raft request' (duration: 120.302696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:17.402907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.788354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-24T01:53:17.402946Z","caller":"traceutil/trace.go:171","msg":"trace[260340383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:828; }","duration":"119.855995ms","start":"2026-04-24T01:53:17.283081Z","end":"2026-04-24T01:53:17.402937Z","steps":["trace[260340383] 'agreement among raft nodes before linearized reading' (duration: 119.733172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:17.40307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.24306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-24T01:53:17.4031Z","caller":"traceutil/trace.go:171","msg":"trace[400722465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:828; }","duration":"113.302572ms","start":"2026-04-24T01:53:17.28979Z","end":"2026-04-24T01:53:17.403093Z","steps":["trace[400722465] 'agreement among raft nodes before linearized reading' (duration: 113.22674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:17.403219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.050515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-24T01:53:17.403249Z","caller":"traceutil/trace.go:171","msg":"trace[1782621417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:828; }","duration":"113.092085ms","start":"2026-04-24T01:53:17.29015Z","end":"2026-04-24T01:53:17.403242Z","steps":["trace[1782621417] 'agreement among raft nodes before linearized reading' (duration: 113.015004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:18.001369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.611264ms","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:5 size:1050272"} {"level":"info","ts":"2026-04-24T01:53:18.001623Z","caller":"traceutil/trace.go:171","msg":"trace[1490027165] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:831; }","duration":"535.934173ms","start":"2026-04-24T01:53:17.465655Z","end":"2026-04-24T01:53:18.001589Z","steps":["trace[1490027165] 'range keys from in-memory index tree' (duration: 533.974126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:18.002021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:53:17.465642Z","time spent":"536.2459ms","remote":"127.0.0.1:57054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":1050295,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-04-24T01:53:24.72855Z","caller":"traceutil/trace.go:171","msg":"trace[400806541] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"116.152568ms","start":"2026-04-24T01:53:24.612374Z","end":"2026-04-24T01:53:24.728526Z","steps":["trace[400806541] 'process raft request' (duration: 115.974824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:27.210806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.48548ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-24T01:53:27.224075Z","caller":"traceutil/trace.go:171","msg":"trace[1929468987] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"168.970863ms","start":"2026-04-24T01:53:27.055081Z","end":"2026-04-24T01:53:27.224051Z","steps":["trace[1929468987] 'process raft request' (duration: 168.762519ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:53:27.224444Z","caller":"traceutil/trace.go:171","msg":"trace[1480920814] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"168.047931ms","start":"2026-04-24T01:53:27.056375Z","end":"2026-04-24T01:53:27.224423Z","steps":["trace[1480920814] 'process raft request' (duration: 167.942279ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:53:27.224479Z","caller":"traceutil/trace.go:171","msg":"trace[285867790] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"168.569794ms","start":"2026-04-24T01:53:27.055886Z","end":"2026-04-24T01:53:27.224456Z","steps":["trace[285867790] 'process raft request' (duration: 168.115973ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:53:28.993526Z","caller":"traceutil/trace.go:171","msg":"trace[469794857] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"253.282491ms","start":"2026-04-24T01:53:28.74022Z","end":"2026-04-24T01:53:28.993503Z","steps":["trace[469794857] 'process raft request' (duration: 252.80762ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:53:29.037442Z","caller":"traceutil/trace.go:171","msg":"trace[239947247] transaction","detail":"{read_only:false; number_of_response:1; response_revision:933; }","duration":"288.356027ms","start":"2026-04-24T01:53:28.749064Z","end":"2026-04-24T01:53:29.03742Z","steps":["trace[239947247] 'process raft request' (duration: 288.206394ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:53:30.378947Z","caller":"traceutil/trace.go:171","msg":"trace[483647697] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"101.530453ms","start":"2026-04-24T01:53:30.277393Z","end":"2026-04-24T01:53:30.378924Z","steps":["trace[483647697] 'process raft request' (duration: 101.397169ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:53:45.494261Z","caller":"traceutil/trace.go:171","msg":"trace[1745176975] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"281.281938ms","start":"2026-04-24T01:53:45.212947Z","end":"2026-04-24T01:53:45.494229Z","steps":["trace[1745176975] 'read index received' (duration: 281.100663ms)","trace[1745176975] 'applied index is now lower than readState.Index' (duration: 180.035µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:53:45.494332Z","caller":"traceutil/trace.go:171","msg":"trace[2139961104] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"442.234102ms","start":"2026-04-24T01:53:45.052038Z","end":"2026-04-24T01:53:45.494272Z","steps":["trace[2139961104] 'process raft request' (duration: 441.999086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:45.494483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.511783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T01:53:45.494532Z","caller":"traceutil/trace.go:171","msg":"trace[816155583] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:986; }","duration":"281.590365ms","start":"2026-04-24T01:53:45.212927Z","end":"2026-04-24T01:53:45.494518Z","steps":["trace[816155583] 'agreement among raft nodes before linearized reading' (duration: 281.458942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:45.494654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:53:45.052009Z","time spent":"442.495028ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:53:45.553883Z","caller":"traceutil/trace.go:171","msg":"trace[1927104444] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"340.151509ms","start":"2026-04-24T01:53:45.213704Z","end":"2026-04-24T01:53:45.553855Z","steps":["trace[1927104444] 'process raft request' (duration: 338.164493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:45.553998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.290563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:53:45.554074Z","caller":"traceutil/trace.go:171","msg":"trace[2132487273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"317.412506ms","start":"2026-04-24T01:53:45.23664Z","end":"2026-04-24T01:53:45.554052Z","steps":["trace[2132487273] 'agreement among raft nodes before linearized reading' (duration: 317.208191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:53:45.554163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:53:45.213686Z","time spent":"340.350064ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:53:45.554225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:53:45.236618Z","time spent":"317.493178ms","remote":"127.0.0.1:56978","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-04-24T01:54:06.932714Z","caller":"traceutil/trace.go:171","msg":"trace[1024024335] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"240.177063ms","start":"2026-04-24T01:54:06.692511Z","end":"2026-04-24T01:54:06.932688Z","steps":["trace[1024024335] 'process raft request' (duration: 240.015579ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:55:00.227403Z","caller":"traceutil/trace.go:171","msg":"trace[1679789482] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"153.580137ms","start":"2026-04-24T01:55:00.073797Z","end":"2026-04-24T01:55:00.227377Z","steps":["trace[1679789482] 'process raft request' (duration: 153.419043ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:55:01.490264Z","caller":"traceutil/trace.go:171","msg":"trace[623473485] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"281.280113ms","start":"2026-04-24T01:55:01.208952Z","end":"2026-04-24T01:55:01.490232Z","steps":["trace[623473485] 'process raft request' (duration: 281.002426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:55:01.490532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.457831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:55:01.490463Z","caller":"traceutil/trace.go:171","msg":"trace[946107992] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1286; }","duration":"141.141364ms","start":"2026-04-24T01:55:01.349018Z","end":"2026-04-24T01:55:01.490159Z","steps":["trace[946107992] 'read index received' (duration: 140.880478ms)","trace[946107992] 'applied index is now lower than readState.Index' (duration: 259.766µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:55:01.49084Z","caller":"traceutil/trace.go:171","msg":"trace[926800391] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1239; }","duration":"141.866742ms","start":"2026-04-24T01:55:01.348953Z","end":"2026-04-24T01:55:01.49082Z","steps":["trace[926800391] 'agreement among raft nodes before linearized reading' (duration: 141.37588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:55:16.672661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.726213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:55:16.672777Z","caller":"traceutil/trace.go:171","msg":"trace[274512622] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"298.789689ms","start":"2026-04-24T01:55:16.373971Z","end":"2026-04-24T01:55:16.67276Z","steps":["trace[274512622] 'read index received' (duration: 123.77761ms)","trace[274512622] 'applied index is now lower than readState.Index' (duration: 175.010759ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:55:16.672872Z","caller":"traceutil/trace.go:171","msg":"trace[2127778034] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"301.931255ms","start":"2026-04-24T01:55:16.370928Z","end":"2026-04-24T01:55:16.672859Z","steps":["trace[2127778034] 'process raft request' (duration: 126.895556ms)","trace[2127778034] 'compare' (duration: 174.549198ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:55:16.672964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:55:16.370913Z","time spent":"302.017517ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:55:16.673251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.267161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-24T01:55:16.673298Z","caller":"traceutil/trace.go:171","msg":"trace[1317456923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1289; }","duration":"299.358074ms","start":"2026-04-24T01:55:16.373928Z","end":"2026-04-24T01:55:16.673286Z","steps":["trace[1317456923] 'agreement among raft nodes before linearized reading' (duration: 299.193859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:55:16.673361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.581133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-24T01:55:16.673462Z","caller":"traceutil/trace.go:171","msg":"trace[725203912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1289; }","duration":"209.733817ms","start":"2026-04-24T01:55:16.463701Z","end":"2026-04-24T01:55:16.673434Z","steps":["trace[725203912] 'agreement among raft nodes before linearized reading' (duration: 209.438889ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:55:16.844812Z","caller":"traceutil/trace.go:171","msg":"trace[1097312165] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"167.20522ms","start":"2026-04-24T01:55:16.677576Z","end":"2026-04-24T01:55:16.844781Z","steps":["trace[1097312165] 'process raft request' (duration: 114.436352ms)","trace[1097312165] 'compare' (duration: 52.635864ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:55:16.845915Z","caller":"traceutil/trace.go:171","msg":"trace[1207056316] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"168.197993ms","start":"2026-04-24T01:55:16.677684Z","end":"2026-04-24T01:55:16.845882Z","steps":["trace[1207056316] 'process raft request' (duration: 167.943677ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:55:54.378749Z","caller":"traceutil/trace.go:171","msg":"trace[1720200638] linearizableReadLoop","detail":"{readStateIndex:1471; appliedIndex:1470; }","duration":"142.401055ms","start":"2026-04-24T01:55:54.236318Z","end":"2026-04-24T01:55:54.378719Z","steps":["trace[1720200638] 'read index received' (duration: 142.18752ms)","trace[1720200638] 'applied index is now lower than readState.Index' (duration: 212.065µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:55:54.378942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.60123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:55:54.37893Z","caller":"traceutil/trace.go:171","msg":"trace[1582173514] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"243.98975ms","start":"2026-04-24T01:55:54.134879Z","end":"2026-04-24T01:55:54.378869Z","steps":["trace[1582173514] 'process raft request' (duration: 243.626271ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:55:54.379104Z","caller":"traceutil/trace.go:171","msg":"trace[257387741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1412; }","duration":"142.726093ms","start":"2026-04-24T01:55:54.236262Z","end":"2026-04-24T01:55:54.378988Z","steps":["trace[257387741] 'agreement among raft nodes before linearized reading' (duration: 142.5845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:56:09.376802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.294866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:56:09.376886Z","caller":"traceutil/trace.go:171","msg":"trace[579358368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1461; }","duration":"143.41322ms","start":"2026-04-24T01:56:09.233454Z","end":"2026-04-24T01:56:09.376867Z","steps":["trace[579358368] 'range keys from in-memory index tree' (duration: 143.235105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:56:09.37697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.5378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-24T01:56:09.377048Z","caller":"traceutil/trace.go:171","msg":"trace[1968246520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1461; }","duration":"185.650253ms","start":"2026-04-24T01:56:09.191379Z","end":"2026-04-24T01:56:09.377029Z","steps":["trace[1968246520] 'range keys from in-memory index tree' (duration: 185.413668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:56:09.37713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.77709ms","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-04-24T01:56:09.377187Z","caller":"traceutil/trace.go:171","msg":"trace[403633535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1461; }","duration":"186.872493ms","start":"2026-04-24T01:56:09.1903Z","end":"2026-04-24T01:56:09.377173Z","steps":["trace[403633535] 'range keys from in-memory index tree' (duration: 186.30849ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:56:21.177633Z","caller":"traceutil/trace.go:171","msg":"trace[906412017] linearizableReadLoop","detail":"{readStateIndex:1563; appliedIndex:1562; }","duration":"153.540069ms","start":"2026-04-24T01:56:21.024058Z","end":"2026-04-24T01:56:21.177598Z","steps":["trace[906412017] 'read index received' (duration: 153.349005ms)","trace[906412017] 'applied index is now lower than readState.Index' (duration: 190.174µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:56:21.177822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.749575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-24T01:56:21.177965Z","caller":"traceutil/trace.go:171","msg":"trace[1415412860] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"339.928502ms","start":"2026-04-24T01:56:20.837996Z","end":"2026-04-24T01:56:21.177924Z","steps":["trace[1415412860] 'process raft request' (duration: 339.45882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:56:21.178213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:56:20.837977Z","time spent":"340.089435ms","remote":"127.0.0.1:57214","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-04-24T01:56:21.177863Z","caller":"traceutil/trace.go:171","msg":"trace[16397873] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1499; }","duration":"153.840127ms","start":"2026-04-24T01:56:21.024011Z","end":"2026-04-24T01:56:21.177851Z","steps":["trace[16397873] 'agreement among raft nodes before linearized reading' (duration: 153.694703ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:56:37.933436Z","caller":"traceutil/trace.go:171","msg":"trace[2079693378] transaction","detail":"{read_only:false; response_revision:1555; number_of_response:1; }","duration":"171.650951ms","start":"2026-04-24T01:56:37.761726Z","end":"2026-04-24T01:56:37.933377Z","steps":["trace[2079693378] 'process raft request' (duration: 171.506698ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:56:39.438565Z","caller":"traceutil/trace.go:171","msg":"trace[1179814447] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"142.347454ms","start":"2026-04-24T01:56:39.296186Z","end":"2026-04-24T01:56:39.438534Z","steps":["trace[1179814447] 'process raft request' (duration: 142.044527ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:56:47.022359Z","caller":"traceutil/trace.go:171","msg":"trace[1374177150] transaction","detail":"{read_only:false; response_revision:1583; number_of_response:1; }","duration":"267.412936ms","start":"2026-04-24T01:56:46.754922Z","end":"2026-04-24T01:56:47.022335Z","steps":["trace[1374177150] 'process raft request' (duration: 267.262913ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:56:47.703661Z","caller":"traceutil/trace.go:171","msg":"trace[1503263356] linearizableReadLoop","detail":"{readStateIndex:1653; appliedIndex:1652; }","duration":"467.40175ms","start":"2026-04-24T01:56:47.236225Z","end":"2026-04-24T01:56:47.703627Z","steps":["trace[1503263356] 'read index received' (duration: 406.119835ms)","trace[1503263356] 'applied index is now lower than readState.Index' (duration: 61.280295ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:56:47.703796Z","caller":"traceutil/trace.go:171","msg":"trace[30165674] transaction","detail":"{read_only:false; response_revision:1584; number_of_response:1; }","duration":"483.379551ms","start":"2026-04-24T01:56:47.22039Z","end":"2026-04-24T01:56:47.703769Z","steps":["trace[30165674] 'process raft request' (duration: 421.945861ms)","trace[30165674] 'compare' (duration: 60.972039ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:56:47.703821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.585314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:56:47.703899Z","caller":"traceutil/trace.go:171","msg":"trace[1689056150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1584; }","duration":"467.701487ms","start":"2026-04-24T01:56:47.236182Z","end":"2026-04-24T01:56:47.703884Z","steps":["trace[1689056150] 'agreement among raft nodes before linearized reading' (duration: 467.582345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:56:47.703948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:56:47.236163Z","time spent":"467.77573ms","remote":"127.0.0.1:56978","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-04-24T01:56:47.703975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:56:47.220368Z","time spent":"483.477333ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:56:48.204263Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439603673042041,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T01:56:48.982408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.825283ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-24T01:56:48.982486Z","caller":"traceutil/trace.go:171","msg":"trace[773778812] linearizableReadLoop","detail":"{readStateIndex:1654; appliedIndex:1653; }","duration":"1.2787305s","start":"2026-04-24T01:56:47.703742Z","end":"2026-04-24T01:56:48.982472Z","steps":["trace[773778812] 'read index received' (duration: 513.789875ms)","trace[773778812] 'applied index is now lower than readState.Index' (duration: 764.939555ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:56:48.982744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.309794958s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:56:48.982767Z","caller":"traceutil/trace.go:171","msg":"trace[736525677] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1584; }","duration":"1.30989359s","start":"2026-04-24T01:56:47.672867Z","end":"2026-04-24T01:56:48.98276Z","steps":["trace[736525677] 'agreement among raft nodes before linearized reading' (duration: 1.309835049s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:56:48.98279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:56:47.672847Z","time spent":"1.309936721s","remote":"127.0.0.1:55374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-24T01:56:48.98295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278124185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:56:48.982968Z","caller":"traceutil/trace.go:171","msg":"trace[343249762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1584; }","duration":"1.278157585s","start":"2026-04-24T01:56:47.704804Z","end":"2026-04-24T01:56:48.982961Z","steps":["trace[343249762] 'agreement among raft nodes before linearized reading' (duration: 1.278129974s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:56:48.982983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:56:47.704792Z","time spent":"1.278187675s","remote":"127.0.0.1:56964","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-04-24T01:56:48.982995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.506387781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-24T01:56:48.983063Z","caller":"traceutil/trace.go:171","msg":"trace[1592421988] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1584; }","duration":"1.506502294s","start":"2026-04-24T01:56:47.476545Z","end":"2026-04-24T01:56:48.983047Z","steps":["trace[1592421988] 'agreement among raft nodes before linearized reading' (duration: 1.506294529s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:56:48.983101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:56:47.476524Z","time spent":"1.506569485s","remote":"127.0.0.1:57214","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-04-24T01:56:48.983121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.867486ms","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-04-24T01:56:48.983143Z","caller":"traceutil/trace.go:171","msg":"trace[959321721] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1584; }","duration":"375.918846ms","start":"2026-04-24T01:56:48.607217Z","end":"2026-04-24T01:56:48.983136Z","steps":["trace[959321721] 'agreement among raft nodes before linearized reading' (duration: 375.881925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:56:48.983161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:56:48.607201Z","time spent":"375.955648ms","remote":"127.0.0.1:57430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-24T01:56:48.983177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.964121ms","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":"warn","ts":"2026-04-24T01:56:48.983186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.329034344s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-24T01:56:48.983243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.296429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:56:48.983256Z","caller":"traceutil/trace.go:171","msg":"trace[140992086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1584; }","duration":"1.329137027s","start":"2026-04-24T01:56:47.654106Z","end":"2026-04-24T01:56:48.983243Z","steps":["trace[140992086] 'agreement among raft nodes before linearized reading' (duration: 1.328978243s)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:56:48.983269Z","caller":"traceutil/trace.go:171","msg":"trace[1524082067] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1584; }","duration":"687.34235ms","start":"2026-04-24T01:56:48.29592Z","end":"2026-04-24T01:56:48.983263Z","steps":["trace[1524082067] 'agreement among raft nodes before linearized reading' (duration: 687.305949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:56:48.983293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:56:48.295909Z","time spent":"687.378791ms","remote":"127.0.0.1:57252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-24T01:56:48.983321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.683093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-24T01:56:48.983323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.474085293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-24T01:56:48.983303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.933538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-24T01:56:48.983349Z","caller":"traceutil/trace.go:171","msg":"trace[1690533276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1584; }","duration":"972.750855ms","start":"2026-04-24T01:56:48.010591Z","end":"2026-04-24T01:56:48.983341Z","steps":["trace[1690533276] 'agreement among raft nodes before linearized reading' (duration: 972.657573ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:56:48.983358Z","caller":"traceutil/trace.go:171","msg":"trace[1773737358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1584; }","duration":"1.474156245s","start":"2026-04-24T01:56:47.509193Z","end":"2026-04-24T01:56:48.983349Z","steps":["trace[1773737358] 'agreement among raft nodes before linearized reading' (duration: 1.474068593s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:56:48.983292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:56:47.65409Z","time spent":"1.329194208s","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-24T01:56:48.983381Z","caller":"traceutil/trace.go:171","msg":"trace[1399568782] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1584; }","duration":"578.037861ms","start":"2026-04-24T01:56:48.405322Z","end":"2026-04-24T01:56:48.98336Z","steps":["trace[1399568782] 'agreement among raft nodes before linearized reading' (duration: 577.826936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:56:48.983395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:56:47.509172Z","time spent":"1.474217486s","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-24T01:56:48.983421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:56:48.405309Z","time spent":"578.104043ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-24T01:56:48.983367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:56:48.010568Z","time spent":"972.795355ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-24T01:56:48.983208Z","caller":"traceutil/trace.go:171","msg":"trace[1609429126] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1584; }","duration":"724.045452ms","start":"2026-04-24T01:56:48.259153Z","end":"2026-04-24T01:56:48.983198Z","steps":["trace[1609429126] 'agreement among raft nodes before linearized reading' (duration: 723.98584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:56:48.983555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:56:48.259132Z","time spent":"724.408681ms","remote":"127.0.0.1:55384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-24T01:57:12.560547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.333954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:57:12.560665Z","caller":"traceutil/trace.go:171","msg":"trace[982632377] linearizableReadLoop","detail":"{readStateIndex:1739; appliedIndex:1738; }","duration":"324.714552ms","start":"2026-04-24T01:57:12.235932Z","end":"2026-04-24T01:57:12.560646Z","steps":["trace[982632377] 'read index received' (duration: 33.38µs)","trace[982632377] 'applied index is now lower than readState.Index' (duration: 324.678852ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:57:12.560768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.834326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:57:12.560775Z","caller":"traceutil/trace.go:171","msg":"trace[714100157] transaction","detail":"{read_only:false; response_revision:1664; number_of_response:1; }","duration":"535.134131ms","start":"2026-04-24T01:57:12.025614Z","end":"2026-04-24T01:57:12.560748Z","steps":["trace[714100157] 'process raft request' (duration: 57.196181ms)","trace[714100157] 'compare' (duration: 477.14508ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:57:12.560809Z","caller":"traceutil/trace.go:171","msg":"trace[1574741366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1664; }","duration":"324.915107ms","start":"2026-04-24T01:57:12.235885Z","end":"2026-04-24T01:57:12.5608Z","steps":["trace[1574741366] 'agreement among raft nodes before linearized reading' (duration: 324.812605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:12.560868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:12.025591Z","time spent":"535.230784ms","remote":"127.0.0.1:57000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:57:12.560887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:12.235863Z","time spent":"325.012339ms","remote":"127.0.0.1:56978","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-04-24T01:57:13.193072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.451427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T01:57:13.193172Z","caller":"traceutil/trace.go:171","msg":"trace[261144039] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1664; }","duration":"258.631501ms","start":"2026-04-24T01:57:12.934512Z","end":"2026-04-24T01:57:13.193144Z","steps":["trace[261144039] 'count revisions from in-memory index tree' (duration: 258.344695ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:57:15.404525Z","caller":"traceutil/trace.go:171","msg":"trace[467563285] transaction","detail":"{read_only:false; response_revision:1671; number_of_response:1; }","duration":"183.206034ms","start":"2026-04-24T01:57:15.22129Z","end":"2026-04-24T01:57:15.404497Z","steps":["trace[467563285] 'process raft request' (duration: 182.992508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:15.404665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.20602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:57:15.404741Z","caller":"traceutil/trace.go:171","msg":"trace[1909299763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1671; }","duration":"169.338123ms","start":"2026-04-24T01:57:15.235384Z","end":"2026-04-24T01:57:15.404723Z","steps":["trace[1909299763] 'agreement among raft nodes before linearized reading' (duration: 169.20267ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:57:15.404476Z","caller":"traceutil/trace.go:171","msg":"trace[731124030] linearizableReadLoop","detail":"{readStateIndex:1746; appliedIndex:1745; }","duration":"169.025115ms","start":"2026-04-24T01:57:15.235419Z","end":"2026-04-24T01:57:15.404444Z","steps":["trace[731124030] 'read index received' (duration: 168.767219ms)","trace[731124030] 'applied index is now lower than readState.Index' (duration: 256.456µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:57:15.405381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.59538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-24T01:57:15.40544Z","caller":"traceutil/trace.go:171","msg":"trace[537635772] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1671; }","duration":"127.685191ms","start":"2026-04-24T01:57:15.27774Z","end":"2026-04-24T01:57:15.405425Z","steps":["trace[537635772] 'agreement among raft nodes before linearized reading' (duration: 127.517547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:15.40545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.940282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-24T01:57:15.4055Z","caller":"traceutil/trace.go:171","msg":"trace[2134447357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1671; }","duration":"127.028595ms","start":"2026-04-24T01:57:15.278458Z","end":"2026-04-24T01:57:15.405486Z","steps":["trace[2134447357] 'agreement among raft nodes before linearized reading' (duration: 126.891751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:15.405636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.776054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-24T01:57:15.405684Z","caller":"traceutil/trace.go:171","msg":"trace[1851768265] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1671; }","duration":"127.826005ms","start":"2026-04-24T01:57:15.277846Z","end":"2026-04-24T01:57:15.405672Z","steps":["trace[1851768265] 'agreement among raft nodes before linearized reading' (duration: 127.701512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:15.405675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.434124ms","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-04-24T01:57:15.405727Z","caller":"traceutil/trace.go:171","msg":"trace[840726162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1671; }","duration":"127.538407ms","start":"2026-04-24T01:57:15.278175Z","end":"2026-04-24T01:57:15.405714Z","steps":["trace[840726162] 'agreement among raft nodes before linearized reading' (duration: 127.339482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:15.406064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.91018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-24T01:57:15.406166Z","caller":"traceutil/trace.go:171","msg":"trace[246288085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1671; }","duration":"111.595726ms","start":"2026-04-24T01:57:15.294539Z","end":"2026-04-24T01:57:15.406135Z","steps":["trace[246288085] 'agreement among raft nodes before linearized reading' (duration: 110.866879ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:57:15.529753Z","caller":"traceutil/trace.go:171","msg":"trace[1599415812] transaction","detail":"{read_only:false; response_revision:1672; number_of_response:1; }","duration":"120.508335ms","start":"2026-04-24T01:57:15.409214Z","end":"2026-04-24T01:57:15.529722Z","steps":["trace[1599415812] 'process raft request' (duration: 34.23533ms)","trace[1599415812] 'compare' (duration: 86.112491ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:57:15.576397Z","caller":"traceutil/trace.go:171","msg":"trace[505822070] transaction","detail":"{read_only:false; response_revision:1673; number_of_response:1; }","duration":"167.118518ms","start":"2026-04-24T01:57:15.409252Z","end":"2026-04-24T01:57:15.57637Z","steps":["trace[505822070] 'process raft request' (duration: 166.914793ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:57:15.576707Z","caller":"traceutil/trace.go:171","msg":"trace[1071223218] transaction","detail":"{read_only:false; response_revision:1674; number_of_response:1; }","duration":"166.711747ms","start":"2026-04-24T01:57:15.40997Z","end":"2026-04-24T01:57:15.576682Z","steps":["trace[1071223218] 'process raft request' (duration: 166.359669ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:57:15.576773Z","caller":"traceutil/trace.go:171","msg":"trace[914492328] transaction","detail":"{read_only:false; response_revision:1676; number_of_response:1; }","duration":"164.630087ms","start":"2026-04-24T01:57:15.412123Z","end":"2026-04-24T01:57:15.576753Z","steps":["trace[914492328] 'process raft request' (duration: 164.592836ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:57:15.576889Z","caller":"traceutil/trace.go:171","msg":"trace[641858504] transaction","detail":"{read_only:false; response_revision:1675; number_of_response:1; }","duration":"166.376739ms","start":"2026-04-24T01:57:15.410451Z","end":"2026-04-24T01:57:15.576828Z","steps":["trace[641858504] 'process raft request' (duration: 166.169414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:38.40811Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439603673042541,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-24T01:57:38.640443Z","caller":"traceutil/trace.go:171","msg":"trace[1758995617] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"737.803711ms","start":"2026-04-24T01:57:37.902616Z","end":"2026-04-24T01:57:38.640419Z","steps":["trace[1758995617] 'process raft request' (duration: 737.653807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:38.640888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:37.902592Z","time spent":"738.199551ms","remote":"127.0.0.1:57214","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-04-24T01:57:38.681846Z","caller":"traceutil/trace.go:171","msg":"trace[1360208530] linearizableReadLoop","detail":"{readStateIndex:1878; appliedIndex:1876; }","duration":"774.587464ms","start":"2026-04-24T01:57:37.907116Z","end":"2026-04-24T01:57:38.681703Z","steps":["trace[1360208530] 'read index received' (duration: 733.135757ms)","trace[1360208530] 'applied index is now lower than readState.Index' (duration: 41.450897ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:57:38.682179Z","caller":"traceutil/trace.go:171","msg":"trace[1299728584] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"285.160353ms","start":"2026-04-24T01:57:38.397005Z","end":"2026-04-24T01:57:38.682165Z","steps":["trace[1299728584] 'process raft request' (duration: 284.567348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:38.682512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.374833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-24T01:57:38.682553Z","caller":"traceutil/trace.go:171","msg":"trace[363980503] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1796; }","duration":"775.454125ms","start":"2026-04-24T01:57:37.907088Z","end":"2026-04-24T01:57:38.682542Z","steps":["trace[363980503] 'agreement among raft nodes before linearized reading' (duration: 775.305911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:38.682585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:37.907072Z","time spent":"775.505357ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-24T01:57:38.682745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.806338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-24T01:57:38.682842Z","caller":"traceutil/trace.go:171","msg":"trace[1091261386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1796; }","duration":"774.951312ms","start":"2026-04-24T01:57:37.907871Z","end":"2026-04-24T01:57:38.682823Z","steps":["trace[1091261386] 'agreement among raft nodes before linearized reading' (duration: 774.692825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:38.682893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:37.907858Z","time spent":"775.023724ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-24T01:57:38.683038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.871918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:57:38.683099Z","caller":"traceutil/trace.go:171","msg":"trace[811488973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1796; }","duration":"446.952229ms","start":"2026-04-24T01:57:38.236129Z","end":"2026-04-24T01:57:38.683081Z","steps":["trace[811488973] 'agreement among raft nodes before linearized reading' (duration: 446.871687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:38.68313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:38.236113Z","time spent":"447.009201ms","remote":"127.0.0.1:56978","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-04-24T01:57:38.683297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.949222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-24T01:57:38.683342Z","caller":"traceutil/trace.go:171","msg":"trace[428147580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1796; }","duration":"775.007833ms","start":"2026-04-24T01:57:37.908322Z","end":"2026-04-24T01:57:38.68333Z","steps":["trace[428147580] 'agreement among raft nodes before linearized reading' (duration: 774.8855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:38.683392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:37.908317Z","time spent":"775.067634ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-24T01:57:38.683431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.111306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-24T01:57:38.683745Z","caller":"traceutil/trace.go:171","msg":"trace[1597411743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1796; }","duration":"775.444484ms","start":"2026-04-24T01:57:37.908278Z","end":"2026-04-24T01:57:38.683723Z","steps":["trace[1597411743] 'agreement among raft nodes before linearized reading' (duration: 775.051454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:38.683813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:37.908268Z","time spent":"775.527337ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-24T01:57:38.683591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.015264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-24T01:57:38.683934Z","caller":"traceutil/trace.go:171","msg":"trace[955777227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1796; }","duration":"775.372033ms","start":"2026-04-24T01:57:37.908551Z","end":"2026-04-24T01:57:38.683923Z","steps":["trace[955777227] 'agreement among raft nodes before linearized reading' (duration: 774.982034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:38.683971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:37.908544Z","time spent":"775.416464ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-24T01:57:48.602026Z","caller":"traceutil/trace.go:171","msg":"trace[8257133] linearizableReadLoop","detail":"{readStateIndex:1913; appliedIndex:1912; }","duration":"151.211387ms","start":"2026-04-24T01:57:48.450783Z","end":"2026-04-24T01:57:48.601994Z","steps":["trace[8257133] 'read index received' (duration: 108.274885ms)","trace[8257133] 'applied index is now lower than readState.Index' (duration: 42.933822ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:57:48.602145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.339761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T01:57:48.602195Z","caller":"traceutil/trace.go:171","msg":"trace[1161025621] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1829; }","duration":"151.437393ms","start":"2026-04-24T01:57:48.450746Z","end":"2026-04-24T01:57:48.602183Z","steps":["trace[1161025621] 'agreement among raft nodes before linearized reading' (duration: 151.3185ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:57:48.602024Z","caller":"traceutil/trace.go:171","msg":"trace[1746911208] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"161.129301ms","start":"2026-04-24T01:57:48.440833Z","end":"2026-04-24T01:57:48.601962Z","steps":["trace[1746911208] 'process raft request' (duration: 118.28686ms)","trace[1746911208] 'compare' (duration: 42.552254ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:57:48.881104Z","caller":"traceutil/trace.go:171","msg":"trace[824363584] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"210.895472ms","start":"2026-04-24T01:57:48.67017Z","end":"2026-04-24T01:57:48.881065Z","steps":["trace[824363584] 'process raft request' (duration: 178.314463ms)","trace[824363584] 'compare' (duration: 32.20717ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:57:48.881463Z","caller":"traceutil/trace.go:171","msg":"trace[1114907597] linearizableReadLoop","detail":"{readStateIndex:1916; appliedIndex:1914; }","duration":"146.468951ms","start":"2026-04-24T01:57:48.734981Z","end":"2026-04-24T01:57:48.88145Z","steps":["trace[1114907597] 'read index received' (duration: 113.512993ms)","trace[1114907597] 'applied index is now lower than readState.Index' (duration: 32.954968ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:57:48.881525Z","caller":"traceutil/trace.go:171","msg":"trace[973463993] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"185.574171ms","start":"2026-04-24T01:57:48.695923Z","end":"2026-04-24T01:57:48.881497Z","steps":["trace[973463993] 'process raft request' (duration: 185.093299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:48.881625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.271487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-24T01:57:48.881642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.641465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-24T01:57:48.881651Z","caller":"traceutil/trace.go:171","msg":"trace[1298600854] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1832; }","duration":"146.344769ms","start":"2026-04-24T01:57:48.735297Z","end":"2026-04-24T01:57:48.881642Z","steps":["trace[1298600854] 'agreement among raft nodes before linearized reading' (duration: 146.248277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:48.881662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.458056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-24T01:57:48.88168Z","caller":"traceutil/trace.go:171","msg":"trace[452823027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1832; }","duration":"146.717918ms","start":"2026-04-24T01:57:48.734947Z","end":"2026-04-24T01:57:48.881665Z","steps":["trace[452823027] 'agreement among raft nodes before linearized reading' (duration: 146.586024ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:57:48.881721Z","caller":"traceutil/trace.go:171","msg":"trace[612220888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1832; }","duration":"105.516908ms","start":"2026-04-24T01:57:48.776173Z","end":"2026-04-24T01:57:48.88169Z","steps":["trace[612220888] 'agreement among raft nodes before linearized reading' (duration: 105.403195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:48.881726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.771305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-24T01:57:48.88175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.978705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-24T01:57:48.881786Z","caller":"traceutil/trace.go:171","msg":"trace[701337133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1832; }","duration":"145.843276ms","start":"2026-04-24T01:57:48.735924Z","end":"2026-04-24T01:57:48.881768Z","steps":["trace[701337133] 'agreement among raft nodes before linearized reading' (duration: 145.684872ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:57:48.881829Z","caller":"traceutil/trace.go:171","msg":"trace[1566666462] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1832; }","duration":"105.074267ms","start":"2026-04-24T01:57:48.776736Z","end":"2026-04-24T01:57:48.88181Z","steps":["trace[1566666462] 'agreement among raft nodes before linearized reading' (duration: 104.861222ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:57:49.195585Z","caller":"traceutil/trace.go:171","msg":"trace[1870279686] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1917; }","duration":"129.167937ms","start":"2026-04-24T01:57:49.0664Z","end":"2026-04-24T01:57:49.195567Z","steps":["trace[1870279686] 'read index received' (duration: 128.942812ms)","trace[1870279686] 'applied index is now lower than readState.Index' (duration: 224.515µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:57:49.195603Z","caller":"traceutil/trace.go:171","msg":"trace[644088253] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"308.420593ms","start":"2026-04-24T01:57:48.88716Z","end":"2026-04-24T01:57:49.195581Z","steps":["trace[644088253] 'process raft request' (duration: 308.220468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:49.195685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.28418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:57:49.195673Z","caller":"traceutil/trace.go:171","msg":"trace[119948323] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"306.223528ms","start":"2026-04-24T01:57:48.889415Z","end":"2026-04-24T01:57:49.195639Z","steps":["trace[119948323] 'process raft request' (duration: 306.132186ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:57:49.195708Z","caller":"traceutil/trace.go:171","msg":"trace[2035907344] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:0; response_revision:1837; }","duration":"129.355852ms","start":"2026-04-24T01:57:49.066345Z","end":"2026-04-24T01:57:49.195701Z","steps":["trace[2035907344] 'agreement among raft nodes before linearized reading' (duration: 129.28872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:49.195726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:48.887138Z","time spent":"308.517015ms","remote":"127.0.0.1:57214","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-04-24T01:57:49.195822Z","caller":"traceutil/trace.go:171","msg":"trace[776805986] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"307.884309ms","start":"2026-04-24T01:57:48.887924Z","end":"2026-04-24T01:57:49.195808Z","steps":["trace[776805986] 'process raft request' (duration: 307.575922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:49.195835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:48.889403Z","time spent":"306.343412ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:57:49.195876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:48.887902Z","time spent":"307.949451ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:57:49.19615Z","caller":"traceutil/trace.go:171","msg":"trace[571466083] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"307.799027ms","start":"2026-04-24T01:57:48.888337Z","end":"2026-04-24T01:57:49.196136Z","steps":["trace[571466083] 'process raft request' (duration: 307.190413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:49.196204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:48.888326Z","time spent":"307.850219ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:57:49.345805Z","caller":"traceutil/trace.go:171","msg":"trace[1277450226] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"144.142055ms","start":"2026-04-24T01:57:49.201638Z","end":"2026-04-24T01:57:49.345781Z","steps":["trace[1277450226] 'process raft request' (duration: 141.436899ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:57:53.077498Z","caller":"traceutil/trace.go:171","msg":"trace[1178081551] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1933; }","duration":"155.340749ms","start":"2026-04-24T01:57:52.922135Z","end":"2026-04-24T01:57:53.077476Z","steps":["trace[1178081551] 'read index received' (duration: 155.183386ms)","trace[1178081551] 'applied index is now lower than readState.Index' (duration: 156.513µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:57:53.077742Z","caller":"traceutil/trace.go:171","msg":"trace[1632874334] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"164.74494ms","start":"2026-04-24T01:57:52.912976Z","end":"2026-04-24T01:57:53.077721Z","steps":["trace[1632874334] 'process raft request' (duration: 164.359181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:53.078104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.959785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-24T01:57:53.078163Z","caller":"traceutil/trace.go:171","msg":"trace[510009697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1849; }","duration":"156.064107ms","start":"2026-04-24T01:57:52.92208Z","end":"2026-04-24T01:57:53.078144Z","steps":["trace[510009697] 'agreement among raft nodes before linearized reading' (duration: 155.482923ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:57:53.304939Z","caller":"traceutil/trace.go:171","msg":"trace[1614387429] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"222.487996ms","start":"2026-04-24T01:57:53.082398Z","end":"2026-04-24T01:57:53.304886Z","steps":["trace[1614387429] 'process raft request' (duration: 222.332053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:55.737251Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439603673042698,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T01:57:56.237837Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439603673042698,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T01:57:56.334837Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.251911848s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-24T01:57:56.61564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.409376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:57:56.615733Z","caller":"traceutil/trace.go:171","msg":"trace[242798837] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1944; }","duration":"1.379558269s","start":"2026-04-24T01:57:55.23616Z","end":"2026-04-24T01:57:56.615718Z","steps":["trace[242798837] 'read index received' (duration: 1.098872767s)","trace[242798837] 'applied index is now lower than readState.Index' (duration: 280.684252ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:57:56.615743Z","caller":"traceutil/trace.go:171","msg":"trace[1851583886] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"1.532944319s","start":"2026-04-24T01:57:55.082776Z","end":"2026-04-24T01:57:56.61572Z","steps":["trace[1851583886] 'process raft request' (duration: 1.252360389s)","trace[1851583886] 'compare' (duration: 280.056398ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:57:56.615821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379665761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:57:56.616002Z","caller":"traceutil/trace.go:171","msg":"trace[1082582122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1860; }","duration":"1.379882727s","start":"2026-04-24T01:57:55.236107Z","end":"2026-04-24T01:57:56.61599Z","steps":["trace[1082582122] 'agreement among raft nodes before linearized reading' (duration: 1.379682722s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:56.616038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:55.236083Z","time spent":"1.379945309s","remote":"127.0.0.1:56964","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-04-24T01:57:56.616109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306585959s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-24T01:57:56.61623Z","caller":"traceutil/trace.go:171","msg":"trace[987799862] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1860; }","duration":"1.306780254s","start":"2026-04-24T01:57:55.309428Z","end":"2026-04-24T01:57:56.616208Z","steps":["trace[987799862] 'agreement among raft nodes before linearized reading' (duration: 1.306503287s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:56.615836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:55.082746Z","time spent":"1.533041792s","remote":"127.0.0.1:57214","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-04-24T01:57:56.616311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:55.309405Z","time spent":"1.306865317s","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-24T01:57:56.616472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228693149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-24T01:57:56.616518Z","caller":"traceutil/trace.go:171","msg":"trace[417124824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1860; }","duration":"1.228762561s","start":"2026-04-24T01:57:55.38774Z","end":"2026-04-24T01:57:56.616503Z","steps":["trace[417124824] 'agreement among raft nodes before linearized reading' (duration: 1.228621498s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:56.616551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:55.387727Z","time spent":"1.228815582s","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-24T01:57:56.616572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255784653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-24T01:57:56.616606Z","caller":"traceutil/trace.go:171","msg":"trace[1809993804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1860; }","duration":"1.255861554s","start":"2026-04-24T01:57:55.360734Z","end":"2026-04-24T01:57:56.616596Z","steps":["trace[1809993804] 'agreement among raft nodes before linearized reading' (duration: 1.255746132s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:56.616639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:55.360713Z","time spent":"1.255915056s","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-24T01:57:56.616615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229099839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-24T01:57:56.616694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.43072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:57:56.616695Z","caller":"traceutil/trace.go:171","msg":"trace[1559103747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1860; }","duration":"1.229252322s","start":"2026-04-24T01:57:55.387422Z","end":"2026-04-24T01:57:56.616674Z","steps":["trace[1559103747] 'agreement among raft nodes before linearized reading' (duration: 1.229005706s)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:57:56.616723Z","caller":"traceutil/trace.go:171","msg":"trace[159180049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1860; }","duration":"471.492761ms","start":"2026-04-24T01:57:56.145221Z","end":"2026-04-24T01:57:56.616714Z","steps":["trace[159180049] 'agreement among raft nodes before linearized reading' (duration: 471.4508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:56.616734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.082081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T01:57:56.616753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:56.145196Z","time spent":"471.551313ms","remote":"127.0.0.1:56978","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-04-24T01:57:56.616747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:55.387382Z","time spent":"1.229354245s","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-24T01:57:56.616749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229253443s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-24T01:57:56.616762Z","caller":"traceutil/trace.go:171","msg":"trace[34972443] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1860; }","duration":"783.166893ms","start":"2026-04-24T01:57:55.833586Z","end":"2026-04-24T01:57:56.616753Z","steps":["trace[34972443] 'agreement among raft nodes before linearized reading' (duration: 783.123212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:56.616791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:55.833562Z","time spent":"783.221105ms","remote":"127.0.0.1:55348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-24T01:57:56.61679Z","caller":"traceutil/trace.go:171","msg":"trace[1803048090] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1860; }","duration":"1.229325015s","start":"2026-04-24T01:57:55.387455Z","end":"2026-04-24T01:57:56.61678Z","steps":["trace[1803048090] 'agreement among raft nodes before linearized reading' (duration: 1.229215312s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:56.616822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:55.387435Z","time spent":"1.229380616s","remote":"127.0.0.1:57214","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-04-24T01:57:57.107742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.727697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:57:57.108289Z","caller":"traceutil/trace.go:171","msg":"trace[375135928] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1945; }","duration":"483.777872ms","start":"2026-04-24T01:57:56.624498Z","end":"2026-04-24T01:57:57.108276Z","steps":["trace[375135928] 'read index received' (duration: 199.387418ms)","trace[375135928] 'applied index is now lower than readState.Index' (duration: 284.389714ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:57:57.108316Z","caller":"traceutil/trace.go:171","msg":"trace[1347578867] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"485.988278ms","start":"2026-04-24T01:57:56.622299Z","end":"2026-04-24T01:57:57.108287Z","steps":["trace[1347578867] 'process raft request' (duration: 485.912386ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:57:57.108421Z","caller":"traceutil/trace.go:171","msg":"trace[1624187588] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"487.283119ms","start":"2026-04-24T01:57:56.621125Z","end":"2026-04-24T01:57:57.108409Z","steps":["trace[1624187588] 'process raft request' (duration: 202.748321ms)","trace[1624187588] 'compare' (duration: 283.212425ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:57:57.108438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:56.622277Z","time spent":"486.08646ms","remote":"127.0.0.1:57214","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-04-24T01:57:57.108429Z","caller":"traceutil/trace.go:171","msg":"trace[1108825808] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"486.201293ms","start":"2026-04-24T01:57:56.622207Z","end":"2026-04-24T01:57:57.108408Z","steps":["trace[1108825808] 'process raft request' (duration: 485.962897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:57.108476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:56.621103Z","time spent":"487.347191ms","remote":"127.0.0.1:57214","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-04-24T01:57:57.108568Z","caller":"traceutil/trace.go:171","msg":"trace[6005155] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"485.225499ms","start":"2026-04-24T01:57:56.623337Z","end":"2026-04-24T01:57:57.108562Z","steps":["trace[6005155] 'process raft request' (duration: 484.917442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:57.108604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:56.623331Z","time spent":"485.2556ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:57:57.108571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:56.622188Z","time spent":"486.314635ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:57:57.108681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.184023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:57:57.108754Z","caller":"traceutil/trace.go:171","msg":"trace[1796699893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1865; }","duration":"484.286056ms","start":"2026-04-24T01:57:56.624454Z","end":"2026-04-24T01:57:57.10874Z","steps":["trace[1796699893] 'agreement among raft nodes before linearized reading' (duration: 484.116762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:57.108822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:56.624414Z","time spent":"484.397717ms","remote":"127.0.0.1:56964","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-04-24T01:57:57.108862Z","caller":"traceutil/trace.go:171","msg":"trace[1812101331] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"486.236094ms","start":"2026-04-24T01:57:56.622503Z","end":"2026-04-24T01:57:57.108739Z","steps":["trace[1812101331] 'process raft request' (duration: 485.733552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:57.108925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:56.622489Z","time spent":"486.399177ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:57:57.342799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.519358ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-24T01:57:57.342933Z","caller":"traceutil/trace.go:171","msg":"trace[405861138] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1950; }","duration":"234.596642ms","start":"2026-04-24T01:57:57.108315Z","end":"2026-04-24T01:57:57.342911Z","steps":["trace[405861138] 'read index received' (duration: 46.89143ms)","trace[405861138] 'applied index is now lower than readState.Index' (duration: 187.703002ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:57:57.34315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.817338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/test-pvc.18a92853db74ce6c\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-04-24T01:57:57.343227Z","caller":"traceutil/trace.go:171","msg":"trace[190160984] range","detail":"{range_begin:/registry/events/default/test-pvc.18a92853db74ce6c; range_end:; response_count:1; response_revision:1865; }","duration":"473.939972ms","start":"2026-04-24T01:57:56.86927Z","end":"2026-04-24T01:57:57.34321Z","steps":["trace[190160984] 'agreement among raft nodes before linearized reading' (duration: 473.743467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:57.343268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:56.86925Z","time spent":"474.009403ms","remote":"127.0.0.1:57034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":919,"request content":"key:\"/registry/events/default/test-pvc.18a92853db74ce6c\" "} {"level":"warn","ts":"2026-04-24T01:57:57.343177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.509011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:57:57.343743Z","caller":"traceutil/trace.go:171","msg":"trace[352476663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1865; }","duration":"106.730847ms","start":"2026-04-24T01:57:57.236616Z","end":"2026-04-24T01:57:57.343347Z","steps":["trace[352476663] 'agreement among raft nodes before linearized reading' (duration: 106.496101ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:57:57.687995Z","caller":"traceutil/trace.go:171","msg":"trace[206938470] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"342.321644ms","start":"2026-04-24T01:57:57.345649Z","end":"2026-04-24T01:57:57.687971Z","steps":["trace[206938470] 'process raft request' (duration: 342.14792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:57:57.688147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:57:57.345625Z","time spent":"342.439807ms","remote":"127.0.0.1:57034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":881,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:57:58.814513Z","caller":"traceutil/trace.go:171","msg":"trace[560509299] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"191.332292ms","start":"2026-04-24T01:57:58.623152Z","end":"2026-04-24T01:57:58.814484Z","steps":["trace[560509299] 'process raft request' (duration: 191.154537ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:10.481921Z","caller":"traceutil/trace.go:171","msg":"trace[1149096752] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"110.210577ms","start":"2026-04-24T01:58:10.37169Z","end":"2026-04-24T01:58:10.4819Z","steps":["trace[1149096752] 'process raft request' (duration: 110.079063ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:13.450925Z","caller":"traceutil/trace.go:171","msg":"trace[1804686312] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"243.085361ms","start":"2026-04-24T01:58:13.207809Z","end":"2026-04-24T01:58:13.450894Z","steps":["trace[1804686312] 'process raft request' (duration: 242.833866ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:13.530399Z","caller":"traceutil/trace.go:171","msg":"trace[1555544140] linearizableReadLoop","detail":"{readStateIndex:2089; appliedIndex:2088; }","duration":"313.49699ms","start":"2026-04-24T01:58:13.216874Z","end":"2026-04-24T01:58:13.530371Z","steps":["trace[1555544140] 'read index received' (duration: 234.162419ms)","trace[1555544140] 'applied index is now lower than readState.Index' (duration: 79.333621ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:58:13.530553Z","caller":"traceutil/trace.go:171","msg":"trace[1482633129] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"320.226741ms","start":"2026-04-24T01:58:13.210314Z","end":"2026-04-24T01:58:13.530541Z","steps":["trace[1482633129] 'process raft request' (duration: 319.893054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:13.530694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:58:13.210293Z","time spent":"320.291443ms","remote":"127.0.0.1:57214","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-04-24T01:58:13.532016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.65423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-24T01:58:13.532143Z","caller":"traceutil/trace.go:171","msg":"trace[1708818480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2000; }","duration":"249.851344ms","start":"2026-04-24T01:58:13.282265Z","end":"2026-04-24T01:58:13.532117Z","steps":["trace[1708818480] 'agreement among raft nodes before linearized reading' (duration: 248.36848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:13.532257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.881877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-24T01:58:13.532344Z","caller":"traceutil/trace.go:171","msg":"trace[1564197755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2000; }","duration":"281.981209ms","start":"2026-04-24T01:58:13.250342Z","end":"2026-04-24T01:58:13.532324Z","steps":["trace[1564197755] 'agreement among raft nodes before linearized reading' (duration: 280.30392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:13.532518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.817483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:58:13.532579Z","caller":"traceutil/trace.go:171","msg":"trace[1198124809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2000; }","duration":"296.900215ms","start":"2026-04-24T01:58:13.235665Z","end":"2026-04-24T01:58:13.532565Z","steps":["trace[1198124809] 'agreement among raft nodes before linearized reading' (duration: 294.943818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:13.5326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.727145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-24T01:58:13.532636Z","caller":"traceutil/trace.go:171","msg":"trace[247572305] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2000; }","duration":"315.784745ms","start":"2026-04-24T01:58:13.216842Z","end":"2026-04-24T01:58:13.532626Z","steps":["trace[247572305] 'agreement among raft nodes before linearized reading' (duration: 313.814128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:13.532675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:58:13.216833Z","time spent":"315.832876ms","remote":"127.0.0.1:57214","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-04-24T01:58:14.608457Z","caller":"traceutil/trace.go:171","msg":"trace[519753336] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"108.774622ms","start":"2026-04-24T01:58:14.49966Z","end":"2026-04-24T01:58:14.608435Z","steps":["trace[519753336] 'process raft request' (duration: 108.622637ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:15.217713Z","caller":"traceutil/trace.go:171","msg":"trace[1519831047] linearizableReadLoop","detail":"{readStateIndex:2095; appliedIndex:2094; }","duration":"291.720962ms","start":"2026-04-24T01:58:14.92597Z","end":"2026-04-24T01:58:15.217691Z","steps":["trace[1519831047] 'read index received' (duration: 291.618369ms)","trace[1519831047] 'applied index is now lower than readState.Index' (duration: 101.593µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:58:15.217966Z","caller":"traceutil/trace.go:171","msg":"trace[737360031] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"307.969559ms","start":"2026-04-24T01:58:14.909988Z","end":"2026-04-24T01:58:15.217958Z","steps":["trace[737360031] 'process raft request' (duration: 307.522409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:15.21805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:58:14.909948Z","time spent":"308.043091ms","remote":"127.0.0.1:57214","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-04-24T01:58:15.218144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.161652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-24T01:58:15.218173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.825486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T01:58:15.218237Z","caller":"traceutil/trace.go:171","msg":"trace[308824777] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2006; }","duration":"293.475543ms","start":"2026-04-24T01:58:14.924748Z","end":"2026-04-24T01:58:15.218223Z","steps":["trace[308824777] 'agreement among raft nodes before linearized reading' (duration: 293.295499ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:15.218258Z","caller":"traceutil/trace.go:171","msg":"trace[96560861] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2006; }","duration":"216.933919ms","start":"2026-04-24T01:58:15.001307Z","end":"2026-04-24T01:58:15.21824Z","steps":["trace[96560861] 'agreement among raft nodes before linearized reading' (duration: 216.740364ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:19.43639Z","caller":"traceutil/trace.go:171","msg":"trace[1828001202] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"205.794665ms","start":"2026-04-24T01:58:19.230544Z","end":"2026-04-24T01:58:19.436339Z","steps":["trace[1828001202] 'process raft request' (duration: 205.562899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:21.498809Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439603673043068,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T01:58:21.999798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439603673043068,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T01:58:22.246724Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.61020603s","expected-duration":"1s"} {"level":"info","ts":"2026-04-24T01:58:22.247115Z","caller":"traceutil/trace.go:171","msg":"trace[1657995727] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"1.610660301s","start":"2026-04-24T01:58:20.636427Z","end":"2026-04-24T01:58:22.247087Z","steps":["trace[1657995727] 'process raft request' (duration: 1.610491797s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:22.247504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:58:20.63641Z","time spent":"1.610969999s","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:58:22.320553Z","caller":"traceutil/trace.go:171","msg":"trace[1245272600] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"1.274214924s","start":"2026-04-24T01:58:21.046319Z","end":"2026-04-24T01:58:22.320534Z","steps":["trace[1245272600] 'process raft request' (duration: 1.274051569s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:22.320703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:58:21.046278Z","time spent":"1.274335637s","remote":"127.0.0.1:57214","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-04-24T01:58:22.320735Z","caller":"traceutil/trace.go:171","msg":"trace[1769793872] linearizableReadLoop","detail":"{readStateIndex:2129; appliedIndex:2126; }","duration":"1.322641259s","start":"2026-04-24T01:58:20.998069Z","end":"2026-04-24T01:58:22.32071Z","steps":["trace[1769793872] 'read index received' (duration: 1.24885803s)","trace[1769793872] 'applied index is now lower than readState.Index' (duration: 73.781319ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:58:22.320935Z","caller":"traceutil/trace.go:171","msg":"trace[1909337598] transaction","detail":"{read_only:false; response_revision:2039; number_of_response:1; }","duration":"879.181931ms","start":"2026-04-24T01:58:21.441696Z","end":"2026-04-24T01:58:22.320878Z","steps":["trace[1909337598] 'process raft request' (duration: 878.77524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:22.321124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153122659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-24T01:58:22.32116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.464276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-24T01:58:22.32114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323059288s","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-04-24T01:58:22.321229Z","caller":"traceutil/trace.go:171","msg":"trace[1493230442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2039; }","duration":"641.579639ms","start":"2026-04-24T01:58:21.67963Z","end":"2026-04-24T01:58:22.32121Z","steps":["trace[1493230442] 'agreement among raft nodes before linearized reading' (duration: 641.366564ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:22.32125Z","caller":"traceutil/trace.go:171","msg":"trace[1131317664] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2039; }","duration":"1.323234033s","start":"2026-04-24T01:58:20.997992Z","end":"2026-04-24T01:58:22.321226Z","steps":["trace[1131317664] 'agreement among raft nodes before linearized reading' (duration: 1.322942136s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:22.321288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:58:21.679609Z","time spent":"641.66585ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-24T01:58:22.321323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.000641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-24T01:58:22.321313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:58:20.997946Z","time spent":"1.323352596s","remote":"127.0.0.1:57214","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":"info","ts":"2026-04-24T01:58:22.321347Z","caller":"traceutil/trace.go:171","msg":"trace[1887777064] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2039; }","duration":"640.024542ms","start":"2026-04-24T01:58:21.681316Z","end":"2026-04-24T01:58:22.32134Z","steps":["trace[1887777064] 'agreement among raft nodes before linearized reading' (duration: 639.96358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:22.321367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:58:21.681294Z","time spent":"640.067763ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-24T01:58:22.321431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.674301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:58:22.321457Z","caller":"traceutil/trace.go:171","msg":"trace[1384945626] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2039; }","duration":"991.723012ms","start":"2026-04-24T01:58:21.329728Z","end":"2026-04-24T01:58:22.321451Z","steps":["trace[1384945626] 'agreement among raft nodes before linearized reading' (duration: 991.686901ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:22.321208Z","caller":"traceutil/trace.go:171","msg":"trace[1288012837] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2039; }","duration":"1.153269492s","start":"2026-04-24T01:58:21.16792Z","end":"2026-04-24T01:58:22.321189Z","steps":["trace[1288012837] 'agreement among raft nodes before linearized reading' (duration: 1.153057597s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:22.321479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:58:21.329715Z","time spent":"991.758643ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-24T01:58:22.321475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.037011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-24T01:58:22.321509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:58:21.167895Z","time spent":"1.15359343s","remote":"127.0.0.1:57452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-24T01:58:22.321443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:58:21.441675Z","time spent":"879.474017ms","remote":"127.0.0.1:57214","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-04-24T01:58:22.321863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.143569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-24T01:58:22.321935Z","caller":"traceutil/trace.go:171","msg":"trace[687245201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2039; }","duration":"641.252901ms","start":"2026-04-24T01:58:21.680664Z","end":"2026-04-24T01:58:22.321917Z","steps":["trace[687245201] 'agreement among raft nodes before linearized reading' (duration: 641.077377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:22.321103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.010035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-24T01:58:22.322088Z","caller":"traceutil/trace.go:171","msg":"trace[354457145] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:2039; }","duration":"454.019899ms","start":"2026-04-24T01:58:21.868044Z","end":"2026-04-24T01:58:22.322064Z","steps":["trace[354457145] 'agreement among raft nodes before linearized reading' (duration: 452.971784ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:22.322238Z","caller":"traceutil/trace.go:171","msg":"trace[535296037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2039; }","duration":"710.148593ms","start":"2026-04-24T01:58:21.611384Z","end":"2026-04-24T01:58:22.321532Z","steps":["trace[535296037] 'agreement among raft nodes before linearized reading' (duration: 709.881317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:22.322343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:58:21.611359Z","time spent":"710.967782ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-24T01:58:22.322267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:58:21.868028Z","time spent":"454.218883ms","remote":"127.0.0.1:57000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.153\" "} {"level":"warn","ts":"2026-04-24T01:58:22.322513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064354763s","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:524"} {"level":"info","ts":"2026-04-24T01:58:22.322594Z","caller":"traceutil/trace.go:171","msg":"trace[167324163] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2039; }","duration":"1.064490587s","start":"2026-04-24T01:58:21.258083Z","end":"2026-04-24T01:58:22.322574Z","steps":["trace[167324163] 'agreement among raft nodes before linearized reading' (duration: 1.064135398s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:22.32264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:58:21.258072Z","time spent":"1.064554348s","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":547,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-24T01:58:22.321623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.900548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-24T01:58:22.322763Z","caller":"traceutil/trace.go:171","msg":"trace[202294193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2039; }","duration":"803.076406ms","start":"2026-04-24T01:58:21.519676Z","end":"2026-04-24T01:58:22.322753Z","steps":["trace[202294193] 'agreement among raft nodes before linearized reading' (duration: 801.902658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:22.322791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:58:21.519654Z","time spent":"803.129648ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-24T01:58:22.322001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:58:21.680645Z","time spent":"641.335513ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-24T01:58:22.462336Z","caller":"traceutil/trace.go:171","msg":"trace[1503130098] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"135.587031ms","start":"2026-04-24T01:58:22.326719Z","end":"2026-04-24T01:58:22.462306Z","steps":["trace[1503130098] 'process raft request' (duration: 135.267083ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:22.462654Z","caller":"traceutil/trace.go:171","msg":"trace[985490726] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"135.802735ms","start":"2026-04-24T01:58:22.326827Z","end":"2026-04-24T01:58:22.46263Z","steps":["trace[985490726] 'process raft request' (duration: 135.386995ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:22.462859Z","caller":"traceutil/trace.go:171","msg":"trace[1214509830] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"135.97884ms","start":"2026-04-24T01:58:22.326865Z","end":"2026-04-24T01:58:22.462844Z","steps":["trace[1214509830] 'process raft request' (duration: 135.682233ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:22.462987Z","caller":"traceutil/trace.go:171","msg":"trace[431791576] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"135.775435ms","start":"2026-04-24T01:58:22.3272Z","end":"2026-04-24T01:58:22.462975Z","steps":["trace[431791576] 'process raft request' (duration: 135.590691ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:22.463281Z","caller":"traceutil/trace.go:171","msg":"trace[1579277896] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"135.887187ms","start":"2026-04-24T01:58:22.327382Z","end":"2026-04-24T01:58:22.463269Z","steps":["trace[1579277896] 'process raft request' (duration: 135.550649ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:22.463322Z","caller":"traceutil/trace.go:171","msg":"trace[649472972] linearizableReadLoop","detail":"{readStateIndex:2133; appliedIndex:2129; }","duration":"136.237206ms","start":"2026-04-24T01:58:22.327062Z","end":"2026-04-24T01:58:22.463299Z","steps":["trace[649472972] 'read index received' (duration: 38.968519ms)","trace[649472972] 'applied index is now lower than readState.Index' (duration: 97.267767ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:58:22.463386Z","caller":"traceutil/trace.go:171","msg":"trace[1049758291] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"135.536421ms","start":"2026-04-24T01:58:22.32784Z","end":"2026-04-24T01:58:22.463376Z","steps":["trace[1049758291] 'process raft request' (duration: 135.332725ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:22.463465Z","caller":"traceutil/trace.go:171","msg":"trace[2045896109] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"135.399468ms","start":"2026-04-24T01:58:22.328057Z","end":"2026-04-24T01:58:22.463457Z","steps":["trace[2045896109] 'process raft request' (duration: 135.194742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:22.463486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.406361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:58:22.463537Z","caller":"traceutil/trace.go:171","msg":"trace[717991836] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2046; }","duration":"136.488603ms","start":"2026-04-24T01:58:22.327034Z","end":"2026-04-24T01:58:22.463522Z","steps":["trace[717991836] 'agreement among raft nodes before linearized reading' (duration: 136.3626ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:24.258534Z","caller":"traceutil/trace.go:171","msg":"trace[2022523488] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"153.829155ms","start":"2026-04-24T01:58:24.104676Z","end":"2026-04-24T01:58:24.258505Z","steps":["trace[2022523488] 'process raft request' (duration: 153.58788ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:26.81642Z","caller":"traceutil/trace.go:171","msg":"trace[524847293] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"222.918291ms","start":"2026-04-24T01:58:26.593471Z","end":"2026-04-24T01:58:26.816389Z","steps":["trace[524847293] 'process raft request' (duration: 222.378218ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:26.816442Z","caller":"traceutil/trace.go:171","msg":"trace[1182184783] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"223.089306ms","start":"2026-04-24T01:58:26.593336Z","end":"2026-04-24T01:58:26.816425Z","steps":["trace[1182184783] 'process raft request' (duration: 222.331478ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:26.816389Z","caller":"traceutil/trace.go:171","msg":"trace[1447731200] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"222.669365ms","start":"2026-04-24T01:58:26.593472Z","end":"2026-04-24T01:58:26.816141Z","steps":["trace[1447731200] 'process raft request' (duration: 222.333697ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:46.68647Z","caller":"traceutil/trace.go:171","msg":"trace[727483534] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2252; }","duration":"206.703015ms","start":"2026-04-24T01:58:46.479727Z","end":"2026-04-24T01:58:46.68643Z","steps":["trace[727483534] 'read index received' (duration: 206.459209ms)","trace[727483534] 'applied index is now lower than readState.Index' (duration: 242.706µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:58:46.686527Z","caller":"traceutil/trace.go:171","msg":"trace[42513209] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"209.083651ms","start":"2026-04-24T01:58:46.477413Z","end":"2026-04-24T01:58:46.686497Z","steps":["trace[42513209] 'process raft request' (duration: 208.821185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:46.686794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.044882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/ceph-csi-rbd-provisioner-http-metrics\" ","response":"range_response_count:1 size:941"} {"level":"info","ts":"2026-04-24T01:58:46.686877Z","caller":"traceutil/trace.go:171","msg":"trace[1405636549] range","detail":"{range_begin:/registry/services/endpoints/kube-system/ceph-csi-rbd-provisioner-http-metrics; range_end:; response_count:1; response_revision:2157; }","duration":"207.171647ms","start":"2026-04-24T01:58:46.479687Z","end":"2026-04-24T01:58:46.686859Z","steps":["trace[1405636549] 'agreement among raft nodes before linearized reading' (duration: 206.93077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:46.687337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.944691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-dns\" ","response":"range_response_count:1 size:755"} {"level":"info","ts":"2026-04-24T01:58:46.687366Z","caller":"traceutil/trace.go:171","msg":"trace[26821929] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-dns; range_end:; response_count:1; response_revision:2157; }","duration":"199.006602ms","start":"2026-04-24T01:58:46.488351Z","end":"2026-04-24T01:58:46.687357Z","steps":["trace[26821929] 'agreement among raft nodes before linearized reading' (duration: 198.90331ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:52.908239Z","caller":"traceutil/trace.go:171","msg":"trace[1850786771] linearizableReadLoop","detail":"{readStateIndex:2283; appliedIndex:2282; }","duration":"205.077077ms","start":"2026-04-24T01:58:52.703132Z","end":"2026-04-24T01:58:52.908209Z","steps":["trace[1850786771] 'read index received' (duration: 191.279068ms)","trace[1850786771] 'applied index is now lower than readState.Index' (duration: 13.796389ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:58:52.90841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.258941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:1 size:199"} {"level":"info","ts":"2026-04-24T01:58:52.908381Z","caller":"traceutil/trace.go:171","msg":"trace[1492565465] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"278.334212ms","start":"2026-04-24T01:58:52.630002Z","end":"2026-04-24T01:58:52.908336Z","steps":["trace[1492565465] 'process raft request' (duration: 264.150254ms)","trace[1492565465] 'compare' (duration: 13.743217ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:58:52.908444Z","caller":"traceutil/trace.go:171","msg":"trace[1029756830] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:1; response_revision:2185; }","duration":"205.359413ms","start":"2026-04-24T01:58:52.703076Z","end":"2026-04-24T01:58:52.908436Z","steps":["trace[1029756830] 'agreement among raft nodes before linearized reading' (duration: 205.249971ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:52.945399Z","caller":"traceutil/trace.go:171","msg":"trace[2113844433] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"231.318582ms","start":"2026-04-24T01:58:52.714049Z","end":"2026-04-24T01:58:52.945368Z","steps":["trace[2113844433] 'process raft request' (duration: 231.007284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:58:52.945558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.697132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-24T01:58:52.945596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.290892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:1 size:220"} {"level":"info","ts":"2026-04-24T01:58:52.945649Z","caller":"traceutil/trace.go:171","msg":"trace[104824542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2186; }","duration":"111.825415ms","start":"2026-04-24T01:58:52.833805Z","end":"2026-04-24T01:58:52.945631Z","steps":["trace[104824542] 'agreement among raft nodes before linearized reading' (duration: 111.60286ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:52.945701Z","caller":"traceutil/trace.go:171","msg":"trace[52990576] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:1; response_revision:2186; }","duration":"239.430285ms","start":"2026-04-24T01:58:52.706246Z","end":"2026-04-24T01:58:52.945676Z","steps":["trace[52990576] 'agreement among raft nodes before linearized reading' (duration: 239.239041ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:57.24082Z","caller":"traceutil/trace.go:171","msg":"trace[1864010131] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"103.471886ms","start":"2026-04-24T01:58:57.137317Z","end":"2026-04-24T01:58:57.240789Z","steps":["trace[1864010131] 'process raft request' (duration: 49.441388ms)","trace[1864010131] 'compare' (duration: 53.905785ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:58:57.248385Z","caller":"traceutil/trace.go:171","msg":"trace[116406830] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"107.733298ms","start":"2026-04-24T01:58:57.140638Z","end":"2026-04-24T01:58:57.248371Z","steps":["trace[116406830] 'process raft request' (duration: 107.634625ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:58:57.248598Z","caller":"traceutil/trace.go:171","msg":"trace[1826913593] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"107.886011ms","start":"2026-04-24T01:58:57.140692Z","end":"2026-04-24T01:58:57.248578Z","steps":["trace[1826913593] 'process raft request' (duration: 107.647486ms)"],"step_count":1}